builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1453910387.68 results: success (0) buildid: 20160127021629 builduid: f8ff6768eaa2432599209e4438a43d2a revision: fa05149b610d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:47.684017) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:47.684776) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:47.685147) ========= bash -c pwd in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006372 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:47.715847) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:47.716305) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:48.122172) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:48.122608) ========= rm -rf properties in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.024327 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:48.172381) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:48.172725) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:48.173129) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:48.176553) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-27 07:59:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.4M=0s 2016-01-27 07:59:48 (35.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110320 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:48.300561) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:48.300950) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.052061 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:48.365374) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:48.365804) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev fa05149b610d --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev fa05149b610d --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-27 07:59:48,629 Setting DEBUG logging. 2016-01-27 07:59:48,629 attempt 1/10 2016-01-27 07:59:48,629 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/fa05149b610d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-27 07:59:49,059 unpacking tar archive at: mozilla-beta-fa05149b610d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.693632 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:49.073198) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:49.073652) ========= echo fa05149b610d in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'fa05149b610d'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False fa05149b610d program finished with exit code 0 elapsedTime=0.003760 script_repo_revision: 'fa05149b610d' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:49.123949) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:49.124359) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-27 07:59:49.134696) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-01-27 07:59:49.135027) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:59:49 INFO - MultiFileLogger online at 20160127 07:59:49 in /builds/slave/test-pgo 07:59:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 07:59:49 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 07:59:49 INFO - {'append_to_log': False, 07:59:49 INFO - 'base_work_dir': '/builds/slave/test-pgo', 07:59:49 INFO - 'blob_upload_branch': 'Mozilla-Beta', 07:59:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 07:59:49 INFO - 'branch': 'Mozilla-Beta', 07:59:49 INFO - 'buildbot_json_path': 'buildprops.json', 07:59:49 INFO - 'config_files': ('talos/linux_config.py',), 07:59:49 INFO - 'default_actions': ('clobber', 07:59:49 INFO - 'read-buildbot-config', 07:59:49 INFO - 'download-and-extract', 07:59:49 INFO - 'populate-webroot', 07:59:49 INFO - 'create-virtualenv', 07:59:49 INFO - 'install', 07:59:49 INFO - 'run-tests'), 07:59:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:59:49 INFO - 'download_minidump_stackwalk': True, 07:59:49 INFO - 'download_symbols': 'ondemand', 07:59:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:59:49 INFO - 'tooltool.py': '/tools/tooltool.py', 07:59:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:59:49 INFO - '/tools/misc-python/virtualenv.py')}, 07:59:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:59:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:59:49 INFO - 'installer_path': 'installer.exe', 07:59:49 INFO - 'log_level': 'info', 07:59:49 INFO - 'log_name': 'talos', 07:59:49 INFO - 'log_to_console': True, 07:59:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:59:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:59:49 INFO - 'opt_config_files': (), 07:59:49 INFO - 'pip_index': False, 07:59:49 INFO - 'sps_profile': False, 07:59:49 INFO - 'sps_profile_interval': 0, 07:59:49 INFO - 'suite': 'dromaeojs', 07:59:49 INFO - 'system_bits': '32', 07:59:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:59:49 INFO - 'title': 'talos-linux64-ix-061', 07:59:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:59:49 INFO - 'use_talos_json': True, 07:59:49 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 07:59:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:59:49 INFO - 'work_dir': 'build'} 07:59:49 INFO - ##### 07:59:49 INFO - ##### Running clobber step. 07:59:49 INFO - ##### 07:59:49 INFO - Running pre-action listener: _resource_record_pre_action 07:59:49 INFO - Running main action method: clobber 07:59:49 INFO - rmtree: /builds/slave/test-pgo/build 07:59:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 07:59:50 INFO - Running post-action listener: _resource_record_post_action 07:59:50 INFO - ##### 07:59:50 INFO - ##### Running read-buildbot-config step. 07:59:50 INFO - ##### 07:59:50 INFO - Running pre-action listener: _resource_record_pre_action 07:59:50 INFO - Running main action method: read_buildbot_config 07:59:50 INFO - Using buildbot properties: 07:59:50 INFO - { 07:59:50 INFO - "properties": { 07:59:50 INFO - "buildnumber": 63, 07:59:50 INFO - "product": "firefox", 07:59:50 INFO - "script_repo_revision": "production", 07:59:50 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 07:59:50 INFO - "repository": "", 07:59:50 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 07:59:50 INFO - "buildid": "20160127021629", 07:59:50 INFO - "slavename": "talos-linux64-ix-061", 07:59:50 INFO - "pgo_build": "True", 07:59:50 INFO - "basedir": "/builds/slave/test-pgo", 07:59:50 INFO - "project": "", 07:59:50 INFO - "platform": "ubuntu64_hw", 07:59:50 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:59:50 INFO - "slavebuilddir": "test", 07:59:50 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 07:59:50 INFO - "branch": "mozilla-beta", 07:59:50 INFO - "repo_path": "releases/mozilla-beta", 07:59:50 INFO - "stage_platform": "linux64-pgo", 07:59:50 INFO - "builduid": "f8ff6768eaa2432599209e4438a43d2a", 07:59:50 INFO - "revision": "fa05149b610d" 07:59:50 INFO - }, 07:59:50 INFO - "sourcestamp": { 07:59:50 INFO - "repository": "", 07:59:50 INFO - "hasPatch": false, 07:59:50 INFO - "project": "", 07:59:50 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 07:59:50 INFO - "changes": [ 07:59:50 INFO - { 07:59:50 INFO - "category": null, 07:59:50 INFO - "files": [ 07:59:50 INFO - { 07:59:50 INFO - "url": null, 07:59:50 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.tar.bz2" 07:59:50 INFO - } 07:59:50 INFO - ], 07:59:50 INFO - "repository": "", 07:59:50 INFO - "rev": "fa05149b610d", 07:59:50 INFO - "who": "sendchange", 07:59:50 INFO - "when": 1453910378, 07:59:50 INFO - "number": 7524935, 07:59:50 INFO - "comments": "Backed out changeset 3bc504ea394f (bug 1241916) for breaking beta builds", 07:59:50 INFO - "project": "", 07:59:50 INFO - "at": "Wed 27 Jan 2016 07:59:38", 07:59:50 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 07:59:50 INFO - "revlink": "", 07:59:50 INFO - "properties": [ 07:59:50 INFO - [ 07:59:50 INFO - "buildid", 07:59:50 INFO - "20160127021629", 07:59:50 INFO - "Change" 07:59:50 INFO - ], 07:59:50 INFO - [ 07:59:50 INFO - "builduid", 07:59:50 INFO - "f8ff6768eaa2432599209e4438a43d2a", 07:59:50 INFO - "Change" 07:59:50 INFO - ], 07:59:50 INFO - [ 07:59:50 INFO - "pgo_build", 07:59:50 INFO - "True", 07:59:50 INFO - "Change" 07:59:50 INFO - ] 07:59:50 INFO - ], 07:59:50 INFO - "revision": "fa05149b610d" 07:59:50 INFO - } 07:59:50 INFO - ], 07:59:50 INFO - "revision": "fa05149b610d" 07:59:50 INFO - } 07:59:50 INFO - } 07:59:50 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.tar.bz2. 07:59:50 INFO - Running post-action listener: _resource_record_post_action 07:59:50 INFO - ##### 07:59:50 INFO - ##### Running download-and-extract step. 07:59:50 INFO - ##### 07:59:50 INFO - Running pre-action listener: _resource_record_pre_action 07:59:50 INFO - Running main action method: download_and_extract 07:59:50 INFO - mkdir: /builds/slave/test-pgo/build/tests 07:59:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:59:50 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/test_packages.json 07:59:50 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 07:59:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 07:59:50 INFO - Downloaded 1270 bytes. 07:59:50 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 07:59:50 INFO - Using the following test package requirements: 07:59:50 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 07:59:50 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 07:59:50 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 07:59:50 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 07:59:50 INFO - u'jsshell-linux-x86_64.zip'], 07:59:50 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 07:59:50 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 07:59:50 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 07:59:50 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 07:59:50 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 07:59:50 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 07:59:50 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 07:59:50 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 07:59:50 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 07:59:50 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 07:59:50 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 07:59:50 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 07:59:50 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:59:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:59:50 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.common.tests.zip 07:59:50 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 07:59:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:59:51 INFO - Downloaded 21579966 bytes. 07:59:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 07:59:51 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 07:59:51 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 07:59:52 INFO - Return code: 0 07:59:52 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:59:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:59:52 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.common.tests.zip 07:59:52 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 07:59:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:59:52 INFO - Downloaded 21579966 bytes. 07:59:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 07:59:52 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 07:59:52 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 07:59:53 INFO - Return code: 0 07:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:59:53 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 07:59:53 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 07:59:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:59:55 INFO - Downloaded 11132038 bytes. 07:59:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 07:59:55 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 07:59:55 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 07:59:56 INFO - Return code: 0 07:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:59:56 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.tar.bz2 07:59:56 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 07:59:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:59:56 INFO - Downloaded 57946360 bytes. 07:59:56 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.tar.bz2 07:59:56 INFO - mkdir: /builds/slave/test-pgo/properties 07:59:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 07:59:56 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 07:59:56 INFO - Contents: 07:59:56 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.tar.bz2 07:59:56 INFO - Running post-action listener: _resource_record_post_action 07:59:56 INFO - Running post-action listener: set_extra_try_arguments 07:59:56 INFO - ##### 07:59:56 INFO - ##### Running populate-webroot step. 07:59:56 INFO - ##### 07:59:56 INFO - Running pre-action listener: _resource_record_pre_action 07:59:56 INFO - Running main action method: populate_webroot 07:59:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:59:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:59:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:59:56 INFO - '--tppagecycles', 07:59:56 INFO - '7'], 07:59:56 INFO - 'tests': ['tsvgm']}}, 07:59:56 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 07:59:56 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 07:59:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:59:56 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:59:56 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 07:59:56 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:59:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:59:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:59:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:59:56 INFO - 'talos_options': ['--e10s'], 07:59:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:59:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:59:56 INFO - 'tests': ['damp', 'tps']}, 07:59:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:59:56 INFO - 'talos_options': ['--e10s'], 07:59:56 INFO - 'tests': ['damp', 'tps']}, 07:59:56 INFO - 'g3': {'tests': ['dromaeo_dom']}, 07:59:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:59:56 INFO - 'other': {'tests': ['a11yr', 07:59:56 INFO - 'ts_paint', 07:59:56 INFO - 'tpaint', 07:59:56 INFO - 'sessionrestore', 07:59:56 INFO - 'sessionrestore_no_auto_restore']}, 07:59:56 INFO - 'other-e10s': {'talos_options': ['--e10s'], 07:59:56 INFO - 'tests': ['a11yr', 07:59:56 INFO - 'ts_paint', 07:59:56 INFO - 'tpaint', 07:59:56 INFO - 'sessionrestore', 07:59:56 INFO - 'sessionrestore_no_auto_restore']}, 07:59:56 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 07:59:56 INFO - 'tests': ['a11yr', 07:59:56 INFO - 'ts_paint', 07:59:56 INFO - 'tpaint', 07:59:56 INFO - 'sessionrestore', 07:59:56 INFO - 'sessionrestore_no_auto_restore']}, 07:59:56 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 07:59:56 INFO - 'tests': ['a11yr', 07:59:56 INFO - 'ts_paint', 07:59:56 INFO - 'tpaint', 07:59:56 INFO - 'sessionrestore', 07:59:56 INFO - 'sessionrestore_no_auto_restore']}, 07:59:56 INFO - 'other_l64': {'tests': ['a11yr', 07:59:56 INFO - 'ts_paint', 07:59:56 INFO - 'tpaint', 07:59:56 INFO - 'sessionrestore', 07:59:56 INFO - 'sessionrestore_no_auto_restore']}, 07:59:56 INFO - 'other_nol64': {'tests': ['a11yr', 07:59:56 INFO - 'ts_paint', 07:59:56 INFO - 'tpaint', 07:59:56 INFO - 'sessionrestore', 07:59:56 INFO - 'sessionrestore_no_auto_restore']}, 07:59:56 INFO - 'svgr': {'tests': ['tsvgx', 07:59:56 INFO - 'tsvgr_opacity', 07:59:56 INFO - 'tart', 07:59:56 INFO - 'tscrollx', 07:59:56 INFO - 'cart']}, 07:59:56 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 07:59:56 INFO - 'tests': ['tsvgx', 07:59:56 INFO - 'tsvgr_opacity', 07:59:56 INFO - 'tart', 07:59:56 INFO - 'tscrollx', 07:59:56 INFO - 'cart']}, 07:59:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:59:56 INFO - 'tests': ['tp5o']}, 07:59:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:59:56 INFO - 'talos_options': ['--e10s'], 07:59:56 INFO - 'tests': ['tp5o']}, 07:59:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:59:56 INFO - 'talos_options': ['--xperf_path', 07:59:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:59:56 INFO - 'tests': ['tp5n']}, 07:59:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:59:56 INFO - 'talos_options': ['--e10s', 07:59:56 INFO - '--xperf_path', 07:59:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:59:56 INFO - 'tests': ['tp5n']}}, 07:59:56 INFO - 'talos.zip': {'path': '', 07:59:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:59:56 INFO - Running post-action listener: _resource_record_post_action 07:59:56 INFO - ##### 07:59:56 INFO - ##### Running create-virtualenv step. 07:59:56 INFO - ##### 07:59:56 INFO - Running pre-action listener: _resource_record_pre_action 07:59:56 INFO - Running main action method: create_virtualenv 07:59:56 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 07:59:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 07:59:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 07:59:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:59:56 INFO - Using real prefix '/usr' 07:59:56 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 07:59:57 INFO - Installing distribute.............................................................................................................................................................................................done. 07:59:59 INFO - Installing pip.................done. 07:59:59 INFO - Return code: 0 07:59:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 07:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:59:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:59:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4b6edacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d1600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2560350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25616e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:59:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 07:59:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:59:59 INFO - Using env: {'DISPLAY': ':0', 07:59:59 INFO - 'HOME': '/home/cltbld', 07:59:59 INFO - 'LANG': 'en_US.UTF-8', 07:59:59 INFO - 'LANGUAGE': 'en_US:en', 07:59:59 INFO - 'LOGNAME': 'cltbld', 07:59:59 INFO - 'MAIL': '/var/mail/cltbld', 07:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:59:59 INFO - 'MOZ_NO_REMOTE': '1', 07:59:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:59:59 INFO - 'NO_EM_RESTART': '1', 07:59:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:59:59 INFO - 'PWD': '/builds/slave/test-pgo', 07:59:59 INFO - 'SHELL': '/bin/bash', 07:59:59 INFO - 'SHLVL': '1', 07:59:59 INFO - 'TERM': 'linux', 07:59:59 INFO - 'TMOUT': '86400', 07:59:59 INFO - 'USER': 'cltbld', 07:59:59 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 07:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:59:59 INFO - '_': '/tools/buildbot/bin/python'} 07:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:59:59 INFO - Cleaning up... 07:59:59 INFO - Return code: 0 07:59:59 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 07:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:59:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:59:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:59:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4b6edacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d1600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2560350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25616e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:59:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 07:59:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 07:59:59 INFO - Using env: {'DISPLAY': ':0', 07:59:59 INFO - 'HOME': '/home/cltbld', 07:59:59 INFO - 'LANG': 'en_US.UTF-8', 07:59:59 INFO - 'LANGUAGE': 'en_US:en', 07:59:59 INFO - 'LOGNAME': 'cltbld', 07:59:59 INFO - 'MAIL': '/var/mail/cltbld', 07:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:59:59 INFO - 'MOZ_NO_REMOTE': '1', 07:59:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:59:59 INFO - 'NO_EM_RESTART': '1', 07:59:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:59:59 INFO - 'PWD': '/builds/slave/test-pgo', 07:59:59 INFO - 'SHELL': '/bin/bash', 07:59:59 INFO - 'SHLVL': '1', 07:59:59 INFO - 'TERM': 'linux', 07:59:59 INFO - 'TMOUT': '86400', 07:59:59 INFO - 'USER': 'cltbld', 07:59:59 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 07:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:59:59 INFO - '_': '/tools/buildbot/bin/python'} 07:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:59:59 INFO - Downloading/unpacking psutil>=0.7.1 07:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:59:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:59:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:59:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:59:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 08:00:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:00:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:00:01 INFO - Installing collected packages: psutil 08:00:01 INFO - Running setup.py install for psutil 08:00:01 INFO - building 'psutil._psutil_linux' extension 08:00:01 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:00:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:00:02 INFO - building 'psutil._psutil_posix' extension 08:00:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:00:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:00:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:00:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:00:02 INFO - Successfully installed psutil 08:00:02 INFO - Cleaning up... 08:00:02 INFO - Return code: 0 08:00:02 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 08:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4b6edacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d1600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2560350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25616e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:00:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 08:00:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 08:00:02 INFO - Using env: {'DISPLAY': ':0', 08:00:02 INFO - 'HOME': '/home/cltbld', 08:00:02 INFO - 'LANG': 'en_US.UTF-8', 08:00:02 INFO - 'LANGUAGE': 'en_US:en', 08:00:02 INFO - 'LOGNAME': 'cltbld', 08:00:02 INFO - 'MAIL': '/var/mail/cltbld', 08:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:02 INFO - 'MOZ_NO_REMOTE': '1', 08:00:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:02 INFO - 'NO_EM_RESTART': '1', 08:00:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:00:02 INFO - 'PWD': '/builds/slave/test-pgo', 08:00:02 INFO - 'SHELL': '/bin/bash', 08:00:02 INFO - 'SHLVL': '1', 08:00:02 INFO - 'TERM': 'linux', 08:00:02 INFO - 'TMOUT': '86400', 08:00:02 INFO - 'USER': 'cltbld', 08:00:02 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 08:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:02 INFO - '_': '/tools/buildbot/bin/python'} 08:00:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:02 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:00:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:00:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:00:04 INFO - Installing collected packages: mozsystemmonitor 08:00:04 INFO - Running setup.py install for mozsystemmonitor 08:00:04 INFO - Successfully installed mozsystemmonitor 08:00:04 INFO - Cleaning up... 08:00:04 INFO - Return code: 0 08:00:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 08:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:04 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4b6edacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d1600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2560350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25616e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:00:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 08:00:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:00:04 INFO - Using env: {'DISPLAY': ':0', 08:00:04 INFO - 'HOME': '/home/cltbld', 08:00:04 INFO - 'LANG': 'en_US.UTF-8', 08:00:04 INFO - 'LANGUAGE': 'en_US:en', 08:00:04 INFO - 'LOGNAME': 'cltbld', 08:00:04 INFO - 'MAIL': '/var/mail/cltbld', 08:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:04 INFO - 'MOZ_NO_REMOTE': '1', 08:00:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:04 INFO - 'NO_EM_RESTART': '1', 08:00:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:00:04 INFO - 'PWD': '/builds/slave/test-pgo', 08:00:04 INFO - 'SHELL': '/bin/bash', 08:00:04 INFO - 'SHLVL': '1', 08:00:04 INFO - 'TERM': 'linux', 08:00:04 INFO - 'TMOUT': '86400', 08:00:04 INFO - 'USER': 'cltbld', 08:00:04 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 08:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:04 INFO - '_': '/tools/buildbot/bin/python'} 08:00:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:04 INFO - Downloading/unpacking blobuploader==1.2.4 08:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:06 INFO - Downloading blobuploader-1.2.4.tar.gz 08:00:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:00:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 08:00:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:07 INFO - Downloading docopt-0.6.1.tar.gz 08:00:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 08:00:07 INFO - Installing collected packages: blobuploader, requests, docopt 08:00:07 INFO - Running setup.py install for blobuploader 08:00:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:00:07 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 08:00:07 INFO - Running setup.py install for requests 08:00:07 INFO - Running setup.py install for docopt 08:00:07 INFO - Successfully installed blobuploader requests docopt 08:00:07 INFO - Cleaning up... 08:00:07 INFO - Return code: 0 08:00:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4b6edacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d1600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2560350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25616e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:00:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 08:00:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:00:07 INFO - Using env: {'DISPLAY': ':0', 08:00:07 INFO - 'HOME': '/home/cltbld', 08:00:07 INFO - 'LANG': 'en_US.UTF-8', 08:00:07 INFO - 'LANGUAGE': 'en_US:en', 08:00:07 INFO - 'LOGNAME': 'cltbld', 08:00:07 INFO - 'MAIL': '/var/mail/cltbld', 08:00:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:07 INFO - 'MOZ_NO_REMOTE': '1', 08:00:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:07 INFO - 'NO_EM_RESTART': '1', 08:00:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:00:07 INFO - 'PWD': '/builds/slave/test-pgo', 08:00:07 INFO - 'SHELL': '/bin/bash', 08:00:07 INFO - 'SHLVL': '1', 08:00:07 INFO - 'TERM': 'linux', 08:00:07 INFO - 'TMOUT': '86400', 08:00:07 INFO - 'USER': 'cltbld', 08:00:07 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 08:00:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:07 INFO - '_': '/tools/buildbot/bin/python'} 08:00:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:00:08 INFO - Running setup.py (path:/tmp/pip-S9EyYa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:00:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:00:08 INFO - Running setup.py (path:/tmp/pip-SuYL7j-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:00:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:00:08 INFO - Running setup.py (path:/tmp/pip-zVGPyC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:00:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:00:08 INFO - Running setup.py (path:/tmp/pip-tCXS6h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:00:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:00:08 INFO - Running setup.py (path:/tmp/pip-gHbldi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:00:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:00:08 INFO - Running setup.py (path:/tmp/pip-RGl6tl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:00:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:00:08 INFO - Running setup.py (path:/tmp/pip-5Y5ygF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:00:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:00:08 INFO - Running setup.py (path:/tmp/pip-tP9I9Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:00:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:00:08 INFO - Running setup.py (path:/tmp/pip-XqdZTr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:00:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:00:08 INFO - Running setup.py (path:/tmp/pip-xg5LZx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:00:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:00:08 INFO - Running setup.py (path:/tmp/pip-fsYSy3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:00:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:00:08 INFO - Running setup.py (path:/tmp/pip-dMiRkA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:00:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:00:09 INFO - Running setup.py (path:/tmp/pip-WAFVjH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:00:09 INFO - Running setup.py (path:/tmp/pip-o0Axap-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:00:09 INFO - Running setup.py (path:/tmp/pip-UEgzQs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:00:09 INFO - Running setup.py (path:/tmp/pip-6Rv4Oa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:00:09 INFO - Running setup.py (path:/tmp/pip-7HYAu7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:00:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:00:09 INFO - Running setup.py install for manifestparser 08:00:09 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 08:00:09 INFO - Running setup.py install for mozcrash 08:00:09 INFO - Running setup.py install for mozdebug 08:00:09 INFO - Running setup.py install for mozdevice 08:00:09 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 08:00:09 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 08:00:09 INFO - Running setup.py install for mozfile 08:00:09 INFO - Running setup.py install for mozhttpd 08:00:09 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 08:00:09 INFO - Running setup.py install for mozinfo 08:00:10 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 08:00:10 INFO - Running setup.py install for mozInstall 08:00:10 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 08:00:10 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 08:00:10 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 08:00:10 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 08:00:10 INFO - Running setup.py install for mozleak 08:00:10 INFO - Running setup.py install for mozlog 08:00:10 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 08:00:10 INFO - Running setup.py install for moznetwork 08:00:10 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 08:00:10 INFO - Running setup.py install for mozprocess 08:00:10 INFO - Running setup.py install for mozprofile 08:00:10 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 08:00:10 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 08:00:10 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 08:00:10 INFO - Running setup.py install for mozrunner 08:00:10 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 08:00:10 INFO - Running setup.py install for mozscreenshot 08:00:10 INFO - Running setup.py install for moztest 08:00:11 INFO - Running setup.py install for mozversion 08:00:11 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 08:00:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:00:11 INFO - Cleaning up... 08:00:11 INFO - Return code: 0 08:00:11 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:00:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:11 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4b6edacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d1600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2560350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25616e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:00:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 08:00:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:00:11 INFO - Using env: {'DISPLAY': ':0', 08:00:11 INFO - 'HOME': '/home/cltbld', 08:00:11 INFO - 'LANG': 'en_US.UTF-8', 08:00:11 INFO - 'LANGUAGE': 'en_US:en', 08:00:11 INFO - 'LOGNAME': 'cltbld', 08:00:11 INFO - 'MAIL': '/var/mail/cltbld', 08:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:11 INFO - 'MOZ_NO_REMOTE': '1', 08:00:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:11 INFO - 'NO_EM_RESTART': '1', 08:00:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:00:11 INFO - 'PWD': '/builds/slave/test-pgo', 08:00:11 INFO - 'SHELL': '/bin/bash', 08:00:11 INFO - 'SHLVL': '1', 08:00:11 INFO - 'TERM': 'linux', 08:00:11 INFO - 'TMOUT': '86400', 08:00:11 INFO - 'USER': 'cltbld', 08:00:11 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 08:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:11 INFO - '_': '/tools/buildbot/bin/python'} 08:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:00:11 INFO - Running setup.py (path:/tmp/pip-UdcsV_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:00:11 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 08:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:00:11 INFO - Running setup.py (path:/tmp/pip-EuZCUQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:00:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 08:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:00:11 INFO - Running setup.py (path:/tmp/pip-e44e7B-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:00:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 08:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:00:11 INFO - Running setup.py (path:/tmp/pip-ZDTx4H-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:00:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 08:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:00:11 INFO - Running setup.py (path:/tmp/pip-pd7gr4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:00:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 08:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:00:11 INFO - Running setup.py (path:/tmp/pip-s6URWY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:00:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 08:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:00:11 INFO - Running setup.py (path:/tmp/pip-n23z_z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:00:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 08:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:00:11 INFO - Running setup.py (path:/tmp/pip-ksqXNl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:00:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 08:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:00:11 INFO - Running setup.py (path:/tmp/pip-EfALmK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 08:00:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:00:12 INFO - Running setup.py (path:/tmp/pip-rv7yk_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 08:00:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:00:12 INFO - Running setup.py (path:/tmp/pip-UCHPJO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 08:00:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:00:12 INFO - Running setup.py (path:/tmp/pip-5q8pxN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 08:00:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:00:12 INFO - Running setup.py (path:/tmp/pip-nD_3LF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 08:00:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:00:12 INFO - Running setup.py (path:/tmp/pip-99PZm1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 08:00:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:00:12 INFO - Running setup.py (path:/tmp/pip-ZV0IYo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 08:00:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:00:12 INFO - Running setup.py (path:/tmp/pip-9L2un8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 08:00:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:00:12 INFO - Running setup.py (path:/tmp/pip-JjuPgc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 08:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 08:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 08:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 08:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 08:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 08:00:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 08:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:14 INFO - Downloading blessings-1.6.tar.gz 08:00:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 08:00:14 INFO - Installing collected packages: blessings 08:00:14 INFO - Running setup.py install for blessings 08:00:14 INFO - Successfully installed blessings 08:00:14 INFO - Cleaning up... 08:00:14 INFO - Return code: 0 08:00:14 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 08:00:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:00:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:00:14 INFO - Reading from file tmpfile_stdout 08:00:14 INFO - Current package versions: 08:00:14 INFO - argparse == 1.2.1 08:00:14 INFO - blessings == 1.6 08:00:14 INFO - blobuploader == 1.2.4 08:00:14 INFO - docopt == 0.6.1 08:00:14 INFO - manifestparser == 1.1 08:00:14 INFO - mozInstall == 1.12 08:00:14 INFO - mozcrash == 0.16 08:00:14 INFO - mozdebug == 0.1 08:00:14 INFO - mozdevice == 0.47 08:00:14 INFO - mozfile == 1.2 08:00:14 INFO - mozhttpd == 0.7 08:00:14 INFO - mozinfo == 0.9 08:00:14 INFO - mozleak == 0.1 08:00:14 INFO - mozlog == 3.1 08:00:14 INFO - moznetwork == 0.27 08:00:14 INFO - mozprocess == 0.22 08:00:14 INFO - mozprofile == 0.27 08:00:14 INFO - mozrunner == 6.11 08:00:14 INFO - mozscreenshot == 0.1 08:00:14 INFO - mozsystemmonitor == 0.0 08:00:14 INFO - moztest == 0.7 08:00:14 INFO - mozversion == 1.4 08:00:14 INFO - psutil == 3.1.1 08:00:14 INFO - requests == 1.2.3 08:00:14 INFO - wsgiref == 0.1.2 08:00:14 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:14 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4b6edacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d1600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2560350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25616e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:00:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 08:00:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:00:14 INFO - Using env: {'DISPLAY': ':0', 08:00:14 INFO - 'HOME': '/home/cltbld', 08:00:14 INFO - 'LANG': 'en_US.UTF-8', 08:00:14 INFO - 'LANGUAGE': 'en_US:en', 08:00:14 INFO - 'LOGNAME': 'cltbld', 08:00:14 INFO - 'MAIL': '/var/mail/cltbld', 08:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:14 INFO - 'MOZ_NO_REMOTE': '1', 08:00:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:14 INFO - 'NO_EM_RESTART': '1', 08:00:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:00:14 INFO - 'PWD': '/builds/slave/test-pgo', 08:00:14 INFO - 'SHELL': '/bin/bash', 08:00:14 INFO - 'SHLVL': '1', 08:00:14 INFO - 'TERM': 'linux', 08:00:14 INFO - 'TMOUT': '86400', 08:00:14 INFO - 'USER': 'cltbld', 08:00:14 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 08:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:14 INFO - '_': '/tools/buildbot/bin/python'} 08:00:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 08:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 08:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 08:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 08:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 08:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 08:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 08:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 08:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 08:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 08:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 08:00:15 INFO - Cleaning up... 08:00:15 INFO - Return code: 0 08:00:15 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 08:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:15 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4b6edacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d1600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2560350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25616e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:00:15 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 08:00:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:00:15 INFO - Using env: {'DISPLAY': ':0', 08:00:15 INFO - 'HOME': '/home/cltbld', 08:00:15 INFO - 'LANG': 'en_US.UTF-8', 08:00:15 INFO - 'LANGUAGE': 'en_US:en', 08:00:15 INFO - 'LOGNAME': 'cltbld', 08:00:15 INFO - 'MAIL': '/var/mail/cltbld', 08:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:15 INFO - 'MOZ_NO_REMOTE': '1', 08:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:15 INFO - 'NO_EM_RESTART': '1', 08:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:00:15 INFO - 'PWD': '/builds/slave/test-pgo', 08:00:15 INFO - 'SHELL': '/bin/bash', 08:00:15 INFO - 'SHLVL': '1', 08:00:15 INFO - 'TERM': 'linux', 08:00:15 INFO - 'TMOUT': '86400', 08:00:15 INFO - 'USER': 'cltbld', 08:00:15 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 08:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:15 INFO - '_': '/tools/buildbot/bin/python'} 08:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:15 INFO - Downloading/unpacking jsonschema 08:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:00:16 INFO - Downloading/unpacking functools32 (from jsonschema) 08:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:17 INFO - Downloading functools32-3.2.3-2.tar.gz 08:00:17 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 08:00:17 INFO - warning: no files found matching '*.txt' 08:00:17 INFO - no previously-included directories found matching 'build' 08:00:17 INFO - no previously-included directories found matching 'dist' 08:00:17 INFO - no previously-included directories found matching '.git*' 08:00:17 INFO - Installing collected packages: jsonschema, functools32 08:00:17 INFO - Running setup.py install for functools32 08:00:17 INFO - warning: no files found matching '*.txt' 08:00:17 INFO - no previously-included directories found matching 'build' 08:00:17 INFO - no previously-included directories found matching 'dist' 08:00:17 INFO - no previously-included directories found matching '.git*' 08:00:17 INFO - Successfully installed jsonschema functools32 08:00:17 INFO - Cleaning up... 08:00:17 INFO - Return code: 0 08:00:17 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 08:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:17 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4b6edacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d1600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2560350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25616e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:00:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 08:00:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 08:00:17 INFO - Using env: {'DISPLAY': ':0', 08:00:17 INFO - 'HOME': '/home/cltbld', 08:00:17 INFO - 'LANG': 'en_US.UTF-8', 08:00:17 INFO - 'LANGUAGE': 'en_US:en', 08:00:17 INFO - 'LOGNAME': 'cltbld', 08:00:17 INFO - 'MAIL': '/var/mail/cltbld', 08:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:17 INFO - 'MOZ_NO_REMOTE': '1', 08:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:17 INFO - 'NO_EM_RESTART': '1', 08:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:00:17 INFO - 'PWD': '/builds/slave/test-pgo', 08:00:17 INFO - 'SHELL': '/bin/bash', 08:00:17 INFO - 'SHLVL': '1', 08:00:17 INFO - 'TERM': 'linux', 08:00:17 INFO - 'TMOUT': '86400', 08:00:17 INFO - 'USER': 'cltbld', 08:00:17 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 08:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:17 INFO - '_': '/tools/buildbot/bin/python'} 08:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:17 INFO - Downloading/unpacking flake8 08:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:00:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:19 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:00:19 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:19 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:20 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 08:00:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:00:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:00:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:00:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:00:20 INFO - no previously-included directories found matching 'docs/_build' 08:00:20 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:00:20 INFO - Running setup.py install for pep8 08:00:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:00:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:00:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:00:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:00:20 INFO - no previously-included directories found matching 'docs/_build' 08:00:20 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 08:00:20 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:00:20 INFO - Cleaning up... 08:00:20 INFO - Return code: 0 08:00:20 INFO - Running post-action listener: _resource_record_post_action 08:00:20 INFO - Running post-action listener: _start_resource_monitoring 08:00:20 INFO - Starting resource monitoring. 08:00:20 INFO - ##### 08:00:20 INFO - ##### Running install step. 08:00:20 INFO - ##### 08:00:20 INFO - Running pre-action listener: _resource_record_pre_action 08:00:20 INFO - Running main action method: install 08:00:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:00:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:00:20 INFO - Reading from file tmpfile_stdout 08:00:20 INFO - Detecting whether we're running mozinstall >=1.0... 08:00:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 08:00:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 08:00:20 INFO - Reading from file tmpfile_stdout 08:00:20 INFO - Output received: 08:00:20 INFO - Usage: mozinstall [options] installer 08:00:20 INFO - Options: 08:00:20 INFO - -h, --help show this help message and exit 08:00:20 INFO - -d DEST, --destination=DEST 08:00:20 INFO - Directory to install application into. [default: 08:00:20 INFO - "/builds/slave/test-pgo"] 08:00:20 INFO - --app=APP Application being installed. [default: firefox] 08:00:20 INFO - mkdir: /builds/slave/test-pgo/build/application 08:00:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 08:00:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 08:00:35 INFO - Reading from file tmpfile_stdout 08:00:35 INFO - Output received: 08:00:35 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 08:00:35 INFO - Running post-action listener: _resource_record_post_action 08:00:35 INFO - ##### 08:00:35 INFO - ##### Running run-tests step. 08:00:35 INFO - ##### 08:00:35 INFO - Running pre-action listener: _resource_record_pre_action 08:00:35 INFO - Running main action method: run_tests 08:00:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 08:00:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 08:00:35 INFO - Python 2.7.3 08:00:35 INFO - Return code: 0 08:00:35 INFO - grabbing minidump binary from tooltool 08:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:35 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2560350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2561250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25616e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 08:00:35 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 08:00:35 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:00:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:00:35 INFO - Return code: 0 08:00:35 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 08:00:35 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 08:00:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 08:00:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 08:00:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 08:00:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 08:00:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 08:00:36 INFO - Return code: 0 08:00:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 08:00:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --webServer localhost 08:00:36 INFO - Using env: {'DISPLAY': ':0', 08:00:36 INFO - 'HOME': '/home/cltbld', 08:00:36 INFO - 'LANG': 'en_US.UTF-8', 08:00:36 INFO - 'LANGUAGE': 'en_US:en', 08:00:36 INFO - 'LOGNAME': 'cltbld', 08:00:36 INFO - 'MAIL': '/var/mail/cltbld', 08:00:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:00:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 08:00:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:36 INFO - 'MOZ_NO_REMOTE': '1', 08:00:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:00:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:36 INFO - 'NO_EM_RESTART': '1', 08:00:36 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:00:36 INFO - 'PWD': '/builds/slave/test-pgo', 08:00:36 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 08:00:36 INFO - 'SHELL': '/bin/bash', 08:00:36 INFO - 'SHLVL': '1', 08:00:36 INFO - 'TERM': 'linux', 08:00:36 INFO - 'TMOUT': '86400', 08:00:36 INFO - 'USER': 'cltbld', 08:00:36 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681', 08:00:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:36 INFO - '_': '/tools/buildbot/bin/python'} 08:00:36 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost'] with output_timeout 3600 08:00:36 INFO - mozversion INFO | application_buildid: 20160127021629 08:00:36 INFO - mozversion INFO | application_changeset: fa05149b610dbe67f678cb2eae08ca7082136ce2 08:00:36 INFO - mozversion INFO | application_display_name: Nightly 08:00:36 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:00:36 INFO - mozversion INFO | application_name: Firefox 08:00:36 INFO - mozversion INFO | application_remotingname: firefox 08:00:36 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:00:36 INFO - mozversion INFO | application_vendor: Mozilla 08:00:36 INFO - mozversion INFO | application_version: 45.0 08:00:36 INFO - mozversion INFO | platform_buildid: 20160127021629 08:00:36 INFO - mozversion INFO | platform_changeset: fa05149b610dbe67f678cb2eae08ca7082136ce2 08:00:36 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:00:36 INFO - mozversion INFO | platform_version: 45.0 08:00:36 INFO - 2016-01-27 08:00:36,607 DEBUG : using testdate: 1453910436 08:00:36 INFO - 2016-01-27 08:00:36,607 DEBUG : actual date: 1453910436 08:00:36 INFO - 2016-01-27 08:00:36,616 INFO : starting webserver on 'localhost:40328' 08:00:36 INFO - 2016-01-27 08:00:36,617 INFO : Starting test suite talos-linux64-ix-061 08:00:36 INFO - 2016-01-27 08:00:36,617 INFO : Starting test dromaeo_css 08:00:36 INFO - 2016-01-27 08:00:36,617 DEBUG : operating with platform_type : linux_ 08:00:36 INFO - 2016-01-27 08:00:36,618 INFO : Initialising browser for dromaeo_css test... 08:00:36 INFO - 2016-01-27 08:00:36,634 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRlAzVa/profile http://localhost:40328/getInfo.html 08:00:40 INFO - 2016-01-27 08:00:40,740 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:00:40 INFO - 2016-01-27 08:00:40,740 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:00:40 INFO - 2016-01-27 08:00:40,740 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:00:40 INFO - 2016-01-27 08:00:40,740 DEBUG : BROWSER_OUTPUT: __metrics 08:00:41 INFO - 2016-01-27 08:00:41,051 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:00:41 INFO - 2016-01-27 08:00:41,903 INFO : Browser initialized. 08:00:41 INFO - 2016-01-27 08:00:41,904 INFO : Running cycle 1/1 for dromaeo_css test... 08:00:41 INFO - 2016-01-27 08:00:41,904 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRlAzVa/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:00:53 INFO - 2016-01-27 08:00:53,621 DEBUG : BROWSER_OUTPUT: RSS: Main: 134545408 08:00:53 INFO - 2016-01-27 08:00:53,622 DEBUG : BROWSER_OUTPUT: 08:01:44 INFO - 2016-01-27 08:01:44,741 DEBUG : BROWSER_OUTPUT: 1453910504733 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:03:52 INFO - 2016-01-27 08:03:52,767 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40328/tests/dromaeo/cssquery-ext.html) 08:03:53 INFO - 2016-01-27 08:03:53,804 DEBUG : BROWSER_OUTPUT: RSS: Main: 243486720 08:03:53 INFO - 2016-01-27 08:03:53,804 DEBUG : BROWSER_OUTPUT: 08:06:55 INFO - 2016-01-27 08:06:55,843 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/dromaeo/cssquery-ext.html (next: http://localhost:40328/tests/dromaeo/cssquery-jquery.html) 08:06:56 INFO - 2016-01-27 08:06:56,409 DEBUG : BROWSER_OUTPUT: RSS: Main: 206254080 08:06:56 INFO - 2016-01-27 08:06:56,409 DEBUG : BROWSER_OUTPUT: 08:09:58 INFO - 2016-01-27 08:09:58,152 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40328/tests/dromaeo/cssquery-mootools.html) 08:09:58 INFO - 2016-01-27 08:09:58,713 DEBUG : BROWSER_OUTPUT: RSS: Main: 202117120 08:09:58 INFO - 2016-01-27 08:09:58,713 DEBUG : BROWSER_OUTPUT: 08:13:00 INFO - 2016-01-27 08:13:00,421 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40328/tests/dromaeo/cssquery-prototype.html) 08:13:00 INFO - 2016-01-27 08:13:00,984 DEBUG : BROWSER_OUTPUT: RSS: Main: 194813952 08:13:00 INFO - 2016-01-27 08:13:00,984 DEBUG : BROWSER_OUTPUT: 08:16:03 INFO - 2016-01-27 08:16:03,362 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40328/tests/dromaeo/cssquery-yui.html) 08:16:03 INFO - 2016-01-27 08:16:03,923 DEBUG : BROWSER_OUTPUT: RSS: Main: 196030464 08:16:03 INFO - 2016-01-27 08:16:03,923 DEBUG : BROWSER_OUTPUT: 08:19:05 INFO - 2016-01-27 08:19:05,867 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/dromaeo/cssquery-yui.html (next: http://localhost:40328/tests/dromaeo/cssquery-dojo.html) 08:19:06 INFO - 2016-01-27 08:19:06,117 DEBUG : BROWSER_OUTPUT: RSS: Main: 240517120 08:19:06 INFO - 2016-01-27 08:19:06,117 DEBUG : BROWSER_OUTPUT: 08:19:06 INFO - 2016-01-27 08:19:06,118 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:19:06 INFO - 2016-01-27 08:19:06,118 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:19:06 INFO - 2016-01-27 08:19:06,118 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:19:06 INFO - 2016-01-27 08:19:06,118 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:19:06 INFO - 2016-01-27 08:19:06,119 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3269.1663625775423;3386.4243078676623;3420.6579342065797;3428.4857271409287;3460.3425349183644;5562.443755624436;5596.888062238752;5635.351934527531;5641.887162256752;5654.547636189101;5600.775968961247;5659.151127330892;5669.433056694328;5676.914846277309;5687.943120568793;4860.441049279325;5132.281480592711;5153.072446959546;5207.088759467097;5232.843014709546;4956.67781594197;5037.143685573443;5104.64267501275;5118.769655365499;5205.765740541685;5062.519060689228;5071.847844564651;5072.0870243356185;5072.4166720827025;5089.541941225289;4833.250846118857;5019.924701129486;5046.722430266371;5104.66819656723;5125.974370128144;4967.081089998362;4972.080165169419;5069.44236134025;5073.340465739467;5081.872953176163;5354.223637572569;5379.542738867201;5384.838454846322;5386.245925570423;5401.216823560561;5709.714514274311;5820.708964551755;5967.433093856077;5968;5972.552058595623;5901.409859014064;5985.6408615483215;5987.670678112702;5996.850078748066;6000.18997435345;5551.028570000234;5619.381867994517;5625.353084395265;5628.690422026787;5632.577556683224;5800.738966746507;5861.472467477947;5925.140854576062;6017;6095.634261944298;5883;5887.499562537147;5918.704064796829;5926.792562260386;5933.495652869555;5996.610220335664;6006.219191505076;6013.488853447506;6016.518678505709;6018.337982821886;5628.577856660767;5705.4579814917515;5715.942840571624;5790.768369265266;5969.671668058211;5965.642061476326;6004.3395226525045;6010.969945150334;6012.158297838306;6023.427774361428;4583.72497650142;4585.770711464414;4587.059652771174;4601.884952876205;4611.815527378934;3977.7812220327583;3979.7612143271494;4070.633642972147;4071;4151.605597468236;5664.54683625309;5664.660120392789;5714.657120572696;5723.942760572424;5725.656460612377;5981.5214782817275;6010.909836352458;6030.065339872327;6042.667653279024;6048.455638992512;5414.106672399089;5530.446955304437;5655.575831812548;5696.686682232517;5757.021306377926;5176.301199338116;5189.247559103909;5190.896182076337;5198.662086964335;5221.817236396784;5907.497862681635;6075.362086980804;6103.481204097613;6105.877882442326;6107.908381374283;5780.797672081457;5874.853128671646;5905.43898329658;5906.143609176559;5920.79277225295;6047.48596369296;6083.8174854754425;6093.512518998381;6094.603850749687;6152.600080994721;5488.917666235089;5916.73374698131;5922;5924.407559244041;5925.881482370414;5700.087985922232;5786.4213578643485;5883.705814709333;5893.764249430146;5932.762689492372;5830.358660547421;5895.764169433346;5913.645181289136;5921.881562368642;5924.674142922181;5574.581906356958;5688.40271771458;5856.238688970407;5906.70466476683;5922.881542369215;5688.11834165705;5777.422257774189;5779.133130030529;5781.624194427349;5808.970955145197;5834.38738932406;5865.706714664165;5873.353931067493;5874.794382196603;5881.617694849821;5477.616566840283;5577;5584.776608935597;5738.254026976467;5739.827805165852 08:19:06 INFO - 2016-01-27 08:19:06,120 DEBUG : BROWSER_OUTPUT: |1;ext.html;597.286242939687;624.2883113250892;631.8546734251122;646.324590802611;649.6134799794124;32639.67360326396;40708.18583628326;41237.58762412375;41346.17307653845;41445.58554414455;45868.77065614676;46327.536724632744;46350.768246158805;46424.76787616057;46441.30338044924;8037.919620803805;8137.511749295061;8891.377603567753;9122.771930701752;9138.0405057469;10743.78512429751;10925.453727313643;11010.944945275263;11014.614488492904;11020.944895275512;10676.519556619933;10730.034296913276;10798.406087665175;10799.2440529163;10806.08148307391;10652.78694426111;10737.194710396674;10759.462026898664;10763.3541987481;10771.946140269287;10445.164386849034;10559.313644613076;10583.10043646291;10585.788284234271;10591.576336946513;42512.362314565304;42565.78717106426;43019.56980430187;43056.78471607622;43101;3245.6754324567355;3379.205886616643;3673.669369756708;3978.781167035812;4029.263922759164;32133.51799723006;32510;32521;32589.185270368194;32596.511052334234;4126.731762435432;4156.501219853607;4187.790610469464;4205.684573656988;4210.221109094797;283383.58308208536;284025;284124;284733;284741;60248.39751602516;61629;61780;62119.68940155361;62174.378256216856;72401.63799180971;73607.26392736004;73929.26070739332;73936.260637394;74075.62962185263;18018.36935707244;18183.545411364823;18272.725909111374;18285.360012399502;18309.633807324044;14135.64660883486;14150.646233844236;14231.359588818528;14236.43054277838;14238.359273832702;20430.489237768878;20566.177352905717;20606.690899636516;20618.07218675133;20658.58682826335;7585.962070189614;7621.923780762232;7724.382049436032;7760.34037106841;7811.609419529001;1041.2815157541272;1070.4273213830563;1070.619929924881;1071.8767341755797;1090.2858627598778;29750;29946.25134371658;30208.848955755082;30848;30925.845370773;1146.4668928948079;1162.8662703789173;1167.8656954450346;1179.121554441929;1250.9249445033327;13832.377543010589;15766;15824.367025319088;15865.603359916095;16087.758683619755;322980;326135;326136;326380;326789;52580.73709631504;54078.72960635251;54131.45868541264;54653;55549.722251388484;1427.635952832042;1439.9136051836924;1445.768677011694;1453.6293245222612;1460.5618314505605;26753.732462675125;27021.248029597726;29056;29143.271418214714;29443.558346624388;45820;46336.76831615955;46368.30447543222;46374.76812615915;46413;243210;243842;243951;244067;245110;33386.49920251247;33553.49669754907;33683.66316336805;34052.31895362029;34182.487262691575;43215.13569728525;43882.12235755331;44204.55795442133;44432.55567444284;44552.331715023654;36461.817690911375;36494.27011459809;37479.43780843235;37718.62281377261;39134.608653914234;36813.81593092017;38417.03471292397;38640;38770.80614596909;38794.03014924649;36803.26393472169;37578;37649.62350376571;37671.623283766814;37886.242275154895;32521.186970325933;32629.673703262662;32766;33205;34082.48876266809;26110;26197.345066373495;26376.472470550867;26423.867880660473;26564.468710626064 08:19:06 INFO - 2016-01-27 08:19:06,124 DEBUG : BROWSER_OUTPUT: |2;jquery.html;384.2545461804098;386.7408836079826;391.4656493885845;391.481287294335;392.6917369864657;17413.825861741378;17654.205560749764;18595.535111622215;18779.71830422543;18842.434726958196;15345.539633810975;15778.501042400982;17709.7343539847;18279.81720182801;18417;5434.538064264522;5456.290682211307;5533.667979921198;5534.723263836812;5560.277163968678;5501;5548.639338442988;5552.305961754781;5566.165075238706;5586.8603284918;5440.292761940962;5527.778888844461;5562.748381614125;5572.721363931808;5572.749226284827;5516.393196748354;5536.916946245809;5541.5289700375515;5543.6119471637;5545.306836645419;5003.949960500421;5164.8967020659375;5286.180642000496;5329.147336426192;5330.893382132374;14296.857031429656;15514.5345639631;21189.046492907855;22609.547809043892;22779;2325.4883925536524;2352.0709319818575;2356.7289761677284;2442.8656423896678;2480.0203919451715;9761.463119528424;10196.490175491195;10371.325863818827;10609.628662996758;10667.359958402521;1853.721941708741;1887.5941672540332;1922.423273018103;1966.3314473079079;1976.723258743777;64758.67620661914;65665.67167164182;67480.6625966867;68232;68376.31623683749;11319.37743424103;11323.773524529463;11343.886561134283;11350.829737553944;11354.602588909514;14760.188189649672;14924.62688432798;14924.850751492564;14945.925270373797;14959;7254.383377412873;7448.590327532038;7456.40348772097;8376.53929033909;8415.11641277657;7017.543859649107;8019.278264956182;8160.387970902206;8250.298724608356;8275.586220689062;4383.736975781399;4414.293713005904;4444.488883778342;4446.133004064176;4486.394336764561;1740.9056111599307;1922;1924.6343194792944;1930.276146445083;1934.3713293179774;913.4154141349542;1031.983496256187;1074.983875241872;1103.8675358956978;1131.2816361610437;15875.682486350208;15940.840591593935;16523.504294871174;16734;16985.91507042457;989.8762654668167;1007.9546420411099;1060.6181774561153;1069.5775168808277;1096.5394534295615;8408.32733381328;9724.513774311255;9830.715888231643;10243.48782560869;10593.516907632938;451262;456676;457562;458957;465702;44481;44584.3312350315;64722.35277647257;80348;81172.59413702894;1189.0606420927543;1206.4088596587785;1244.2596654990266;1246.569933372985;1254.216114928188;15884.285207165936;16591.585210369838;16702.49892503227;16821.831781682027;16924.32302707878;37346.813265933495;37714.622853771114;37951.81024094862;38995;39943;51314;51869;52340.21489677734;55698.16452753293;56079.71960140173;10420.32267902584;10499.475026248505;10500.527476263434;10588;10593.89406105929;12578.874211257771;12652.746945061232;13368.398422071225;18909.810901891356;19357.1289291985;14954.551363459113;15379.538613841603;15392.846071539141;15417.922910385376;15805.13071781017;14439.92780036093;14593.70812583721;14884.404623814926;15625.29686164148;15753.44862929792;14432.927835360755;14895.25523723831;15316.387344506094;15687.764683529984;15857.127857967922;8479;10227.28409011362;10240.385576865412;10356.482175891024;10566.101881339864;14453.132812031312;14801.851981480047;14947.252637368305;15079.924600376928;15423.537293881202 08:19:06 INFO - 2016-01-27 08:19:06,125 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1553.1845780964993;1557.5171696774;1726.6115124097087;1764.1179410294853;1821.6083542038464;7485.850282994344;7932.365410767139;7937.44437889348;7950.324222441085;7962.522248665085;7803.648835802388;7881.330086942617;7889.9605501972555;7897.210278972114;7898.842023159534;2247.3932038349635;2313.7801908818637;2349.2717257650097;2350.388898886293;2354.0936739355384;2313.7223533176043;2337.3104934044413;2338.5089131282452;2341.555104530134;2343.7773411525877;1979.8020197980231;2017.4653716764997;2208.094681180716;2237;2302.5970455170363;2227.1425501182;2231.4979129695853;2250.4036430345973;2253.4591697992446;2254.199759085534;2241.12596087526;2316.2356422380576;2316.745158032615;2317.640765681322;2317.8609283443047;6440.903386449207;6553.213614366259;7488.213737557532;7735.7679269622;7747.2640099190485;4669.112868554964;4737.265723812815;4739.12326219647;4741.170295198326;4743.288506723985;3000.804947678394;3057.908262752121;3065.1264389648845;3071.9232019199476;3087.0893086539527;1029.5521448169995;1054.4516851237313;1054.915606751458;1058.5077938758436;1095.2223921016089;10562.31344962575;10675.359478431319;10678.199135064824;10689.358638481639;10698.732531686692;4068.3287257602756;4078.062045729499;4164.167166566699;4191.119864828359;4191.706580539394;8511.531865747444;8514.233718965323;8519.403641745143;8523.445976011539;8529.914700853036;4671.509491503343;4673.299005149255;4677.204875171229;4697.271922851895;4707.246840505502;4330.610245077895;4338.132373525307;4342.283523218697;4342.652587792969;4352.738835669807;3436.2096717754557;3446.844891979867;3471.6181220065773;3476.878309259164;3529.082438565991;1035.5605708065796;1037.527924794217;1054.5096530113533;1066.7013236293851;1086.8750093739163;1387.840398354182;1401.159304417362;1418.7729963205838;1422.5518961527087;1429.7069100834303;7285.526440781332;7410.629468526552;7443.330100290891;7458.664360103809;7460.85078298431;1106.750981029262;1143.5482984221353;1190.6547101340514;1206.21595962625;1218.8415505984342;5318.973405133027;6162.383761623892;6190.597611155261;6195.938040619536;6205.162303089115;7401.444891633149;7469.029026226664;7483.513571617937;7488.588127653032;7678.078630564256;9031.187193152648;9313.627454901729;9334.533273336307;9378.202852757591;9482.431054136774;7108.8933665995055;7152.892706609405;7174.856502869913;7176.461765367619;7241.275872412716;7211.134663840373;7277.563346199245;7291.671874765542;7298.635068246673;7345.228750981284;8023.3121904160425;8036.1160272368825;8142.918570814453;8167.264946154874;8173.937388139504;8187.3040791533385;8197.631106600336;8349.290310323691;8396.622152003296;8400.789980250298;6551.967240163769;6623.105880706235;6624.668766561749;6624.701888414937;6627.039079333566;7344.30229128232;7378.704851805867;7390.260973902782;7399.889001665087;7418.443616728659;6746.190457145174;6874.2782007890455;6899.689513971984;6958.478114141561;6975.9302406975285;6880.862382752217;6918.273581273894;6927.861442771217;7006.439484841098;7026.578405295699;7177.712891484282;7346.816329591589;7347.7795666130105;7355.043844300207;7358.632068396666;6744.190697116377;6764.0868482754195;6764.391204791593;6778.4916131289365;6780.898286525608;6383.6808159592765;6737.528373014038;6747.493937954575;6749.426298764463;6781.050652908498 08:19:06 INFO - 2016-01-27 08:19:06,126 DEBUG : BROWSER_OUTPUT: |4;prototype.html;332.788649316488;333.64300198787294;340.10043435114125;352.2901353772148;359.3137108123483;6084.543659225554;6090.66501342426;6097.817065488042;6112.847178820526;6161.260648722148;5788.160716696077;5864.179014937912;5930.377310382401;6068.514518838493;6094.116353128794;4723.976380118094;4730.479647238801;4742.075295317406;4743.857684269477;4756.334113224151;4465.754383508905;4749.76251187441;4750.073735621555;4761.142994261032;4767.880802979936;4503.864884053483;4583.656225783063;4689.109069276844;4701.717896926179;4706.388169537972;4667.766611669403;4679.555442233;4688.296755486705;4698.90602187956;4705.858824235278;4161.04296011919;4168.083021735213;4169.187008533338;4173.207090652797;4173.248815213291;5766;5825.679587622679;5833.591648584601;5839.328477225131;5848.912266316009;5440.455954404568;5441.972790136064;5442.23808666783;5450.155225939986;5450.618456708032;6471.967640161816;6474.1907261592305;6476.93523064768;6478.805635830932;6483.416492515649;4536.931946020813;4542.591166795004;4545.749983750892;4547.8635640930825;4561.2245918193985;6549.639769812658;6612.413020875004;6692.498062645321;6695.363940425603;6702.530822842403;4640.83757068501;4641.2109941310055;4646.442426908792;4647.09381670571;4656.604188643935;4698.647601429906;4701.130290896127;4706.717596944194;4712.85861424151;4714.057188562233;5408.540274076748;5416.891662166735;5421.701806400686;5423.864403389868;5423.891522169534;6285.862806645762;6511.967440162769;6520.706568204442;6521.08704781331;6528.281888992207;1613.895096818703;1642.7125253080662;1644.1861278667136;1645.4487746604782;1646.415522489523;1061.3207547169818;1080.3841810168128;1089.8310761831929;1106.9003789658968;1136.2500749505452;6267;6283.403076707706;6284.340144284875;6288.276848162428;6294;6418.935810641927;6628.900566491507;6629.5359324846795;6635.270120286765;6638.900416493757;6153.815385538441;6174.660393678301;6315.652639104894;6347.777827776098;6354.301026887131;3574.160072382988;3624.075860655516;3625.728070394678;3627.147620309225;3628.582712987987;6734;6901.413379862768;6906.136732908387;6910.239873613898;6915.792526224222;6842.392037871096;6848.589084654936;6869.141357330333;6897.068895699016;6900.689468973908;6885.483588730765;6911.6889739961825;6914.619695916773;6916.135483064617;6920.688569014406;6602.279370250294;6656.2345330287635;6663.700133493908;6694;6699.732010719516;6651.933480665131;6667.099941507834;6667.166604174478;6672.4328432081875;6676.732930682719;6899.344562266577;6903.965480172567;6909.1018167637885;6912.997615345847;6916.861662766817;4505.63954883615;4566.315052742115;4572.771361431982;4574.336721175478;4574.954250457586;6649.135612370361;6701.095352127595;6707.195136583641;6708.631025293461;6711.6308603025345;6491.188601424822;6492.740290388331;6498.252700939453;6500.219973603198;6504.252011018604;6391.744330226739;6485.740570377132;6486.805395838132;6488.480921526361;6496.870062598816;6436.195475565554;6440;6445.516586255956;6451.806445806634;6452.483801295979;6510.18622672166;6553.4101930826455;6563.245226798788;6563.343665633399;6573.506986976092;6219.595726277777;6323.620582765049;6351.714172862325;6357.777477788255;6389.616623002449 08:19:06 INFO - 2016-01-27 08:19:06,128 DEBUG : BROWSER_OUTPUT: |5;yui.html;767.4359345880779;770.8304173081921;821.4619424277097;909.6315992023233;940.6049459227124;12210.572629957953;12412.255264684103;12436.689082772935;12449.502019919204;12458.37708114594;11827.290362578251;11897.64307070787;11987.340696261703;12027.458764355602;12074.456649450774;884.6417201033591;894.0299774744377;896.9282457403425;903.7108125399875;904.0913881549034;880.5157163560017;881.6825942660637;887.4364778365752;887.817997310553;888.7689200807804;922.557172557173;933.351320832022;933.8739270198538;934.2666007184362;934.8223837470892;924.6347692661361;927.2442958988403;927.4481683398367;927.777333439973;928.0858354520825;690.823839920822;692.9029935808991;693.3967448319994;694.2918223412141;694.7568351077133;11164.385958772264;11181.161412894066;11197.048250898684;11256;11274.661760147208;10753.83869241962;10993.395363255018;11056.834147487794;11117.22179447439;11279.661610151708;9116.316276279305;9191.770205744842;9203.907960920438;9252.167304942588;9487.146156845847;3740.2893450244364;3800.90497737556;3802.923941521182;3813.599572044923;3823.694104471663;43115.7844210777;45035.774821125684;46174.769126154155;54144;54368.91262174774;10001.199904007663;10302.124319432782;10331.586736530606;10474.790504189874;10511.421871797125;16039.679206415807;16273.51179464618;19562.90218548898;19807.30674426417;19810.40568782939;7367.77896663101;7401.259874012556;7438.702451901863;7617.314441700273;7621.123570789319;4140.896477588084;4163.271427500175;4213.831446742096;4287.3140297552245;4337.652987760972;3169.6513383527795;3183.1564635371647;3184.315372194943;3188.1551388881967;3190.0589326148843;559.6921693068796;568.687222027883;569.0894568690145;570.9628874123168;581.5580159079046;626.6188483761688;646.8803271394808;654.7119267522274;681.2812482830562;706.9187043489961;8550.358723095793;8743;8858.77853053666;8864.822703546022;8907.153820387182;538.526097034607;568.5110804707948;647.3364801078932;649.9642519661464;673.7170388436771;4078.7960601970376;4092;4205.810738516774;4230.915381692411;4261.722988005769;60276.095858562156;60787.08819367713;61221.38778612246;62149.68925155346;62535.37464625386;53210.73394633002;53571.19643205277;53931;54098.45901540934;54613;48723.75638121787;49136.75431622891;49141;49293;49311.50688493069;7959.801004974922;8075.636596353295;8103.83792324162;8264.132266111972;8482;33572.66427335695;33865.322693545495;37671.43492847664;38552.614473854905;38563.807180963915;47825.28262076141;48245.5175448241;48677;48952;49171.262431062845;11370.715732106764;11966.880331196577;12020.939895300467;12173.2696038238;12235.877641223475;11453.541858325907;12404.441800119197;13711.794323084961;13825.446982121004;13998.860011400164;10533;10550.630727924487;10890.945545272223;10925.83611245816;11323.830142548033;11355.602553910574;11498;11932.2851038188;11932.403379831147;12190;10365.274430789772;10647.201459890384;10753.677389678322;10863.945680271549;11472.196946213686;10184.347826086956;10498.790024199627;10705.090067344363;10930.289531180448;10947.56209751601;9864.950675246579;10173.643922462677;10173.745656358651;10243.07812296897;10377.325473844176 08:19:06 INFO - 2016-01-27 08:19:06,128 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:19:06 INFO - 2016-01-27 08:19:06,128 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:19:06 INFO - 2016-01-27 08:19:06,128 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,777 08:19:06 INFO - 2016-01-27 08:19:06,128 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:19:06 INFO - 2016-01-27 08:19:06,128 DEBUG : BROWSER_OUTPUT: __startTimestamp1453911546114__endTimestamp 08:19:06 INFO - 2016-01-27 08:19:06,129 DEBUG : BROWSER_OUTPUT: 08:19:06 INFO - 2016-01-27 08:19:06,129 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:19:06 INFO - 2016-01-27 08:19:06,129 DEBUG : BROWSER_OUTPUT: Number of tests: 6 08:19:06 INFO - 2016-01-27 08:19:06,129 DEBUG : BROWSER_OUTPUT: 08:19:06 INFO - 2016-01-27 08:19:06,129 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5524.45 Median:5714.66 stddev:599.10 (10.5%) stddev-sans-first:574.22 08:19:06 INFO - 2016-01-27 08:19:06,129 DEBUG : BROWSER_OUTPUT: Values: 3269.2 3386.4 3420.7 3428.5 3460.3 5562.4 5596.9 5635.4 5641.9 5654.5 5600.8 5659.2 5669.4 5676.9 5687.9 4860.4 5132.3 5153.1 5207.1 5232.8 4956.7 5037.1 5104.6 5118.8 5205.8 5062.5 5071.8 5072.1 5072.4 5089.5 4833.3 5019.9 5046.7 5104.7 5126.0 4967.1 4972.1 5069.4 5073.3 5081.9 5354.2 5379.5 5384.8 5386.2 5401.2 5709.7 5820.7 5967.4 5968.0 5972.6 5901.4 5985.6 5987.7 5996.9 6000.2 5551.0 5619.4 5625.4 5628.7 5632.6 5800.7 5861.5 5925.1 6017.0 6095.6 5883.0 5887.5 5918.7 5926.8 5933.5 5996.6 6006.2 6013.5 6016.5 6018.3 5628.6 5705.5 5715.9 5790.8 5969.7 5965.6 6004.3 6011.0 6012.2 6023.4 4583.7 4585.8 4587.1 4601.9 4611.8 3977.8 3979.8 4070.6 4071.0 4151.6 5664.5 5664.7 5714.7 5723.9 5725.7 5981.5 6010.9 6030.1 6042.7 6048.5 5414.1 5530.4 5655.6 5696.7 5757.0 5176.3 5189.2 5190.9 5198.7 5221.8 5907.5 6075.4 6103.5 6105.9 6107.9 5780.8 5874.9 5905.4 5906.1 5920.8 6047.5 6083.8 6093.5 6094.6 6152.6 5488.9 5916.7 5922.0 5924.4 5925.9 5700.1 5786.4 5883.7 5893.8 5932.8 5830.4 5895.8 5913.6 5921.9 5924.7 5574.6 5688.4 5856.2 5906.7 5922.9 5688.1 5777.4 5779.1 5781.6 5809.0 5834.4 5865.7 5873.4 5874.8 5881.6 5477.6 5577.0 5584.8 5738.3 5739.8 08:19:06 INFO - 2016-01-27 08:19:06,129 DEBUG : BROWSER_OUTPUT: 08:19:06 INFO - 2016-01-27 08:19:06,129 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:47214.39 Median:179504.84 stddev:74693.76 (41.6%) stddev-sans-first:74821.28 08:19:06 INFO - 2016-01-27 08:19:06,131 DEBUG : BROWSER_OUTPUT: Values: 597.3 624.3 631.9 646.3 649.6 32639.7 40708.2 41237.6 41346.2 41445.6 45868.8 46327.5 46350.8 46424.8 46441.3 8037.9 8137.5 8891.4 9122.8 9138.0 10743.8 10925.5 11010.9 11014.6 11020.9 10676.5 10730.0 10798.4 10799.2 10806.1 10652.8 10737.2 10759.5 10763.4 10771.9 10445.2 10559.3 10583.1 10585.8 10591.6 42512.4 42565.8 43019.6 43056.8 43101.0 3245.7 3379.2 3673.7 3978.8 4029.3 32133.5 32510.0 32521.0 32589.2 32596.5 4126.7 4156.5 4187.8 4205.7 4210.2 283383.6 284025.0 284124.0 284733.0 284741.0 60248.4 61629.0 61780.0 62119.7 62174.4 72401.6 73607.3 73929.3 73936.3 74075.6 18018.4 18183.5 18272.7 18285.4 18309.6 14135.6 14150.6 14231.4 14236.4 14238.4 20430.5 20566.2 20606.7 20618.1 20658.6 7586.0 7621.9 7724.4 7760.3 7811.6 1041.3 1070.4 1070.6 1071.9 1090.3 29750.0 29946.3 30208.8 30848.0 30925.8 1146.5 1162.9 1167.9 1179.1 1250.9 13832.4 15766.0 15824.4 15865.6 16087.8 322980.0 326135.0 326136.0 326380.0 326789.0 52580.7 54078.7 54131.5 54653.0 55549.7 1427.6 1439.9 1445.8 1453.6 1460.6 26753.7 27021.2 29056.0 29143.3 29443.6 45820.0 46336.8 46368.3 46374.8 46413.0 243210.0 243842.0 243951.0 244067.0 245110.0 33386.5 33553.5 33683.7 34052.3 34182.5 43215.1 43882.1 44204.6 44432.6 44552.3 36461.8 36494.3 37479.4 37718.6 39134.6 36813.8 38417.0 38640.0 38770.8 38794.0 36803.3 37578.0 37649.6 37671.6 37886.2 32521.2 32629.7 32766.0 33205.0 34082.5 26110.0 26197.3 26376.5 26423.9 26564.5 08:19:06 INFO - 2016-01-27 08:19:06,131 DEBUG : BROWSER_OUTPUT: 08:19:06 INFO - 2016-01-27 08:19:06,131 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26881.66 Median:1932.32 stddev:74865.35 (3874.4%) stddev-sans-first:75048.93 08:19:06 INFO - 2016-01-27 08:19:06,133 DEBUG : BROWSER_OUTPUT: Values: 384.3 386.7 391.5 391.5 392.7 17413.8 17654.2 18595.5 18779.7 18842.4 15345.5 15778.5 17709.7 18279.8 18417.0 5434.5 5456.3 5533.7 5534.7 5560.3 5501.0 5548.6 5552.3 5566.2 5586.9 5440.3 5527.8 5562.7 5572.7 5572.7 5516.4 5536.9 5541.5 5543.6 5545.3 5003.9 5164.9 5286.2 5329.1 5330.9 14296.9 15514.5 21189.0 22609.5 22779.0 2325.5 2352.1 2356.7 2442.9 2480.0 9761.5 10196.5 10371.3 10609.6 10667.4 1853.7 1887.6 1922.4 1966.3 1976.7 64758.7 65665.7 67480.7 68232.0 68376.3 11319.4 11323.8 11343.9 11350.8 11354.6 14760.2 14924.6 14924.9 14945.9 14959.0 7254.4 7448.6 7456.4 8376.5 8415.1 7017.5 8019.3 8160.4 8250.3 8275.6 4383.7 4414.3 4444.5 4446.1 4486.4 1740.9 1922.0 1924.6 1930.3 1934.4 913.4 1032.0 1075.0 1103.9 1131.3 15875.7 15940.8 16523.5 16734.0 16985.9 989.9 1008.0 1060.6 1069.6 1096.5 8408.3 9724.5 9830.7 10243.5 10593.5 451262.0 456676.0 457562.0 458957.0 465702.0 44481.0 44584.3 64722.4 80348.0 81172.6 1189.1 1206.4 1244.3 1246.6 1254.2 15884.3 16591.6 16702.5 16821.8 16924.3 37346.8 37714.6 37951.8 38995.0 39943.0 51314.0 51869.0 52340.2 55698.2 56079.7 10420.3 10499.5 10500.5 10588.0 10593.9 12578.9 12652.7 13368.4 18909.8 19357.1 14954.6 15379.5 15392.8 15417.9 15805.1 14439.9 14593.7 14884.4 15625.3 15753.4 14432.9 14895.3 15316.4 15687.8 15857.1 8479.0 10227.3 10240.4 10356.5 10566.1 14453.1 14801.9 14947.3 15079.9 15423.5 08:19:06 INFO - 2016-01-27 08:19:06,133 DEBUG : BROWSER_OUTPUT: 08:19:06 INFO - 2016-01-27 08:19:06,133 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5388.07 Median:6412.29 stddev:2717.48 (42.4%) stddev-sans-first:2709.82 08:19:06 INFO - 2016-01-27 08:19:06,133 DEBUG : BROWSER_OUTPUT: Values: 1553.2 1557.5 1726.6 1764.1 1821.6 7485.9 7932.4 7937.4 7950.3 7962.5 7803.6 7881.3 7890.0 7897.2 7898.8 2247.4 2313.8 2349.3 2350.4 2354.1 2313.7 2337.3 2338.5 2341.6 2343.8 1979.8 2017.5 2208.1 2237.0 2302.6 2227.1 2231.5 2250.4 2253.5 2254.2 2241.1 2316.2 2316.7 2317.6 2317.9 6440.9 6553.2 7488.2 7735.8 7747.3 4669.1 4737.3 4739.1 4741.2 4743.3 3000.8 3057.9 3065.1 3071.9 3087.1 1029.6 1054.5 1054.9 1058.5 1095.2 10562.3 10675.4 10678.2 10689.4 10698.7 4068.3 4078.1 4164.2 4191.1 4191.7 8511.5 8514.2 8519.4 8523.4 8529.9 4671.5 4673.3 4677.2 4697.3 4707.2 4330.6 4338.1 4342.3 4342.7 4352.7 3436.2 3446.8 3471.6 3476.9 3529.1 1035.6 1037.5 1054.5 1066.7 1086.9 1387.8 1401.2 1418.8 1422.6 1429.7 7285.5 7410.6 7443.3 7458.7 7460.9 1106.8 1143.5 1190.7 1206.2 1218.8 5319.0 6162.4 6190.6 6195.9 6205.2 7401.4 7469.0 7483.5 7488.6 7678.1 9031.2 9313.6 9334.5 9378.2 9482.4 7108.9 7152.9 7174.9 7176.5 7241.3 7211.1 7277.6 7291.7 7298.6 7345.2 8023.3 8036.1 8142.9 8167.3 8173.9 8187.3 8197.6 8349.3 8396.6 8400.8 6552.0 6623.1 6624.7 6624.7 6627.0 7344.3 7378.7 7390.3 7399.9 7418.4 6746.2 6874.3 6899.7 6958.5 6975.9 6880.9 6918.3 6927.9 7006.4 7026.6 7177.7 7346.8 7347.8 7355.0 7358.6 6744.2 6764.1 6764.4 6778.5 6780.9 6383.7 6737.5 6747.5 6749.4 6781.1 08:19:06 INFO - 2016-01-27 08:19:06,134 DEBUG : BROWSER_OUTPUT: 08:19:06 INFO - 2016-01-27 08:19:06,134 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5429.34 Median:6167.96 stddev:1626.55 (26.4%) stddev-sans-first:1585.49 08:19:06 INFO - 2016-01-27 08:19:06,135 DEBUG : BROWSER_OUTPUT: Values: 332.8 333.6 340.1 352.3 359.3 6084.5 6090.7 6097.8 6112.8 6161.3 5788.2 5864.2 5930.4 6068.5 6094.1 4724.0 4730.5 4742.1 4743.9 4756.3 4465.8 4749.8 4750.1 4761.1 4767.9 4503.9 4583.7 4689.1 4701.7 4706.4 4667.8 4679.6 4688.3 4698.9 4705.9 4161.0 4168.1 4169.2 4173.2 4173.2 5766.0 5825.7 5833.6 5839.3 5848.9 5440.5 5442.0 5442.2 5450.2 5450.6 6472.0 6474.2 6476.9 6478.8 6483.4 4536.9 4542.6 4545.7 4547.9 4561.2 6549.6 6612.4 6692.5 6695.4 6702.5 4640.8 4641.2 4646.4 4647.1 4656.6 4698.6 4701.1 4706.7 4712.9 4714.1 5408.5 5416.9 5421.7 5423.9 5423.9 6285.9 6512.0 6520.7 6521.1 6528.3 1613.9 1642.7 1644.2 1645.4 1646.4 1061.3 1080.4 1089.8 1106.9 1136.3 6267.0 6283.4 6284.3 6288.3 6294.0 6418.9 6628.9 6629.5 6635.3 6638.9 6153.8 6174.7 6315.7 6347.8 6354.3 3574.2 3624.1 3625.7 3627.1 3628.6 6734.0 6901.4 6906.1 6910.2 6915.8 6842.4 6848.6 6869.1 6897.1 6900.7 6885.5 6911.7 6914.6 6916.1 6920.7 6602.3 6656.2 6663.7 6694.0 6699.7 6651.9 6667.1 6667.2 6672.4 6676.7 6899.3 6904.0 6909.1 6913.0 6916.9 4505.6 4566.3 4572.8 4574.3 4575.0 6649.1 6701.1 6707.2 6708.6 6711.6 6491.2 6492.7 6498.3 6500.2 6504.3 6391.7 6485.7 6486.8 6488.5 6496.9 6436.2 6440.0 6445.5 6451.8 6452.5 6510.2 6553.4 6563.2 6563.3 6573.5 6219.6 6323.6 6351.7 6357.8 6389.6 08:19:06 INFO - 2016-01-27 08:19:06,136 DEBUG : BROWSER_OUTPUT: 08:19:06 INFO - 2016-01-27 08:19:06,136 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14128.57 Median:45605.27 stddev:16896.72 (37.0%) stddev-sans-first:16914.33 08:19:06 INFO - 2016-01-27 08:19:06,137 DEBUG : BROWSER_OUTPUT: Values: 767.4 770.8 821.5 909.6 940.6 12210.6 12412.3 12436.7 12449.5 12458.4 11827.3 11897.6 11987.3 12027.5 12074.5 884.6 894.0 896.9 903.7 904.1 880.5 881.7 887.4 887.8 888.8 922.6 933.4 933.9 934.3 934.8 924.6 927.2 927.4 927.8 928.1 690.8 692.9 693.4 694.3 694.8 11164.4 11181.2 11197.0 11256.0 11274.7 10753.8 10993.4 11056.8 11117.2 11279.7 9116.3 9191.8 9203.9 9252.2 9487.1 3740.3 3800.9 3802.9 3813.6 3823.7 43115.8 45035.8 46174.8 54144.0 54368.9 10001.2 10302.1 10331.6 10474.8 10511.4 16039.7 16273.5 19562.9 19807.3 19810.4 7367.8 7401.3 7438.7 7617.3 7621.1 4140.9 4163.3 4213.8 4287.3 4337.7 3169.7 3183.2 3184.3 3188.2 3190.1 559.7 568.7 569.1 571.0 581.6 626.6 646.9 654.7 681.3 706.9 8550.4 8743.0 8858.8 8864.8 8907.2 538.5 568.5 647.3 650.0 673.7 4078.8 4092.0 4205.8 4230.9 4261.7 60276.1 60787.1 61221.4 62149.7 62535.4 53210.7 53571.2 53931.0 54098.5 54613.0 48723.8 49136.8 49141.0 49293.0 49311.5 7959.8 8075.6 8103.8 8264.1 8482.0 33572.7 33865.3 37671.4 38552.6 38563.8 47825.3 48245.5 48677.0 48952.0 49171.3 11370.7 11966.9 12020.9 12173.3 12235.9 11453.5 12404.4 13711.8 13825.4 13998.9 10533.0 10550.6 10890.9 10925.8 11323.8 11355.6 11498.0 11932.3 11932.4 12190.0 10365.3 10647.2 10753.7 10863.9 11472.2 10184.3 10498.8 10705.1 10930.3 10947.6 9865.0 10173.6 10173.7 10243.1 10377.3 08:19:06 INFO - 2016-01-27 08:19:06,138 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:19:06 INFO - 2016-01-27 08:19:06,138 DEBUG : BROWSER_OUTPUT: 08:19:06 INFO - 2016-01-27 08:19:06,723 INFO : Browser exited with error code: 0 08:19:06 INFO - 2016-01-27 08:19:06,744 INFO : Completed test dromaeo_css (00:18:30) 08:19:06 INFO - 2016-01-27 08:19:06,744 INFO : Starting test kraken 08:19:06 INFO - 2016-01-27 08:19:06,744 DEBUG : operating with platform_type : linux_ 08:19:06 INFO - 2016-01-27 08:19:06,744 INFO : Initialising browser for kraken test... 08:19:06 INFO - 2016-01-27 08:19:06,750 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpNuS1NJ/profile http://localhost:40328/getInfo.html 08:19:10 INFO - 2016-01-27 08:19:10,719 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:19:10 INFO - 2016-01-27 08:19:10,719 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:19:10 INFO - 2016-01-27 08:19:10,719 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:19:10 INFO - 2016-01-27 08:19:10,719 DEBUG : BROWSER_OUTPUT: __metrics 08:19:10 INFO - 2016-01-27 08:19:10,876 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:19:11 INFO - 2016-01-27 08:19:11,869 INFO : Browser initialized. 08:19:11 INFO - 2016-01-27 08:19:11,869 INFO : Running cycle 1/1 for kraken test... 08:19:11 INFO - 2016-01-27 08:19:11,869 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpNuS1NJ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:19:23 INFO - 2016-01-27 08:19:23,645 DEBUG : BROWSER_OUTPUT: RSS: Main: 133992448 08:19:23 INFO - 2016-01-27 08:19:23,645 DEBUG : BROWSER_OUTPUT: 08:19:26 INFO - 2016-01-27 08:19:26,073 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40328/tests/kraken/driver.html?testName=audio-beat-detection) 08:19:26 INFO - 2016-01-27 08:19:26,668 DEBUG : BROWSER_OUTPUT: RSS: Main: 165859328 08:19:26 INFO - 2016-01-27 08:19:26,668 DEBUG : BROWSER_OUTPUT: 08:19:29 INFO - 2016-01-27 08:19:29,218 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40328/tests/kraken/driver.html?testName=audio-dft) 08:19:29 INFO - 2016-01-27 08:19:29,784 DEBUG : BROWSER_OUTPUT: RSS: Main: 163368960 08:19:29 INFO - 2016-01-27 08:19:29,784 DEBUG : BROWSER_OUTPUT: 08:19:32 INFO - 2016-01-27 08:19:32,568 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40328/tests/kraken/driver.html?testName=audio-fft) 08:19:33 INFO - 2016-01-27 08:19:33,142 DEBUG : BROWSER_OUTPUT: RSS: Main: 161959936 08:19:33 INFO - 2016-01-27 08:19:33,142 DEBUG : BROWSER_OUTPUT: 08:19:35 INFO - 2016-01-27 08:19:35,119 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40328/tests/kraken/driver.html?testName=audio-oscillator) 08:19:35 INFO - 2016-01-27 08:19:35,685 DEBUG : BROWSER_OUTPUT: RSS: Main: 161292288 08:19:35 INFO - 2016-01-27 08:19:35,685 DEBUG : BROWSER_OUTPUT: 08:19:37 INFO - 2016-01-27 08:19:37,862 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40328/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:19:38 INFO - 2016-01-27 08:19:38,416 DEBUG : BROWSER_OUTPUT: RSS: Main: 159776768 08:19:38 INFO - 2016-01-27 08:19:38,416 DEBUG : BROWSER_OUTPUT: 08:19:41 INFO - 2016-01-27 08:19:41,246 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40328/tests/kraken/driver.html?testName=imaging-darkroom) 08:19:41 INFO - 2016-01-27 08:19:41,813 DEBUG : BROWSER_OUTPUT: RSS: Main: 159375360 08:19:41 INFO - 2016-01-27 08:19:41,814 DEBUG : BROWSER_OUTPUT: 08:19:45 INFO - 2016-01-27 08:19:45,149 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40328/tests/kraken/driver.html?testName=imaging-desaturate) 08:19:45 INFO - 2016-01-27 08:19:45,730 DEBUG : BROWSER_OUTPUT: RSS: Main: 159006720 08:19:45 INFO - 2016-01-27 08:19:45,730 DEBUG : BROWSER_OUTPUT: 08:19:48 INFO - 2016-01-27 08:19:48,447 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40328/tests/kraken/driver.html?testName=json-parse-financial) 08:19:49 INFO - 2016-01-27 08:19:49,013 DEBUG : BROWSER_OUTPUT: RSS: Main: 160817152 08:19:49 INFO - 2016-01-27 08:19:49,013 DEBUG : BROWSER_OUTPUT: 08:19:50 INFO - 2016-01-27 08:19:50,530 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40328/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:19:51 INFO - 2016-01-27 08:19:51,091 DEBUG : BROWSER_OUTPUT: RSS: Main: 166715392 08:19:51 INFO - 2016-01-27 08:19:51,091 DEBUG : BROWSER_OUTPUT: 08:19:52 INFO - 2016-01-27 08:19:52,861 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40328/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:19:53 INFO - 2016-01-27 08:19:53,451 DEBUG : BROWSER_OUTPUT: RSS: Main: 164737024 08:19:53 INFO - 2016-01-27 08:19:53,451 DEBUG : BROWSER_OUTPUT: 08:19:55 INFO - 2016-01-27 08:19:55,387 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40328/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:19:55 INFO - 2016-01-27 08:19:55,968 DEBUG : BROWSER_OUTPUT: RSS: Main: 170446848 08:19:55 INFO - 2016-01-27 08:19:55,968 DEBUG : BROWSER_OUTPUT: 08:19:58 INFO - 2016-01-27 08:19:58,309 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40328/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:19:58 INFO - 2016-01-27 08:19:58,883 DEBUG : BROWSER_OUTPUT: RSS: Main: 175702016 08:19:58 INFO - 2016-01-27 08:19:58,883 DEBUG : BROWSER_OUTPUT: 08:20:01 INFO - 2016-01-27 08:20:01,424 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40328/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:20:01 INFO - 2016-01-27 08:20:01,991 DEBUG : BROWSER_OUTPUT: RSS: Main: 174657536 08:20:01 INFO - 2016-01-27 08:20:01,991 DEBUG : BROWSER_OUTPUT: 08:20:03 INFO - 2016-01-27 08:20:03,628 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40328/tests/kraken/driver.html?testName=ai-astar) 08:20:03 INFO - 2016-01-27 08:20:03,886 DEBUG : BROWSER_OUTPUT: RSS: Main: 232583168 08:20:03 INFO - 2016-01-27 08:20:03,886 DEBUG : BROWSER_OUTPUT: 08:20:03 INFO - 2016-01-27 08:20:03,886 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:20:03 INFO - 2016-01-27 08:20:03,886 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:20:03 INFO - 2016-01-27 08:20:03,886 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:20:03 INFO - 2016-01-27 08:20:03,886 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:20:03 INFO - 2016-01-27 08:20:03,886 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;109;103;109;110;110;100;110;109;110 08:20:03 INFO - 2016-01-27 08:20:03,887 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;120;145;156;121;148;148;122;163;124 08:20:03 INFO - 2016-01-27 08:20:03,887 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;161;159;159;161;159;161;160;159 08:20:03 INFO - 2016-01-27 08:20:03,887 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;71;72;154;73;71;72;71;163;72 08:20:03 INFO - 2016-01-27 08:20:03,887 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;94;148;91;92;92;135;91;92;139 08:20:03 INFO - 2016-01-27 08:20:03,887 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;110;109;109;110;109;109 08:20:03 INFO - 2016-01-27 08:20:03,887 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;148;147;145;146;145;145;146;145 08:20:03 INFO - 2016-01-27 08:20:03,887 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;95;95;95;95;95;96;95;95 08:20:03 INFO - 2016-01-27 08:20:03,887 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;57;58;57;58;57;56;57;55;54 08:20:03 INFO - 2016-01-27 08:20:03,887 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;65;64;64;65;65;65;66;65;65;65 08:20:03 INFO - 2016-01-27 08:20:03,887 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;79;79;78;78;86;79;78;79;78 08:20:03 INFO - 2016-01-27 08:20:03,888 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;117;108;116;116;112;113;119;112;115;119 08:20:03 INFO - 2016-01-27 08:20:03,888 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;133;144;142;140;138;142;152;145;144;145 08:20:03 INFO - 2016-01-27 08:20:03,888 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;65;64;66;65;64;65;63;62;66 08:20:03 INFO - 2016-01-27 08:20:03,888 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:20:03 INFO - 2016-01-27 08:20:03,888 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:20:03 INFO - 2016-01-27 08:20:03,888 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,910 08:20:03 INFO - 2016-01-27 08:20:03,888 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:20:03 INFO - 2016-01-27 08:20:03,888 DEBUG : BROWSER_OUTPUT: __startTimestamp1453911603880__endTimestamp 08:20:03 INFO - 2016-01-27 08:20:03,889 DEBUG : BROWSER_OUTPUT: 08:20:03 INFO - 2016-01-27 08:20:03,889 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:20:03 INFO - 2016-01-27 08:20:03,889 DEBUG : BROWSER_OUTPUT: Number of tests: 14 08:20:03 INFO - 2016-01-27 08:20:03,889 DEBUG : BROWSER_OUTPUT: 08:20:03 INFO - 2016-01-27 08:20:03,889 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.80 Median:109.50 stddev:3.46 (3.2%) stddev-sans-first:3.67 08:20:03 INFO - 2016-01-27 08:20:03,889 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 103.0 109.0 110.0 110.0 100.0 110.0 109.0 110.0 08:20:03 INFO - 2016-01-27 08:20:03,889 DEBUG : BROWSER_OUTPUT: 08:20:03 INFO - 2016-01-27 08:20:03,889 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:139.30 Median:147.00 stddev:16.02 (10.9%) stddev-sans-first:16.81 08:20:03 INFO - 2016-01-27 08:20:03,889 DEBUG : BROWSER_OUTPUT: Values: 146.0 120.0 145.0 156.0 121.0 148.0 148.0 122.0 163.0 124.0 08:20:03 INFO - 2016-01-27 08:20:03,889 DEBUG : BROWSER_OUTPUT: 08:20:03 INFO - 2016-01-27 08:20:03,889 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.90 Median:160.00 stddev:0.88 (0.5%) stddev-sans-first:0.93 08:20:03 INFO - 2016-01-27 08:20:03,889 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 161.0 159.0 159.0 161.0 159.0 161.0 160.0 159.0 08:20:03 INFO - 2016-01-27 08:20:03,889 DEBUG : BROWSER_OUTPUT: 08:20:03 INFO - 2016-01-27 08:20:03,889 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.90 Median:71.50 stddev:36.75 (51.4%) stddev-sans-first:38.34 08:20:03 INFO - 2016-01-27 08:20:03,889 DEBUG : BROWSER_OUTPUT: Values: 70.0 71.0 72.0 154.0 73.0 71.0 72.0 71.0 163.0 72.0 08:20:03 INFO - 2016-01-27 08:20:03,889 DEBUG : BROWSER_OUTPUT: 08:20:03 INFO - 2016-01-27 08:20:03,890 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.70 Median:92.00 stddev:23.66 (25.7%) stddev-sans-first:24.58 08:20:03 INFO - 2016-01-27 08:20:03,890 DEBUG : BROWSER_OUTPUT: Values: 93.0 94.0 148.0 91.0 92.0 92.0 135.0 91.0 92.0 139.0 08:20:03 INFO - 2016-01-27 08:20:03,890 DEBUG : BROWSER_OUTPUT: 08:20:03 INFO - 2016-01-27 08:20:03,890 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 08:20:03 INFO - 2016-01-27 08:20:03,890 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 110.0 109.0 109.0 110.0 109.0 109.0 08:20:03 INFO - 2016-01-27 08:20:03,890 DEBUG : BROWSER_OUTPUT: 08:20:03 INFO - 2016-01-27 08:20:03,890 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:1.03 (0.7%) stddev-sans-first:1.05 08:20:03 INFO - 2016-01-27 08:20:03,890 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 148.0 147.0 145.0 146.0 145.0 145.0 146.0 145.0 08:20:03 INFO - 2016-01-27 08:20:03,890 DEBUG : BROWSER_OUTPUT: 08:20:03 INFO - 2016-01-27 08:20:03,890 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 08:20:03 INFO - 2016-01-27 08:20:03,890 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 08:20:03 INFO - 2016-01-27 08:20:03,890 DEBUG : BROWSER_OUTPUT: 08:20:03 INFO - 2016-01-27 08:20:03,890 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.50 Median:57.00 stddev:1.27 (2.2%) stddev-sans-first:1.33 08:20:03 INFO - 2016-01-27 08:20:03,890 DEBUG : BROWSER_OUTPUT: Values: 56.0 57.0 58.0 57.0 58.0 57.0 56.0 57.0 55.0 54.0 08:20:03 INFO - 2016-01-27 08:20:03,890 DEBUG : BROWSER_OUTPUT: 08:20:03 INFO - 2016-01-27 08:20:03,890 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.90 Median:65.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 08:20:03 INFO - 2016-01-27 08:20:03,891 DEBUG : BROWSER_OUTPUT: Values: 65.0 64.0 64.0 65.0 65.0 65.0 66.0 65.0 65.0 65.0 08:20:03 INFO - 2016-01-27 08:20:03,891 DEBUG : BROWSER_OUTPUT: 08:20:03 INFO - 2016-01-27 08:20:03,891 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.10 Median:79.00 stddev:2.51 (3.2%) stddev-sans-first:2.55 08:20:03 INFO - 2016-01-27 08:20:03,891 DEBUG : BROWSER_OUTPUT: Values: 77.0 79.0 79.0 78.0 78.0 86.0 79.0 78.0 79.0 78.0 08:20:03 INFO - 2016-01-27 08:20:03,891 DEBUG : BROWSER_OUTPUT: 08:20:03 INFO - 2016-01-27 08:20:03,891 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:114.70 Median:116.00 stddev:3.47 (3.0%) stddev-sans-first:3.57 08:20:03 INFO - 2016-01-27 08:20:03,891 DEBUG : BROWSER_OUTPUT: Values: 117.0 108.0 116.0 116.0 112.0 113.0 119.0 112.0 115.0 119.0 08:20:03 INFO - 2016-01-27 08:20:03,891 DEBUG : BROWSER_OUTPUT: 08:20:03 INFO - 2016-01-27 08:20:03,891 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.50 Median:144.00 stddev:4.99 (3.5%) stddev-sans-first:3.94 08:20:03 INFO - 2016-01-27 08:20:03,891 DEBUG : BROWSER_OUTPUT: Values: 133.0 144.0 142.0 140.0 138.0 142.0 152.0 145.0 144.0 145.0 08:20:03 INFO - 2016-01-27 08:20:03,891 DEBUG : BROWSER_OUTPUT: 08:20:03 INFO - 2016-01-27 08:20:03,891 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:65.00 stddev:1.26 (1.9%) stddev-sans-first:1.33 08:20:03 INFO - 2016-01-27 08:20:03,891 DEBUG : BROWSER_OUTPUT: Values: 64.0 65.0 64.0 66.0 65.0 64.0 65.0 63.0 62.0 66.0 08:20:03 INFO - 2016-01-27 08:20:03,891 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:20:03 INFO - 2016-01-27 08:20:03,891 DEBUG : BROWSER_OUTPUT: 08:20:04 INFO - 2016-01-27 08:20:04,563 INFO : Browser exited with error code: 0 08:20:04 INFO - 2016-01-27 08:20:04,615 INFO : Completed test kraken (00:00:57) 08:20:04 INFO - 2016-01-27 08:20:04,616 INFO : Starting test v8_7 08:20:04 INFO - 2016-01-27 08:20:04,616 DEBUG : operating with platform_type : linux_ 08:20:04 INFO - 2016-01-27 08:20:04,616 INFO : Initialising browser for v8_7 test... 08:20:04 INFO - 2016-01-27 08:20:04,622 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp81r8ub/profile http://localhost:40328/getInfo.html 08:20:08 INFO - 2016-01-27 08:20:08,900 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:20:08 INFO - 2016-01-27 08:20:08,900 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:20:08 INFO - 2016-01-27 08:20:08,901 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:20:08 INFO - 2016-01-27 08:20:08,901 DEBUG : BROWSER_OUTPUT: __metrics 08:20:09 INFO - 2016-01-27 08:20:09,065 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:20:09 INFO - 2016-01-27 08:20:09,790 INFO : Browser initialized. 08:20:09 INFO - 2016-01-27 08:20:09,791 INFO : Running cycle 1/1 for v8_7 test... 08:20:09 INFO - 2016-01-27 08:20:09,791 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp81r8ub/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:20:21 INFO - 2016-01-27 08:20:21,526 DEBUG : BROWSER_OUTPUT: RSS: Main: 134094848 08:20:21 INFO - 2016-01-27 08:20:21,526 DEBUG : BROWSER_OUTPUT: 08:20:42 INFO - 2016-01-27 08:20:42,891 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40328/tests/v8_7/run.html (next: http://localhost:40328/tests/v8_7/run.html) 08:20:43 INFO - 2016-01-27 08:20:43,141 DEBUG : BROWSER_OUTPUT: RSS: Main: 425992192 08:20:43 INFO - 2016-01-27 08:20:43,141 DEBUG : BROWSER_OUTPUT: 08:20:43 INFO - 2016-01-27 08:20:43,141 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:20:43 INFO - 2016-01-27 08:20:43,141 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:20:43 INFO - 2016-01-27 08:20:43,141 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:20:43 INFO - 2016-01-27 08:20:43,142 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:20:43 INFO - 2016-01-27 08:20:43,142 DEBUG : BROWSER_OUTPUT: |0;Richards;168.94745734076702 08:20:43 INFO - 2016-01-27 08:20:43,142 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;166.77785190126752 08:20:43 INFO - 2016-01-27 08:20:43,142 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.473962684489 08:20:43 INFO - 2016-01-27 08:20:43,142 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5133.333333333333 08:20:43 INFO - 2016-01-27 08:20:43,142 DEBUG : BROWSER_OUTPUT: |4;RayTrace;954.1984732824427 08:20:43 INFO - 2016-01-27 08:20:43,142 DEBUG : BROWSER_OUTPUT: |5;Earley;544.9591280653951 08:20:43 INFO - 2016-01-27 08:20:43,142 DEBUG : BROWSER_OUTPUT: |6;Boyer;10536.842105263158 08:20:43 INFO - 2016-01-27 08:20:43,143 DEBUG : BROWSER_OUTPUT: |7;RegExp;24285.714285714286 08:20:43 INFO - 2016-01-27 08:20:43,143 DEBUG : BROWSER_OUTPUT: |8;Splay;516.905737704918 08:20:43 INFO - 2016-01-27 08:20:43,143 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4882.926829268293 08:20:43 INFO - 2016-01-27 08:20:43,143 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:20:43 INFO - 2016-01-27 08:20:43,143 DEBUG : BROWSER_OUTPUT: __startTimestamp1453911643138__endTimestamp 08:20:43 INFO - 2016-01-27 08:20:43,143 DEBUG : BROWSER_OUTPUT: 08:20:43 INFO - 2016-01-27 08:20:43,143 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:20:43 INFO - 2016-01-27 08:20:43,144 DEBUG : BROWSER_OUTPUT: Number of tests: 10 08:20:43 INFO - 2016-01-27 08:20:43,144 DEBUG : BROWSER_OUTPUT: 08:20:43 INFO - 2016-01-27 08:20:43,144 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:168.95 Median:168.95 stddev:0.00 (0.0%) 08:20:43 INFO - 2016-01-27 08:20:43,144 DEBUG : BROWSER_OUTPUT: Values: 168.9 08:20:43 INFO - 2016-01-27 08:20:43,144 DEBUG : BROWSER_OUTPUT: 08:20:43 INFO - 2016-01-27 08:20:43,144 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:166.78 Median:166.78 stddev:0.00 (0.0%) 08:20:43 INFO - 2016-01-27 08:20:43,144 DEBUG : BROWSER_OUTPUT: Values: 166.8 08:20:43 INFO - 2016-01-27 08:20:43,145 DEBUG : BROWSER_OUTPUT: 08:20:43 INFO - 2016-01-27 08:20:43,145 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.47 Median:278.47 stddev:0.00 (0.0%) 08:20:43 INFO - 2016-01-27 08:20:43,145 DEBUG : BROWSER_OUTPUT: Values: 278.5 08:20:43 INFO - 2016-01-27 08:20:43,145 DEBUG : BROWSER_OUTPUT: 08:20:43 INFO - 2016-01-27 08:20:43,145 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5133.33 Median:5133.33 stddev:0.00 (0.0%) 08:20:43 INFO - 2016-01-27 08:20:43,145 DEBUG : BROWSER_OUTPUT: Values: 5133.3 08:20:43 INFO - 2016-01-27 08:20:43,145 DEBUG : BROWSER_OUTPUT: 08:20:43 INFO - 2016-01-27 08:20:43,145 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:954.20 Median:954.20 stddev:0.00 (0.0%) 08:20:43 INFO - 2016-01-27 08:20:43,146 DEBUG : BROWSER_OUTPUT: Values: 954.2 08:20:43 INFO - 2016-01-27 08:20:43,146 DEBUG : BROWSER_OUTPUT: 08:20:43 INFO - 2016-01-27 08:20:43,146 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:544.96 Median:544.96 stddev:0.00 (0.0%) 08:20:43 INFO - 2016-01-27 08:20:43,146 DEBUG : BROWSER_OUTPUT: Values: 545.0 08:20:43 INFO - 2016-01-27 08:20:43,146 DEBUG : BROWSER_OUTPUT: 08:20:43 INFO - 2016-01-27 08:20:43,146 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10536.84 Median:10536.84 stddev:0.00 (0.0%) 08:20:43 INFO - 2016-01-27 08:20:43,146 DEBUG : BROWSER_OUTPUT: Values: 10536.8 08:20:43 INFO - 2016-01-27 08:20:43,147 DEBUG : BROWSER_OUTPUT: 08:20:43 INFO - 2016-01-27 08:20:43,147 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24285.71 Median:24285.71 stddev:0.00 (0.0%) 08:20:43 INFO - 2016-01-27 08:20:43,147 DEBUG : BROWSER_OUTPUT: Values: 24285.7 08:20:43 INFO - 2016-01-27 08:20:43,147 DEBUG : BROWSER_OUTPUT: 08:20:43 INFO - 2016-01-27 08:20:43,147 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:516.91 Median:516.91 stddev:0.00 (0.0%) 08:20:43 INFO - 2016-01-27 08:20:43,147 DEBUG : BROWSER_OUTPUT: Values: 516.9 08:20:43 INFO - 2016-01-27 08:20:43,147 DEBUG : BROWSER_OUTPUT: 08:20:43 INFO - 2016-01-27 08:20:43,147 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4882.93 Median:4882.93 stddev:0.00 (0.0%) 08:20:43 INFO - 2016-01-27 08:20:43,147 DEBUG : BROWSER_OUTPUT: Values: 4882.9 08:20:43 INFO - 2016-01-27 08:20:43,147 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:20:43 INFO - 2016-01-27 08:20:43,147 DEBUG : BROWSER_OUTPUT: 08:20:43 INFO - 2016-01-27 08:20:43,843 INFO : Browser exited with error code: 0 08:20:43 INFO - 2016-01-27 08:20:43,860 INFO : Completed test v8_7 (00:00:39) 08:20:44 INFO - 2016-01-27 08:20:44,155 INFO : Completed test suite (00:20:07) 08:20:44 INFO - 2016-01-27 08:20:44,155 DEBUG : Working with test: dromaeo_css 08:20:44 INFO - 2016-01-27 08:20:44,155 DEBUG : Generating results file: dromaeo_css 08:20:44 INFO - 2016-01-27 08:20:44,156 DEBUG : Working with test: kraken 08:20:44 INFO - 2016-01-27 08:20:44,156 DEBUG : Generating results file: kraken 08:20:44 INFO - 2016-01-27 08:20:44,157 INFO : javascript benchmark 08:20:44 INFO - 2016-01-27 08:20:44,157 DEBUG : Working with test: v8_7 08:20:44 INFO - 2016-01-27 08:20:44,157 DEBUG : Generating results file: v8_7 08:20:44 INFO - 2016-01-27 08:20:44,157 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:20:44 INFO - 2016-01-27 08:20:44,212 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 08:20:44 INFO - 2016-01-27 08:20:44,212 DEBUG : process_Request line: dromaeo_css 7217.77 graph.html#tests=[[72,53,35]] 08:20:44 INFO - 2016-01-27 08:20:44,212 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:20:44 INFO - 2016-01-27 08:20:44,333 DEBUG : process_Request line: kraken 1474.90 graph.html#tests=[[232,53,35]] 08:20:44 INFO - 2016-01-27 08:20:44,333 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:20:44 INFO - 2016-01-27 08:20:44,435 DEBUG : process_Request line: v8_7 23241.67 graph.html#tests=[[230,53,35]] 08:20:44 INFO - 2016-01-27 08:20:44,436 INFO : javascript benchmark 08:20:44 INFO - 2016-01-27 08:20:44,440 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3269.1663625775423, 3386.4243078676623, 3420.6579342065797, 3428.4857271409287, 3460.3425349183644, 5562.443755624436, 5596.888062238752, 5635.351934527531, 5641.887162256752, 5654.547636189101, 5600.775968961247, 5659.151127330892, 5669.433056694328, 5676.914846277309, 5687.943120568793, 4860.441049279325, 5132.281480592711, 5153.072446959546, 5207.088759467097, 5232.843014709546, 4956.67781594197, 5037.143685573443, 5104.64267501275, 5118.769655365499, 5205.765740541685, 5062.519060689228, 5071.847844564651, 5072.0870243356185, 5072.4166720827025, 5089.541941225289, 4833.250846118857, 5019.924701129486, 5046.722430266371, 5104.66819656723, 5125.974370128144, 4967.081089998362, 4972.080165169419, 5069.44236134025, 5073.340465739467, 5081.872953176163, 5354.223637572569, 5379.542738867201, 5384.838454846322, 5386.245925570423, 5401.216823560561, 5709.714514274311, 5820.708964551755, 5967.433093856077, 5968.0, 5972.552058595623, 5901.409859014064, 5985.6408615483215, 5987.670678112702, 5996.850078748066, 6000.18997435345, 5551.028570000234, 5619.381867994517, 5625.353084395265, 5628.690422026787, 5632.577556683224, 5800.738966746507, 5861.472467477947, 5925.140854576062, 6017.0, 6095.634261944298, 5883.0, 5887.499562537147, 5918.704064796829, 5926.792562260386, 5933.495652869555, 5996.610220335664, 6006.219191505076, 6013.488853447506, 6016.518678505709, 6018.337982821886, 5628.577856660767, 5705.4579814917515, 5715.942840571624, 5790.768369265266, 5969.671668058211, 5965.642061476326, 6004.3395226525045, 6010.969945150334, 6012.158297838306, 6023.427774361428, 4583.72497650142, 4585.770711464414, 4587.059652771174, 4601.884952876205, 4611.815527378934, 3977.7812220327583, 3979.7612143271494, 4070.633642972147, 4071.0, 4151.605597468236, 5664.54683625309, 5664.660120392789, 5714.657120572696, 5723.942760572424, 5725.656460612377, 5981.5214782817275, 6010.909836352458, 6030.065339872327, 6042.667653279024, 6048.455638992512, 5414.106672399089, 5530.446955304437, 5655.575831812548, 5696.686682232517, 5757.021306377926, 5176.301199338116, 5189.247559103909, 5190.896182076337, 5198.662086964335, 5221.817236396784, 5907.497862681635, 6075.362086980804, 6103.481204097613, 6105.877882442326, 6107.908381374283, 5780.797672081457, 5874.853128671646, 5905.43898329658, 5906.143609176559, 5920.79277225295, 6047.48596369296, 6083.8174854754425, 6093.512518998381, 6094.603850749687, 6152.600080994721, 5488.917666235089, 5916.73374698131, 5922.0, 5924.407559244041, 5925.881482370414, 5700.087985922232, 5786.4213578643485, 5883.705814709333, 5893.764249430146, 5932.762689492372, 5830.358660547421, 5895.764169433346, 5913.645181289136, 5921.881562368642, 5924.674142922181, 5574.581906356958, 5688.40271771458, 5856.238688970407, 5906.70466476683, 5922.881542369215, 5688.11834165705, 5777.422257774189, 5779.133130030529, 5781.624194427349, 5808.970955145197, 5834.38738932406, 5865.706714664165, 5873.353931067493, 5874.794382196603, 5881.617694849821, 5477.616566840283, 5577.0, 5584.776608935597, 5738.254026976467, 5739.827805165852], "unit": "ms", "name": "dojo.html", "value": 5486.802517251661}, {"lowerIsBetter": false, "replicates": [597.286242939687, 624.2883113250892, 631.8546734251122, 646.324590802611, 649.6134799794124, 32639.67360326396, 40708.18583628326, 41237.58762412375, 41346.17307653845, 41445.58554414455, 45868.77065614676, 46327.536724632744, 46350.768246158805, 46424.76787616057, 46441.30338044924, 8037.919620803805, 8137.511749295061, 8891.377603567753, 9122.771930701752, 9138.0405057469, 10743.78512429751, 10925.453727313643, 11010.944945275263, 11014.614488492904, 11020.944895275512, 10676.519556619933, 10730.034296913276, 10798.406087665175, 10799.2440529163, 10806.08148307391, 10652.78694426111, 10737.194710396674, 10759.462026898664, 10763.3541987481, 10771.946140269287, 10445.164386849034, 10559.313644613076, 10583.10043646291, 10585.788284234271, 10591.576336946513, 42512.362314565304, 42565.78717106426, 43019.56980430187, 43056.78471607622, 43101.0, 3245.6754324567355, 3379.205886616643, 3673.669369756708, 3978.781167035812, 4029.263922759164, 32133.51799723006, 32510.0, 32521.0, 32589.185270368194, 32596.511052334234, 4126.731762435432, 4156.501219853607, 4187.790610469464, 4205.684573656988, 4210.221109094797, 283383.58308208536, 284025.0, 284124.0, 284733.0, 284741.0, 60248.39751602516, 61629.0, 61780.0, 62119.68940155361, 62174.378256216856, 72401.63799180971, 73607.26392736004, 73929.26070739332, 73936.260637394, 74075.62962185263, 18018.36935707244, 18183.545411364823, 18272.725909111374, 18285.360012399502, 18309.633807324044, 14135.64660883486, 14150.646233844236, 14231.359588818528, 14236.43054277838, 14238.359273832702, 20430.489237768878, 20566.177352905717, 20606.690899636516, 20618.07218675133, 20658.58682826335, 7585.962070189614, 7621.923780762232, 7724.382049436032, 7760.34037106841, 7811.609419529001, 1041.2815157541272, 1070.4273213830563, 1070.619929924881, 1071.8767341755797, 1090.2858627598778, 29750.0, 29946.25134371658, 30208.848955755082, 30848.0, 30925.845370773, 1146.4668928948079, 1162.8662703789173, 1167.8656954450346, 1179.121554441929, 1250.9249445033327, 13832.377543010589, 15766.0, 15824.367025319088, 15865.603359916095, 16087.758683619755, 322980.0, 326135.0, 326136.0, 326380.0, 326789.0, 52580.73709631504, 54078.72960635251, 54131.45868541264, 54653.0, 55549.722251388484, 1427.635952832042, 1439.9136051836924, 1445.768677011694, 1453.6293245222612, 1460.5618314505605, 26753.732462675125, 27021.248029597726, 29056.0, 29143.271418214714, 29443.558346624388, 45820.0, 46336.76831615955, 46368.30447543222, 46374.76812615915, 46413.0, 243210.0, 243842.0, 243951.0, 244067.0, 245110.0, 33386.49920251247, 33553.49669754907, 33683.66316336805, 34052.31895362029, 34182.487262691575, 43215.13569728525, 43882.12235755331, 44204.55795442133, 44432.55567444284, 44552.331715023654, 36461.817690911375, 36494.27011459809, 37479.43780843235, 37718.62281377261, 39134.608653914234, 36813.81593092017, 38417.03471292397, 38640.0, 38770.80614596909, 38794.03014924649, 36803.26393472169, 37578.0, 37649.62350376571, 37671.623283766814, 37886.242275154895, 32521.186970325933, 32629.673703262662, 32766.0, 33205.0, 34082.48876266809, 26110.0, 26197.345066373495, 26376.472470550867, 26423.867880660473, 26564.468710626064], "unit": "ms", "name": "ext.html", "value": 19829.576614749505}, {"lowerIsBetter": false, "replicates": [384.2545461804098, 386.7408836079826, 391.4656493885845, 391.481287294335, 392.6917369864657, 17413.825861741378, 17654.205560749764, 18595.535111622215, 18779.71830422543, 18842.434726958196, 15345.539633810975, 15778.501042400982, 17709.7343539847, 18279.81720182801, 18417.0, 5434.538064264522, 5456.290682211307, 5533.667979921198, 5534.723263836812, 5560.277163968678, 5501.0, 5548.639338442988, 5552.305961754781, 5566.165075238706, 5586.8603284918, 5440.292761940962, 5527.778888844461, 5562.748381614125, 5572.721363931808, 5572.749226284827, 5516.393196748354, 5536.916946245809, 5541.5289700375515, 5543.6119471637, 5545.306836645419, 5003.949960500421, 5164.8967020659375, 5286.180642000496, 5329.147336426192, 5330.893382132374, 14296.857031429656, 15514.5345639631, 21189.046492907855, 22609.547809043892, 22779.0, 2325.4883925536524, 2352.0709319818575, 2356.7289761677284, 2442.8656423896678, 2480.0203919451715, 9761.463119528424, 10196.490175491195, 10371.325863818827, 10609.628662996758, 10667.359958402521, 1853.721941708741, 1887.5941672540332, 1922.423273018103, 1966.3314473079079, 1976.723258743777, 64758.67620661914, 65665.67167164182, 67480.6625966867, 68232.0, 68376.31623683749, 11319.37743424103, 11323.773524529463, 11343.886561134283, 11350.829737553944, 11354.602588909514, 14760.188189649672, 14924.62688432798, 14924.850751492564, 14945.925270373797, 14959.0, 7254.383377412873, 7448.590327532038, 7456.40348772097, 8376.53929033909, 8415.11641277657, 7017.543859649107, 8019.278264956182, 8160.387970902206, 8250.298724608356, 8275.586220689062, 4383.736975781399, 4414.293713005904, 4444.488883778342, 4446.133004064176, 4486.394336764561, 1740.9056111599307, 1922.0, 1924.6343194792944, 1930.276146445083, 1934.3713293179774, 913.4154141349542, 1031.983496256187, 1074.983875241872, 1103.8675358956978, 1131.2816361610437, 15875.682486350208, 15940.840591593935, 16523.504294871174, 16734.0, 16985.91507042457, 989.8762654668167, 1007.9546420411099, 1060.6181774561153, 1069.5775168808277, 1096.5394534295615, 8408.32733381328, 9724.513774311255, 9830.715888231643, 10243.48782560869, 10593.516907632938, 451262.0, 456676.0, 457562.0, 458957.0, 465702.0, 44481.0, 44584.3312350315, 64722.35277647257, 80348.0, 81172.59413702894, 1189.0606420927543, 1206.4088596587785, 1244.2596654990266, 1246.569933372985, 1254.216114928188, 15884.285207165936, 16591.585210369838, 16702.49892503227, 16821.831781682027, 16924.32302707878, 37346.813265933495, 37714.622853771114, 37951.81024094862, 38995.0, 39943.0, 51314.0, 51869.0, 52340.21489677734, 55698.16452753293, 56079.71960140173, 10420.32267902584, 10499.475026248505, 10500.527476263434, 10588.0, 10593.89406105929, 12578.874211257771, 12652.746945061232, 13368.398422071225, 18909.810901891356, 19357.1289291985, 14954.551363459113, 15379.538613841603, 15392.846071539141, 15417.922910385376, 15805.13071781017, 14439.92780036093, 14593.70812583721, 14884.404623814926, 15625.29686164148, 15753.44862929792, 14432.927835360755, 14895.25523723831, 15316.387344506094, 15687.764683529984, 15857.127857967922, 8479.0, 10227.28409011362, 10240.385576865412, 10356.482175891024, 10566.101881339864, 14453.132812031312, 14801.851981480047, 14947.252637368305, 15079.924600376928, 15423.537293881202], "unit": "ms", "name": "jquery.html", "value": 9239.4556880979}, {"lowerIsBetter": false, "replicates": [1553.1845780964993, 1557.5171696774, 1726.6115124097087, 1764.1179410294853, 1821.6083542038464, 7485.850282994344, 7932.365410767139, 7937.44437889348, 7950.324222441085, 7962.522248665085, 7803.648835802388, 7881.330086942617, 7889.9605501972555, 7897.210278972114, 7898.842023159534, 2247.3932038349635, 2313.7801908818637, 2349.2717257650097, 2350.388898886293, 2354.0936739355384, 2313.7223533176043, 2337.3104934044413, 2338.5089131282452, 2341.555104530134, 2343.7773411525877, 1979.8020197980231, 2017.4653716764997, 2208.094681180716, 2237.0, 2302.5970455170363, 2227.1425501182, 2231.4979129695853, 2250.4036430345973, 2253.4591697992446, 2254.199759085534, 2241.12596087526, 2316.2356422380576, 2316.745158032615, 2317.640765681322, 2317.8609283443047, 6440.903386449207, 6553.213614366259, 7488.213737557532, 7735.7679269622, 7747.2640099190485, 4669.112868554964, 4737.265723812815, 4739.12326219647, 4741.170295198326, 4743.288506723985, 3000.804947678394, 3057.908262752121, 3065.1264389648845, 3071.9232019199476, 3087.0893086539527, 1029.5521448169995, 1054.4516851237313, 1054.915606751458, 1058.5077938758436, 1095.2223921016089, 10562.31344962575, 10675.359478431319, 10678.199135064824, 10689.358638481639, 10698.732531686692, 4068.3287257602756, 4078.062045729499, 4164.167166566699, 4191.119864828359, 4191.706580539394, 8511.531865747444, 8514.233718965323, 8519.403641745143, 8523.445976011539, 8529.914700853036, 4671.509491503343, 4673.299005149255, 4677.204875171229, 4697.271922851895, 4707.246840505502, 4330.610245077895, 4338.132373525307, 4342.283523218697, 4342.652587792969, 4352.738835669807, 3436.2096717754557, 3446.844891979867, 3471.6181220065773, 3476.878309259164, 3529.082438565991, 1035.5605708065796, 1037.527924794217, 1054.5096530113533, 1066.7013236293851, 1086.8750093739163, 1387.840398354182, 1401.159304417362, 1418.7729963205838, 1422.5518961527087, 1429.7069100834303, 7285.526440781332, 7410.629468526552, 7443.330100290891, 7458.664360103809, 7460.85078298431, 1106.750981029262, 1143.5482984221353, 1190.6547101340514, 1206.21595962625, 1218.8415505984342, 5318.973405133027, 6162.383761623892, 6190.597611155261, 6195.938040619536, 6205.162303089115, 7401.444891633149, 7469.029026226664, 7483.513571617937, 7488.588127653032, 7678.078630564256, 9031.187193152648, 9313.627454901729, 9334.533273336307, 9378.202852757591, 9482.431054136774, 7108.8933665995055, 7152.892706609405, 7174.856502869913, 7176.461765367619, 7241.275872412716, 7211.134663840373, 7277.563346199245, 7291.671874765542, 7298.635068246673, 7345.228750981284, 8023.3121904160425, 8036.1160272368825, 8142.918570814453, 8167.264946154874, 8173.937388139504, 8187.3040791533385, 8197.631106600336, 8349.290310323691, 8396.622152003296, 8400.789980250298, 6551.967240163769, 6623.105880706235, 6624.668766561749, 6624.701888414937, 6627.039079333566, 7344.30229128232, 7378.704851805867, 7390.260973902782, 7399.889001665087, 7418.443616728659, 6746.190457145174, 6874.2782007890455, 6899.689513971984, 6958.478114141561, 6975.9302406975285, 6880.862382752217, 6918.273581273894, 6927.861442771217, 7006.439484841098, 7026.578405295699, 7177.712891484282, 7346.816329591589, 7347.7795666130105, 7355.043844300207, 7358.632068396666, 6744.190697116377, 6764.0868482754195, 6764.391204791593, 6778.4916131289365, 6780.898286525608, 6383.6808159592765, 6737.528373014038, 6747.493937954575, 6749.426298764463, 6781.050652908498], "unit": "ms", "name": "mootools.html", "value": 4476.805932004389}, {"lowerIsBetter": false, "replicates": [332.788649316488, 333.64300198787294, 340.10043435114125, 352.2901353772148, 359.3137108123483, 6084.543659225554, 6090.66501342426, 6097.817065488042, 6112.847178820526, 6161.260648722148, 5788.160716696077, 5864.179014937912, 5930.377310382401, 6068.514518838493, 6094.116353128794, 4723.976380118094, 4730.479647238801, 4742.075295317406, 4743.857684269477, 4756.334113224151, 4465.754383508905, 4749.76251187441, 4750.073735621555, 4761.142994261032, 4767.880802979936, 4503.864884053483, 4583.656225783063, 4689.109069276844, 4701.717896926179, 4706.388169537972, 4667.766611669403, 4679.555442233, 4688.296755486705, 4698.90602187956, 4705.858824235278, 4161.04296011919, 4168.083021735213, 4169.187008533338, 4173.207090652797, 4173.248815213291, 5766.0, 5825.679587622679, 5833.591648584601, 5839.328477225131, 5848.912266316009, 5440.455954404568, 5441.972790136064, 5442.23808666783, 5450.155225939986, 5450.618456708032, 6471.967640161816, 6474.1907261592305, 6476.93523064768, 6478.805635830932, 6483.416492515649, 4536.931946020813, 4542.591166795004, 4545.749983750892, 4547.8635640930825, 4561.2245918193985, 6549.639769812658, 6612.413020875004, 6692.498062645321, 6695.363940425603, 6702.530822842403, 4640.83757068501, 4641.2109941310055, 4646.442426908792, 4647.09381670571, 4656.604188643935, 4698.647601429906, 4701.130290896127, 4706.717596944194, 4712.85861424151, 4714.057188562233, 5408.540274076748, 5416.891662166735, 5421.701806400686, 5423.864403389868, 5423.891522169534, 6285.862806645762, 6511.967440162769, 6520.706568204442, 6521.08704781331, 6528.281888992207, 1613.895096818703, 1642.7125253080662, 1644.1861278667136, 1645.4487746604782, 1646.415522489523, 1061.3207547169818, 1080.3841810168128, 1089.8310761831929, 1106.9003789658968, 1136.2500749505452, 6267.0, 6283.403076707706, 6284.340144284875, 6288.276848162428, 6294.0, 6418.935810641927, 6628.900566491507, 6629.5359324846795, 6635.270120286765, 6638.900416493757, 6153.815385538441, 6174.660393678301, 6315.652639104894, 6347.777827776098, 6354.301026887131, 3574.160072382988, 3624.075860655516, 3625.728070394678, 3627.147620309225, 3628.582712987987, 6734.0, 6901.413379862768, 6906.136732908387, 6910.239873613898, 6915.792526224222, 6842.392037871096, 6848.589084654936, 6869.141357330333, 6897.068895699016, 6900.689468973908, 6885.483588730765, 6911.6889739961825, 6914.619695916773, 6916.135483064617, 6920.688569014406, 6602.279370250294, 6656.2345330287635, 6663.700133493908, 6694.0, 6699.732010719516, 6651.933480665131, 6667.099941507834, 6667.166604174478, 6672.4328432081875, 6676.732930682719, 6899.344562266577, 6903.965480172567, 6909.1018167637885, 6912.997615345847, 6916.861662766817, 4505.63954883615, 4566.315052742115, 4572.771361431982, 4574.336721175478, 4574.954250457586, 6649.135612370361, 6701.095352127595, 6707.195136583641, 6708.631025293461, 6711.6308603025345, 6491.188601424822, 6492.740290388331, 6498.252700939453, 6500.219973603198, 6504.252011018604, 6391.744330226739, 6485.740570377132, 6486.805395838132, 6488.480921526361, 6496.870062598816, 6436.195475565554, 6440.0, 6445.516586255956, 6451.806445806634, 6452.483801295979, 6510.18622672166, 6553.4101930826455, 6563.245226798788, 6563.343665633399, 6573.506986976092, 6219.595726277777, 6323.620582765049, 6351.714172862325, 6357.777477788255, 6389.616623002449], "unit": "ms", "name": "prototype.html", "value": 4898.744078592902}, {"lowerIsBetter": false, "replicates": [767.4359345880779, 770.8304173081921, 821.4619424277097, 909.6315992023233, 940.6049459227124, 12210.572629957953, 12412.255264684103, 12436.689082772935, 12449.502019919204, 12458.37708114594, 11827.290362578251, 11897.64307070787, 11987.340696261703, 12027.458764355602, 12074.456649450774, 884.6417201033591, 894.0299774744377, 896.9282457403425, 903.7108125399875, 904.0913881549034, 880.5157163560017, 881.6825942660637, 887.4364778365752, 887.817997310553, 888.7689200807804, 922.557172557173, 933.351320832022, 933.8739270198538, 934.2666007184362, 934.8223837470892, 924.6347692661361, 927.2442958988403, 927.4481683398367, 927.777333439973, 928.0858354520825, 690.823839920822, 692.9029935808991, 693.3967448319994, 694.2918223412141, 694.7568351077133, 11164.385958772264, 11181.161412894066, 11197.048250898684, 11256.0, 11274.661760147208, 10753.83869241962, 10993.395363255018, 11056.834147487794, 11117.22179447439, 11279.661610151708, 9116.316276279305, 9191.770205744842, 9203.907960920438, 9252.167304942588, 9487.146156845847, 3740.2893450244364, 3800.90497737556, 3802.923941521182, 3813.599572044923, 3823.694104471663, 43115.7844210777, 45035.774821125684, 46174.769126154155, 54144.0, 54368.91262174774, 10001.199904007663, 10302.124319432782, 10331.586736530606, 10474.790504189874, 10511.421871797125, 16039.679206415807, 16273.51179464618, 19562.90218548898, 19807.30674426417, 19810.40568782939, 7367.77896663101, 7401.259874012556, 7438.702451901863, 7617.314441700273, 7621.123570789319, 4140.896477588084, 4163.271427500175, 4213.831446742096, 4287.3140297552245, 4337.652987760972, 3169.6513383527795, 3183.1564635371647, 3184.315372194943, 3188.1551388881967, 3190.0589326148843, 559.6921693068796, 568.687222027883, 569.0894568690145, 570.9628874123168, 581.5580159079046, 626.6188483761688, 646.8803271394808, 654.7119267522274, 681.2812482830562, 706.9187043489961, 8550.358723095793, 8743.0, 8858.77853053666, 8864.822703546022, 8907.153820387182, 538.526097034607, 568.5110804707948, 647.3364801078932, 649.9642519661464, 673.7170388436771, 4078.7960601970376, 4092.0, 4205.810738516774, 4230.915381692411, 4261.722988005769, 60276.095858562156, 60787.08819367713, 61221.38778612246, 62149.68925155346, 62535.37464625386, 53210.73394633002, 53571.19643205277, 53931.0, 54098.45901540934, 54613.0, 48723.75638121787, 49136.75431622891, 49141.0, 49293.0, 49311.50688493069, 7959.801004974922, 8075.636596353295, 8103.83792324162, 8264.132266111972, 8482.0, 33572.66427335695, 33865.322693545495, 37671.43492847664, 38552.614473854905, 38563.807180963915, 47825.28262076141, 48245.5175448241, 48677.0, 48952.0, 49171.262431062845, 11370.715732106764, 11966.880331196577, 12020.939895300467, 12173.2696038238, 12235.877641223475, 11453.541858325907, 12404.441800119197, 13711.794323084961, 13825.446982121004, 13998.860011400164, 10533.0, 10550.630727924487, 10890.945545272223, 10925.83611245816, 11323.830142548033, 11355.602553910574, 11498.0, 11932.2851038188, 11932.403379831147, 12190.0, 10365.274430789772, 10647.201459890384, 10753.677389678322, 10863.945680271549, 11472.196946213686, 10184.347826086956, 10498.790024199627, 10705.090067344363, 10930.289531180448, 10947.56209751601, 9864.950675246579, 10173.643922462677, 10173.745656358651, 10243.07812296897, 10377.325473844176], "unit": "ms", "name": "yui.html", "value": 6412.789644405569}], "name": "dromaeo_css", "value": 7217.773748242275}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 109.0, 103.0, 109.0, 110.0, 110.0, 100.0, 110.0, 109.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 107.8}, {"lowerIsBetter": true, "replicates": [146.0, 120.0, 145.0, 156.0, 121.0, 148.0, 148.0, 122.0, 163.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 139.3}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 161.0, 159.0, 159.0, 161.0, 159.0, 161.0, 160.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 159.9}, {"lowerIsBetter": true, "replicates": [70.0, 71.0, 72.0, 154.0, 73.0, 71.0, 72.0, 71.0, 163.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 88.9}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 148.0, 91.0, 92.0, 92.0, 135.0, 91.0, 92.0, 139.0], "unit": "ms", "name": "audio-oscillator", "value": 106.7}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 148.0, 147.0, 145.0, 146.0, 145.0, 145.0, 146.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [56.0, 57.0, 58.0, 57.0, 58.0, 57.0, 56.0, 57.0, 55.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 56.5}, {"lowerIsBetter": true, "replicates": [65.0, 64.0, 64.0, 65.0, 65.0, 65.0, 66.0, 65.0, 65.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.9}, {"lowerIsBetter": true, "replicates": [77.0, 79.0, 79.0, 78.0, 78.0, 86.0, 79.0, 78.0, 79.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.1}, {"lowerIsBetter": true, "replicates": [117.0, 108.0, 116.0, 116.0, 112.0, 113.0, 119.0, 112.0, 115.0, 119.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 114.7}, {"lowerIsBetter": true, "replicates": [133.0, 144.0, 142.0, 140.0, 138.0, 142.0, 152.0, 145.0, 144.0, 145.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 142.5}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 64.0, 66.0, 65.0, 64.0, 65.0, 63.0, 62.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.4}], "name": "kraken", "value": 1474.9000000000003}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.94745734076702], "unit": "score", "name": "Richards", "value": 208.952538}, {"lowerIsBetter": false, "replicates": [166.77785190126752], "unit": "score", "name": "DeltaBlue", "value": 396.44352799999996}, {"lowerIsBetter": false, "replicates": [278.473962684489], "unit": "score", "name": "Encrypt", "value": 955.8559709999997}, {"lowerIsBetter": false, "replicates": [5133.333333333333], "unit": "score", "name": "Decrypt", "value": 51.85344155844158}, {"lowerIsBetter": false, "replicates": [954.1984732824427], "unit": "score", "name": "RayTrace", "value": 775.5084720000002}, {"lowerIsBetter": false, "replicates": [544.9591280653951], "unit": "score", "name": "Earley", "value": 1222.959605}, {"lowerIsBetter": false, "replicates": [10536.842105263158], "unit": "score", "name": "Boyer", "value": 63.25073426573423}, {"lowerIsBetter": false, "replicates": [24285.714285714286], "unit": "score", "name": "RegExp", "value": 37.511147058823504}, {"lowerIsBetter": false, "replicates": [516.905737704918], "unit": "score", "name": "Splay", "value": 157.65156788899904}, {"lowerIsBetter": false, "replicates": [4882.926829268293], "unit": "score", "name": "NavierStokes", "value": 303.91608391608383}], "name": "v8_7", "value": 23241.668322400717}]} 08:20:44 INFO - RETURN: dromaeo_css: 7217.77 08:20:44 INFO - RETURN: kraken: 1474.90 08:20:44 INFO - RETURN: v8_7: 23241.67 08:20:44 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23241.67"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1474.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7217.77"}}} 08:20:44 INFO - Return code: 0 08:20:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 08:20:44 INFO - # TBPL SUCCESS # 08:20:44 INFO - Running post-action listener: _resource_record_post_action 08:20:44 INFO - Running post-run listener: _resource_record_post_run 08:20:45 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 16384; Write bytes: 503332864; Read time: 112; Write time: 378796 08:20:45 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 239124480; Read time: 0; Write time: 208552 08:20:45 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 16384; Write bytes: 263610368; Read time: 112; Write time: 170244 08:20:45 INFO - Running post-run listener: _upload_blobber_files 08:20:45 INFO - Blob upload gear active. 08:20:45 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 08:20:45 INFO - Copying logs to upload dir... 08:20:45 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1256.312328 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-01-27 08:20:45.458160) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-27 08:20:45.461785) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009942 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453889789/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-27 08:20:45.513916) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 08:20:45.514299) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1453910335.143387-960760681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004539 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 08:20:45.564467) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-27 08:20:45.564834) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-27 08:20:45.565205) ========= ========= Total master_lag: 0.23 =========