builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-067 starttime: 1454951875.78 results: success (0) buildid: 20160208064703 builduid: 0fe5e1ba1494409b9278597f4b83dec6 revision: 6cf32abc9a72 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:55.775585) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:55.775999) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:55.776294) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.004411 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:55.793542) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:55.793808) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:55.804548) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:55.804829) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004614 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:55.853021) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:55.853360) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:55.853746) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:55.854031) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-08 09:17:56-- 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% 32.0M=0s 2016-02-08 09:17:56 (32.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093485 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:55.961001) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:55.961335) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022392 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:56.011017) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:56.011386) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6cf32abc9a72 --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 6cf32abc9a72 --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=e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-08 09:17:56,201 Setting DEBUG logging. 2016-02-08 09:17:56,201 attempt 1/10 2016-02-08 09:17:56,202 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6cf32abc9a72?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-08 09:17:56,457 unpacking tar archive at: mozilla-beta-6cf32abc9a72/testing/mozharness/ program finished with exit code 0 elapsedTime=0.509931 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:56.534493) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:56.534873) ========= echo 6cf32abc9a72 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'6cf32abc9a72'] 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=e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 6cf32abc9a72 program finished with exit code 0 elapsedTime=0.004075 script_repo_revision: '6cf32abc9a72' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:56.583789) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:56.584095) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-08 09:17:56.594396) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-02-08 09:17:56.594666) ========= /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=e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:17:56 INFO - MultiFileLogger online at 20160208 09:17:56 in /builds/slave/test-pgo 09:17:56 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 09:17:56 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:17:56 INFO - {'append_to_log': False, 09:17:56 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:17:56 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:17:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:17:56 INFO - 'branch': 'Mozilla-Beta', 09:17:56 INFO - 'buildbot_json_path': 'buildprops.json', 09:17:56 INFO - 'config_files': ('talos/linux_config.py',), 09:17:56 INFO - 'default_actions': ('clobber', 09:17:56 INFO - 'read-buildbot-config', 09:17:56 INFO - 'download-and-extract', 09:17:56 INFO - 'populate-webroot', 09:17:56 INFO - 'create-virtualenv', 09:17:56 INFO - 'install', 09:17:56 INFO - 'run-tests'), 09:17:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:17:56 INFO - 'download_minidump_stackwalk': True, 09:17:56 INFO - 'download_symbols': 'ondemand', 09:17:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:17:56 INFO - 'tooltool.py': '/tools/tooltool.py', 09:17:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:17:56 INFO - '/tools/misc-python/virtualenv.py')}, 09:17:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:17:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:17:56 INFO - 'installer_path': 'installer.exe', 09:17:56 INFO - 'log_level': 'info', 09:17:56 INFO - 'log_name': 'talos', 09:17:56 INFO - 'log_to_console': True, 09:17:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:17:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:17:56 INFO - 'opt_config_files': (), 09:17:56 INFO - 'pip_index': False, 09:17:56 INFO - 'sps_profile': False, 09:17:56 INFO - 'sps_profile_interval': 0, 09:17:56 INFO - 'suite': 'dromaeojs', 09:17:56 INFO - 'system_bits': '32', 09:17:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:17:56 INFO - 'title': 'talos-linux64-ix-067', 09:17:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:17:56 INFO - 'use_talos_json': True, 09:17:56 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:17:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:17:56 INFO - 'work_dir': 'build'} 09:17:56 INFO - ##### 09:17:56 INFO - ##### Running clobber step. 09:17:56 INFO - ##### 09:17:56 INFO - Running pre-action listener: _resource_record_pre_action 09:17:56 INFO - Running main action method: clobber 09:17:56 INFO - rmtree: /builds/slave/test-pgo/build 09:17:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:17:57 INFO - Running post-action listener: _resource_record_post_action 09:17:57 INFO - ##### 09:17:57 INFO - ##### Running read-buildbot-config step. 09:17:57 INFO - ##### 09:17:57 INFO - Running pre-action listener: _resource_record_pre_action 09:17:57 INFO - Running main action method: read_buildbot_config 09:17:57 INFO - Using buildbot properties: 09:17:57 INFO - { 09:17:57 INFO - "properties": { 09:17:57 INFO - "buildnumber": 71, 09:17:57 INFO - "product": "firefox", 09:17:57 INFO - "script_repo_revision": "production", 09:17:57 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:17:57 INFO - "repository": "", 09:17:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:17:57 INFO - "buildid": "20160208064703", 09:17:57 INFO - "slavename": "talos-linux64-ix-067", 09:17:57 INFO - "pgo_build": "True", 09:17:57 INFO - "basedir": "/builds/slave/test-pgo", 09:17:57 INFO - "project": "", 09:17:57 INFO - "platform": "ubuntu64_hw", 09:17:57 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:17:57 INFO - "slavebuilddir": "test", 09:17:57 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:17:57 INFO - "branch": "mozilla-beta", 09:17:57 INFO - "repo_path": "releases/mozilla-beta", 09:17:57 INFO - "stage_platform": "linux64-pgo", 09:17:57 INFO - "builduid": "0fe5e1ba1494409b9278597f4b83dec6", 09:17:57 INFO - "revision": "6cf32abc9a72" 09:17:57 INFO - }, 09:17:57 INFO - "sourcestamp": { 09:17:57 INFO - "repository": "", 09:17:57 INFO - "hasPatch": false, 09:17:57 INFO - "project": "", 09:17:57 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:17:57 INFO - "changes": [ 09:17:57 INFO - { 09:17:57 INFO - "category": null, 09:17:57 INFO - "files": [ 09:17:57 INFO - { 09:17:57 INFO - "url": null, 09:17:57 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/firefox-45.0.en-US.linux-x86_64.tar.bz2" 09:17:57 INFO - } 09:17:57 INFO - ], 09:17:57 INFO - "repository": "", 09:17:57 INFO - "rev": "6cf32abc9a72", 09:17:57 INFO - "who": "sendchange", 09:17:57 INFO - "when": 1454951763, 09:17:57 INFO - "number": 7579059, 09:17:57 INFO - "comments": "Bug 1239319 - use input types properly for search and url bar to get better OSK support on Windows, r=jaws, a=sylvestre", 09:17:57 INFO - "project": "", 09:17:57 INFO - "at": "Mon 08 Feb 2016 09:16:03", 09:17:57 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:17:57 INFO - "revlink": "", 09:17:57 INFO - "properties": [ 09:17:57 INFO - [ 09:17:57 INFO - "buildid", 09:17:57 INFO - "20160208064703", 09:17:57 INFO - "Change" 09:17:57 INFO - ], 09:17:57 INFO - [ 09:17:57 INFO - "builduid", 09:17:57 INFO - "0fe5e1ba1494409b9278597f4b83dec6", 09:17:57 INFO - "Change" 09:17:57 INFO - ], 09:17:57 INFO - [ 09:17:57 INFO - "pgo_build", 09:17:57 INFO - "True", 09:17:57 INFO - "Change" 09:17:57 INFO - ] 09:17:57 INFO - ], 09:17:57 INFO - "revision": "6cf32abc9a72" 09:17:57 INFO - } 09:17:57 INFO - ], 09:17:57 INFO - "revision": "6cf32abc9a72" 09:17:57 INFO - } 09:17:57 INFO - } 09:17:57 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/firefox-45.0.en-US.linux-x86_64.tar.bz2. 09:17:57 INFO - Running post-action listener: _resource_record_post_action 09:17:57 INFO - ##### 09:17:57 INFO - ##### Running download-and-extract step. 09:17:57 INFO - ##### 09:17:57 INFO - Running pre-action listener: _resource_record_pre_action 09:17:57 INFO - Running main action method: download_and_extract 09:17:57 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:17:57 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')]} 09:17:57 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/test_packages.json 09:17:57 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:17:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:17:57 INFO - Downloaded 1270 bytes. 09:17:57 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:17:57 INFO - Using the following test package requirements: 09:17:57 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:17:57 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:17:57 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:17:57 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:17:57 INFO - u'jsshell-linux-x86_64.zip'], 09:17:57 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:17:57 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:17:57 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:17:57 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:17:57 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 09:17:57 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:17:57 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 09:17:57 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:17:57 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:17:57 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:17:57 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:17:57 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:17:57 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:17:57 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')]} 09:17:57 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:17:57 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/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 09:17:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/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 09:17:58 INFO - Downloaded 21582209 bytes. 09:17:58 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 09:17:58 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:17:58 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 09:17:59 INFO - Return code: 0 09:17:59 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 09:17: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')]} 09:17:59 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:17:59 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/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 09:17:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/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 09:17:59 INFO - Downloaded 21582209 bytes. 09:17:59 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 09:17:59 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:17:59 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 09:18:00 INFO - Return code: 0 09:18:00 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')]} 09:18:00 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:18:00 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/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 09:18:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/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 09:18:01 INFO - Downloaded 11132038 bytes. 09:18:01 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 09:18:01 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:18:01 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 09:18:01 INFO - Return code: 0 09:18:01 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')]} 09:18:01 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:18:01 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/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 09:18:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/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 09:18:02 INFO - Downloaded 57944597 bytes. 09:18:02 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:18:02 INFO - mkdir: /builds/slave/test-pgo/properties 09:18:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:18:02 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:18:02 INFO - Contents: 09:18:02 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:18:02 INFO - Running post-action listener: _resource_record_post_action 09:18:02 INFO - Running post-action listener: set_extra_try_arguments 09:18:02 INFO - ##### 09:18:02 INFO - ##### Running populate-webroot step. 09:18:02 INFO - ##### 09:18:02 INFO - Running pre-action listener: _resource_record_pre_action 09:18:02 INFO - Running main action method: populate_webroot 09:18:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:18:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:18:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:18:02 INFO - '--tppagecycles', 09:18:02 INFO - '7'], 09:18:02 INFO - 'tests': ['tsvgm']}}, 09:18:02 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:18:02 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:18:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:18:02 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:18:02 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:18:02 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:18:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:18:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:02 INFO - 'talos_options': ['--e10s'], 09:18:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:18:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:02 INFO - 'tests': ['damp', 'tps']}, 09:18:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:02 INFO - 'talos_options': ['--e10s'], 09:18:02 INFO - 'tests': ['damp', 'tps']}, 09:18:02 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:18:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:18:02 INFO - 'other': {'tests': ['a11yr', 09:18:02 INFO - 'ts_paint', 09:18:02 INFO - 'tpaint', 09:18:02 INFO - 'sessionrestore', 09:18:02 INFO - 'sessionrestore_no_auto_restore']}, 09:18:02 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:18:02 INFO - 'tests': ['a11yr', 09:18:02 INFO - 'ts_paint', 09:18:02 INFO - 'tpaint', 09:18:02 INFO - 'sessionrestore', 09:18:02 INFO - 'sessionrestore_no_auto_restore']}, 09:18:02 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:18:02 INFO - 'tests': ['a11yr', 09:18:02 INFO - 'ts_paint', 09:18:02 INFO - 'tpaint', 09:18:02 INFO - 'sessionrestore', 09:18:02 INFO - 'sessionrestore_no_auto_restore']}, 09:18:02 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:18:02 INFO - 'tests': ['a11yr', 09:18:02 INFO - 'ts_paint', 09:18:02 INFO - 'tpaint', 09:18:02 INFO - 'sessionrestore', 09:18:02 INFO - 'sessionrestore_no_auto_restore']}, 09:18:02 INFO - 'other_l64': {'tests': ['a11yr', 09:18:02 INFO - 'ts_paint', 09:18:02 INFO - 'tpaint', 09:18:02 INFO - 'sessionrestore', 09:18:02 INFO - 'sessionrestore_no_auto_restore']}, 09:18:02 INFO - 'other_nol64': {'tests': ['a11yr', 09:18:02 INFO - 'ts_paint', 09:18:02 INFO - 'tpaint', 09:18:02 INFO - 'sessionrestore', 09:18:02 INFO - 'sessionrestore_no_auto_restore']}, 09:18:02 INFO - 'svgr': {'tests': ['tsvgx', 09:18:02 INFO - 'tsvgr_opacity', 09:18:02 INFO - 'tart', 09:18:02 INFO - 'tscrollx', 09:18:02 INFO - 'cart']}, 09:18:02 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:18:02 INFO - 'tests': ['tsvgx', 09:18:02 INFO - 'tsvgr_opacity', 09:18:02 INFO - 'tart', 09:18:02 INFO - 'tscrollx', 09:18:02 INFO - 'cart']}, 09:18:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:02 INFO - 'tests': ['tp5o']}, 09:18:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:02 INFO - 'talos_options': ['--e10s'], 09:18:02 INFO - 'tests': ['tp5o']}, 09:18:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:02 INFO - 'talos_options': ['--xperf_path', 09:18:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:18:02 INFO - 'tests': ['tp5n']}, 09:18:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:02 INFO - 'talos_options': ['--e10s', 09:18:02 INFO - '--xperf_path', 09:18:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:18:02 INFO - 'tests': ['tp5n']}}, 09:18:02 INFO - 'talos.zip': {'path': '', 09:18:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:18:02 INFO - Running post-action listener: _resource_record_post_action 09:18:02 INFO - ##### 09:18:02 INFO - ##### Running create-virtualenv step. 09:18:02 INFO - ##### 09:18:02 INFO - Running pre-action listener: _resource_record_pre_action 09:18:02 INFO - Running main action method: create_virtualenv 09:18:02 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:18:02 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 09:18:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:18:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:18:02 INFO - Using real prefix '/usr' 09:18:02 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:18:02 INFO - Installing distribute.............................................................................................................................................................................................done. 09:18:04 INFO - Installing pip.................done. 09:18:04 INFO - Return code: 0 09:18:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:18: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')]} 09:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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')]} 09:18:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4a802acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1322600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b1350>, '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 0x13b2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13b26e0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', '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 09:18: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', 'pip>=1.5'] in /builds/slave/test-pgo/build 09:18: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 pip>=1.5 09:18:04 INFO - Using env: {'DISPLAY': ':0', 09:18:04 INFO - 'HOME': '/home/cltbld', 09:18:04 INFO - 'LANG': 'en_US.UTF-8', 09:18:04 INFO - 'LANGUAGE': 'en_US:en', 09:18:04 INFO - 'LOGNAME': 'cltbld', 09:18:04 INFO - 'MAIL': '/var/mail/cltbld', 09:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:04 INFO - 'MOZ_NO_REMOTE': '1', 09:18:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:04 INFO - 'NO_EM_RESTART': '1', 09:18:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:18:04 INFO - 'PWD': '/builds/slave/test-pgo', 09:18:04 INFO - 'SHELL': '/bin/bash', 09:18:04 INFO - 'SHLVL': '1', 09:18:04 INFO - 'TERM': 'linux', 09:18:04 INFO - 'TMOUT': '86400', 09:18:04 INFO - 'USER': 'cltbld', 09:18:04 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', 09:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:04 INFO - '_': '/tools/buildbot/bin/python'} 09:18:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:05 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 09:18:05 INFO - Cleaning up... 09:18:05 INFO - Return code: 0 09:18:05 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:18:05 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')]} 09:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:05 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')]} 09:18:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:05 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 0x7f4a802acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1322600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b1350>, '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 0x13b2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13b26e0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', '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 09:18:05 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 09:18:05 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 09:18:05 INFO - Using env: {'DISPLAY': ':0', 09:18:05 INFO - 'HOME': '/home/cltbld', 09:18:05 INFO - 'LANG': 'en_US.UTF-8', 09:18:05 INFO - 'LANGUAGE': 'en_US:en', 09:18:05 INFO - 'LOGNAME': 'cltbld', 09:18:05 INFO - 'MAIL': '/var/mail/cltbld', 09:18:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:05 INFO - 'MOZ_NO_REMOTE': '1', 09:18:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:05 INFO - 'NO_EM_RESTART': '1', 09:18:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:18:05 INFO - 'PWD': '/builds/slave/test-pgo', 09:18:05 INFO - 'SHELL': '/bin/bash', 09:18:05 INFO - 'SHLVL': '1', 09:18:05 INFO - 'TERM': 'linux', 09:18:05 INFO - 'TMOUT': '86400', 09:18:05 INFO - 'USER': 'cltbld', 09:18:05 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', 09:18:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:05 INFO - '_': '/tools/buildbot/bin/python'} 09:18:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:05 INFO - Downloading/unpacking psutil>=0.7.1 09:18:05 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 09:18:05 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 09:18:05 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 09:18:05 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 09:18:05 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 09:18:05 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 09:18:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:18:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:18:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:18:07 INFO - Installing collected packages: psutil 09:18:07 INFO - Running setup.py install for psutil 09:18:07 INFO - building 'psutil._psutil_linux' extension 09:18:07 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 09:18:07 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 09:18:07 INFO - building 'psutil._psutil_posix' extension 09:18:07 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 09:18:07 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 09:18:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:18:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:18:07 INFO - Successfully installed psutil 09:18:07 INFO - Cleaning up... 09:18:07 INFO - Return code: 0 09:18:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:18: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')]} 09:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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')]} 09:18:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:07 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 0x7f4a802acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1322600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b1350>, '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 0x13b2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13b26e0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', '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 09:18:07 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 09:18:07 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 09:18:07 INFO - Using env: {'DISPLAY': ':0', 09:18:07 INFO - 'HOME': '/home/cltbld', 09:18:07 INFO - 'LANG': 'en_US.UTF-8', 09:18:07 INFO - 'LANGUAGE': 'en_US:en', 09:18:07 INFO - 'LOGNAME': 'cltbld', 09:18:07 INFO - 'MAIL': '/var/mail/cltbld', 09:18:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:07 INFO - 'MOZ_NO_REMOTE': '1', 09:18:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:07 INFO - 'NO_EM_RESTART': '1', 09:18:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:18:07 INFO - 'PWD': '/builds/slave/test-pgo', 09:18:07 INFO - 'SHELL': '/bin/bash', 09:18:07 INFO - 'SHLVL': '1', 09:18:07 INFO - 'TERM': 'linux', 09:18:07 INFO - 'TMOUT': '86400', 09:18:07 INFO - 'USER': 'cltbld', 09:18:07 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', 09:18:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:07 INFO - '_': '/tools/buildbot/bin/python'} 09:18:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:18:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:18:09 INFO - Installing collected packages: mozsystemmonitor 09:18:09 INFO - Running setup.py install for mozsystemmonitor 09:18:09 INFO - Successfully installed mozsystemmonitor 09:18:09 INFO - Cleaning up... 09:18:09 INFO - Return code: 0 09:18:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:18:09 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')]} 09:18:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:09 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')]} 09:18:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:09 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 0x7f4a802acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1322600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b1350>, '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 0x13b2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13b26e0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', '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 09:18:09 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 09:18:09 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 09:18:09 INFO - Using env: {'DISPLAY': ':0', 09:18:09 INFO - 'HOME': '/home/cltbld', 09:18:09 INFO - 'LANG': 'en_US.UTF-8', 09:18:09 INFO - 'LANGUAGE': 'en_US:en', 09:18:09 INFO - 'LOGNAME': 'cltbld', 09:18:09 INFO - 'MAIL': '/var/mail/cltbld', 09:18:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:09 INFO - 'MOZ_NO_REMOTE': '1', 09:18:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:09 INFO - 'NO_EM_RESTART': '1', 09:18:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:18:09 INFO - 'PWD': '/builds/slave/test-pgo', 09:18:09 INFO - 'SHELL': '/bin/bash', 09:18:09 INFO - 'SHLVL': '1', 09:18:09 INFO - 'TERM': 'linux', 09:18:09 INFO - 'TMOUT': '86400', 09:18:09 INFO - 'USER': 'cltbld', 09:18:09 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', 09:18:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:09 INFO - '_': '/tools/buildbot/bin/python'} 09:18:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:10 INFO - Downloading/unpacking blobuploader==1.2.4 09:18:10 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 09:18:10 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 09:18:10 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 09:18:10 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 09:18:10 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 09:18:10 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 09:18:11 INFO - Downloading blobuploader-1.2.4.tar.gz 09:18:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:18:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:18:11 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 09:18:11 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 09:18:11 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 09:18:11 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 09:18:11 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 09:18:11 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 09:18:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:18:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18:12 INFO - Downloading docopt-0.6.1.tar.gz 09:18:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:18:12 INFO - Installing collected packages: blobuploader, requests, docopt 09:18:12 INFO - Running setup.py install for blobuploader 09:18:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:18:12 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:18:12 INFO - Running setup.py install for requests 09:18:13 INFO - Running setup.py install for docopt 09:18:13 INFO - Successfully installed blobuploader requests docopt 09:18:13 INFO - Cleaning up... 09:18:13 INFO - Return code: 0 09:18:13 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:18:13 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')]} 09:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:13 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')]} 09:18:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:13 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 0x7f4a802acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1322600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b1350>, '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 0x13b2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13b26e0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', '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 09:18:13 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 09:18:13 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 09:18:13 INFO - Using env: {'DISPLAY': ':0', 09:18:13 INFO - 'HOME': '/home/cltbld', 09:18:13 INFO - 'LANG': 'en_US.UTF-8', 09:18:13 INFO - 'LANGUAGE': 'en_US:en', 09:18:13 INFO - 'LOGNAME': 'cltbld', 09:18:13 INFO - 'MAIL': '/var/mail/cltbld', 09:18:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:13 INFO - 'MOZ_NO_REMOTE': '1', 09:18:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:13 INFO - 'NO_EM_RESTART': '1', 09:18:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:18:13 INFO - 'PWD': '/builds/slave/test-pgo', 09:18:13 INFO - 'SHELL': '/bin/bash', 09:18:13 INFO - 'SHLVL': '1', 09:18:13 INFO - 'TERM': 'linux', 09:18:13 INFO - 'TMOUT': '86400', 09:18:13 INFO - 'USER': 'cltbld', 09:18:13 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', 09:18:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:13 INFO - '_': '/tools/buildbot/bin/python'} 09:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:18:13 INFO - Running setup.py (path:/tmp/pip-Wk1e9k-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:18:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:18:13 INFO - Running setup.py (path:/tmp/pip-saG1IS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:18:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:18:13 INFO - Running setup.py (path:/tmp/pip-qy1HxF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:18:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:18:13 INFO - Running setup.py (path:/tmp/pip-O8ISmm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:18:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:18:13 INFO - Running setup.py (path:/tmp/pip-L0vAaR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:18:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:18:13 INFO - Running setup.py (path:/tmp/pip-9mgOwQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:18:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:18:13 INFO - Running setup.py (path:/tmp/pip-1siEZX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:18:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:18:13 INFO - Running setup.py (path:/tmp/pip-9aIW9U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:18:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:18:13 INFO - Running setup.py (path:/tmp/pip-Slksa_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:18:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:18:14 INFO - Running setup.py (path:/tmp/pip-tklm7D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:18:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:18:14 INFO - Running setup.py (path:/tmp/pip-ZxKkIv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:18:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:18:14 INFO - Running setup.py (path:/tmp/pip-jc9M8Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:18:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:18:14 INFO - Running setup.py (path:/tmp/pip-AToHkJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:18:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:18:14 INFO - Running setup.py (path:/tmp/pip-4v1YcH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:18:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:18:14 INFO - Running setup.py (path:/tmp/pip-hTBSQi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:18:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:18:14 INFO - Running setup.py (path:/tmp/pip-UbnpoY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:18:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:18:14 INFO - Running setup.py (path:/tmp/pip-q5llJw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:18:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:18:14 INFO - Running setup.py install for manifestparser 09:18:14 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:18:14 INFO - Running setup.py install for mozcrash 09:18:14 INFO - Running setup.py install for mozdebug 09:18:14 INFO - Running setup.py install for mozdevice 09:18:15 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:18:15 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:18:15 INFO - Running setup.py install for mozfile 09:18:15 INFO - Running setup.py install for mozhttpd 09:18:15 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:18:15 INFO - Running setup.py install for mozinfo 09:18:15 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:18:15 INFO - Running setup.py install for mozInstall 09:18:15 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:18:15 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:18:15 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:18:15 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:18:15 INFO - Running setup.py install for mozleak 09:18:15 INFO - Running setup.py install for mozlog 09:18:15 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:18:15 INFO - Running setup.py install for moznetwork 09:18:15 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:18:15 INFO - Running setup.py install for mozprocess 09:18:15 INFO - Running setup.py install for mozprofile 09:18:16 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:18:16 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:18:16 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:18:16 INFO - Running setup.py install for mozrunner 09:18:16 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:18:16 INFO - Running setup.py install for mozscreenshot 09:18:16 INFO - Running setup.py install for moztest 09:18:16 INFO - Running setup.py install for mozversion 09:18:16 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:18:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:18:16 INFO - Cleaning up... 09:18:16 INFO - Return code: 0 09:18:16 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:18:16 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')]} 09:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:16 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')]} 09:18:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:16 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 0x7f4a802acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1322600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b1350>, '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 0x13b2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13b26e0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', '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 09:18:16 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 09:18:16 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 09:18:16 INFO - Using env: {'DISPLAY': ':0', 09:18:16 INFO - 'HOME': '/home/cltbld', 09:18:16 INFO - 'LANG': 'en_US.UTF-8', 09:18:16 INFO - 'LANGUAGE': 'en_US:en', 09:18:16 INFO - 'LOGNAME': 'cltbld', 09:18:16 INFO - 'MAIL': '/var/mail/cltbld', 09:18:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:16 INFO - 'MOZ_NO_REMOTE': '1', 09:18:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:16 INFO - 'NO_EM_RESTART': '1', 09:18:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:18:16 INFO - 'PWD': '/builds/slave/test-pgo', 09:18:16 INFO - 'SHELL': '/bin/bash', 09:18:16 INFO - 'SHLVL': '1', 09:18:16 INFO - 'TERM': 'linux', 09:18:16 INFO - 'TMOUT': '86400', 09:18:16 INFO - 'USER': 'cltbld', 09:18:16 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', 09:18:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:16 INFO - '_': '/tools/buildbot/bin/python'} 09:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:18:16 INFO - Running setup.py (path:/tmp/pip-8cgTpA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:18:16 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)) 09:18:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:18:16 INFO - Running setup.py (path:/tmp/pip-g0vlwB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:18:16 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)) 09:18:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:18:16 INFO - Running setup.py (path:/tmp/pip-TN2bee-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:18:16 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)) 09:18:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:18:16 INFO - Running setup.py (path:/tmp/pip-EINsxZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:18:16 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)) 09:18:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:18:16 INFO - Running setup.py (path:/tmp/pip-z4B7QJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:18:17 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)) 09:18:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:18:17 INFO - Running setup.py (path:/tmp/pip-fCswm1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:18:17 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)) 09:18:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:18:17 INFO - Running setup.py (path:/tmp/pip-ieZqOg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:18:17 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)) 09:18:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:18:17 INFO - Running setup.py (path:/tmp/pip-s8ueAC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:18:17 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)) 09:18:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:18:17 INFO - Running setup.py (path:/tmp/pip-vkYvUu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:18:17 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)) 09:18:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:18:17 INFO - Running setup.py (path:/tmp/pip-v1QkdW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:18:17 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)) 09:18:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:18:17 INFO - Running setup.py (path:/tmp/pip-SIHvVq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:18:17 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)) 09:18:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:18:17 INFO - Running setup.py (path:/tmp/pip-rC5yLl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:18:17 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)) 09:18:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:18:17 INFO - Running setup.py (path:/tmp/pip-nARXYX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:18:17 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)) 09:18:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:18:17 INFO - Running setup.py (path:/tmp/pip-mFfXAj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:18:17 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)) 09:18:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:18:17 INFO - Running setup.py (path:/tmp/pip-sfC_QJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:18:17 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)) 09:18:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:18:17 INFO - Running setup.py (path:/tmp/pip-Zr4H62-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:18:17 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)) 09:18:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:18:17 INFO - Running setup.py (path:/tmp/pip-MVG6WV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:18:17 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)) 09:18:17 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)) 09:18:17 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)) 09:18:17 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)) 09:18:17 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)) 09:18:17 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)) 09:18:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18:19 INFO - Downloading blessings-1.6.tar.gz 09:18:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:18:19 INFO - Installing collected packages: blessings 09:18:19 INFO - Running setup.py install for blessings 09:18:19 INFO - Successfully installed blessings 09:18:19 INFO - Cleaning up... 09:18:19 INFO - Return code: 0 09:18:19 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:18:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:18:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:18:20 INFO - Reading from file tmpfile_stdout 09:18:20 INFO - Current package versions: 09:18:20 INFO - argparse == 1.2.1 09:18:20 INFO - blessings == 1.6 09:18:20 INFO - blobuploader == 1.2.4 09:18:20 INFO - docopt == 0.6.1 09:18:20 INFO - manifestparser == 1.1 09:18:20 INFO - mozInstall == 1.12 09:18:20 INFO - mozcrash == 0.16 09:18:20 INFO - mozdebug == 0.1 09:18:20 INFO - mozdevice == 0.47 09:18:20 INFO - mozfile == 1.2 09:18:20 INFO - mozhttpd == 0.7 09:18:20 INFO - mozinfo == 0.9 09:18:20 INFO - mozleak == 0.1 09:18:20 INFO - mozlog == 3.1 09:18:20 INFO - moznetwork == 0.27 09:18:20 INFO - mozprocess == 0.22 09:18:20 INFO - mozprofile == 0.27 09:18:20 INFO - mozrunner == 6.11 09:18:20 INFO - mozscreenshot == 0.1 09:18:20 INFO - mozsystemmonitor == 0.0 09:18:20 INFO - moztest == 0.7 09:18:20 INFO - mozversion == 1.4 09:18:20 INFO - psutil == 3.1.1 09:18:20 INFO - requests == 1.2.3 09:18:20 INFO - wsgiref == 0.1.2 09:18:20 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:18:20 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')]} 09:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:20 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')]} 09:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:20 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 0x7f4a802acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1322600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b1350>, '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 0x13b2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13b26e0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', '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 09:18:20 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 09:18:20 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 09:18:20 INFO - Using env: {'DISPLAY': ':0', 09:18:20 INFO - 'HOME': '/home/cltbld', 09:18:20 INFO - 'LANG': 'en_US.UTF-8', 09:18:20 INFO - 'LANGUAGE': 'en_US:en', 09:18:20 INFO - 'LOGNAME': 'cltbld', 09:18:20 INFO - 'MAIL': '/var/mail/cltbld', 09:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:20 INFO - 'MOZ_NO_REMOTE': '1', 09:18:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:20 INFO - 'NO_EM_RESTART': '1', 09:18:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:18:20 INFO - 'PWD': '/builds/slave/test-pgo', 09:18:20 INFO - 'SHELL': '/bin/bash', 09:18:20 INFO - 'SHLVL': '1', 09:18:20 INFO - 'TERM': 'linux', 09:18:20 INFO - 'TMOUT': '86400', 09:18:20 INFO - 'USER': 'cltbld', 09:18:20 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', 09:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:20 INFO - '_': '/tools/buildbot/bin/python'} 09:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:20 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)) 09:18:20 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)) 09:18:20 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)) 09:18:20 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)) 09:18:20 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)) 09:18:20 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)) 09:18:20 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)) 09:18:20 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)) 09:18:20 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)) 09:18:20 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)) 09:18:20 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)) 09:18:20 INFO - Cleaning up... 09:18:20 INFO - Return code: 0 09:18:20 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:18:20 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')]} 09:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:20 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')]} 09:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:20 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 0x7f4a802acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1322600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b1350>, '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 0x13b2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13b26e0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', '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 09:18:20 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 09:18:20 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 09:18:20 INFO - Using env: {'DISPLAY': ':0', 09:18:20 INFO - 'HOME': '/home/cltbld', 09:18:20 INFO - 'LANG': 'en_US.UTF-8', 09:18:20 INFO - 'LANGUAGE': 'en_US:en', 09:18:20 INFO - 'LOGNAME': 'cltbld', 09:18:20 INFO - 'MAIL': '/var/mail/cltbld', 09:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:20 INFO - 'MOZ_NO_REMOTE': '1', 09:18:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:20 INFO - 'NO_EM_RESTART': '1', 09:18:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:18:20 INFO - 'PWD': '/builds/slave/test-pgo', 09:18:20 INFO - 'SHELL': '/bin/bash', 09:18:20 INFO - 'SHLVL': '1', 09:18:20 INFO - 'TERM': 'linux', 09:18:20 INFO - 'TMOUT': '86400', 09:18:20 INFO - 'USER': 'cltbld', 09:18:20 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', 09:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:20 INFO - '_': '/tools/buildbot/bin/python'} 09:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:20 INFO - Downloading/unpacking jsonschema 09:18:20 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 09:18:20 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 09:18:20 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 09:18:20 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 09:18:20 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 09:18:20 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 09:18:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:18:22 INFO - Downloading/unpacking functools32 (from jsonschema) 09:18:22 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 09:18:22 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 09:18:22 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 09:18:22 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 09:18:22 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 09:18:22 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 09:18:22 INFO - Downloading functools32-3.2.3-2.tar.gz 09:18:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:18:22 INFO - warning: no files found matching '*.txt' 09:18:22 INFO - no previously-included directories found matching 'build' 09:18:22 INFO - no previously-included directories found matching 'dist' 09:18:22 INFO - no previously-included directories found matching '.git*' 09:18:22 INFO - Installing collected packages: jsonschema, functools32 09:18:22 INFO - Running setup.py install for functools32 09:18:22 INFO - warning: no files found matching '*.txt' 09:18:22 INFO - no previously-included directories found matching 'build' 09:18:22 INFO - no previously-included directories found matching 'dist' 09:18:22 INFO - no previously-included directories found matching '.git*' 09:18:22 INFO - Successfully installed jsonschema functools32 09:18:22 INFO - Cleaning up... 09:18:22 INFO - Return code: 0 09:18:22 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:18:22 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')]} 09:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:22 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')]} 09:18:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:22 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 0x7f4a802acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1322600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b1350>, '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 0x13b2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13b26e0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', '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 09:18:22 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 09:18:22 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 09:18:22 INFO - Using env: {'DISPLAY': ':0', 09:18:22 INFO - 'HOME': '/home/cltbld', 09:18:22 INFO - 'LANG': 'en_US.UTF-8', 09:18:22 INFO - 'LANGUAGE': 'en_US:en', 09:18:22 INFO - 'LOGNAME': 'cltbld', 09:18:22 INFO - 'MAIL': '/var/mail/cltbld', 09:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:22 INFO - 'MOZ_NO_REMOTE': '1', 09:18:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:22 INFO - 'NO_EM_RESTART': '1', 09:18:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:18:22 INFO - 'PWD': '/builds/slave/test-pgo', 09:18:22 INFO - 'SHELL': '/bin/bash', 09:18:22 INFO - 'SHLVL': '1', 09:18:22 INFO - 'TERM': 'linux', 09:18:22 INFO - 'TMOUT': '86400', 09:18:22 INFO - 'USER': 'cltbld', 09:18:22 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', 09:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:22 INFO - '_': '/tools/buildbot/bin/python'} 09:18:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:23 INFO - Downloading/unpacking flake8 09:18:23 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 09:18:23 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 09:18:23 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 09:18:23 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 09:18:23 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 09:18:23 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 09:18:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:18:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:18:24 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 09:18:24 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 09:18:24 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 09:18:24 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 09:18:24 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 09:18:24 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 09:18:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:18:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:18:25 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 09:18:25 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 09:18:25 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 09:18:25 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 09:18:25 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 09:18:25 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 09:18:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:18:25 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 09:18:25 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 09:18:25 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 09:18:25 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 09:18:25 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 09:18:25 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 09:18:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:18:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:18:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:18:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:18:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:18:25 INFO - no previously-included directories found matching 'docs/_build' 09:18:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:18:25 INFO - Running setup.py install for pep8 09:18:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:18:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:18:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:18:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:18:25 INFO - no previously-included directories found matching 'docs/_build' 09:18:25 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:18:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:18:25 INFO - Cleaning up... 09:18:25 INFO - Return code: 0 09:18:25 INFO - Running post-action listener: _resource_record_post_action 09:18:25 INFO - Running post-action listener: _start_resource_monitoring 09:18:25 INFO - Starting resource monitoring. 09:18:25 INFO - ##### 09:18:25 INFO - ##### Running install step. 09:18:25 INFO - ##### 09:18:25 INFO - Running pre-action listener: _resource_record_pre_action 09:18:25 INFO - Running main action method: install 09:18:25 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:18:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:18:26 INFO - Reading from file tmpfile_stdout 09:18:26 INFO - Detecting whether we're running mozinstall >=1.0... 09:18:26 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:18:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:18:26 INFO - Reading from file tmpfile_stdout 09:18:26 INFO - Output received: 09:18:26 INFO - Usage: mozinstall [options] installer 09:18:26 INFO - Options: 09:18:26 INFO - -h, --help show this help message and exit 09:18:26 INFO - -d DEST, --destination=DEST 09:18:26 INFO - Directory to install application into. [default: 09:18:26 INFO - "/builds/slave/test-pgo"] 09:18:26 INFO - --app=APP Application being installed. [default: firefox] 09:18:26 INFO - mkdir: /builds/slave/test-pgo/build/application 09:18:26 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'] 09:18:26 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 09:18:40 INFO - Reading from file tmpfile_stdout 09:18:40 INFO - Output received: 09:18:40 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:18:40 INFO - Running post-action listener: _resource_record_post_action 09:18:40 INFO - ##### 09:18:40 INFO - ##### Running run-tests step. 09:18:40 INFO - ##### 09:18:40 INFO - Running pre-action listener: _resource_record_pre_action 09:18:40 INFO - Running main action method: run_tests 09:18:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:18:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:18:40 INFO - Python 2.7.3 09:18:40 INFO - Return code: 0 09:18:40 INFO - grabbing minidump binary from tooltool 09:18:40 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')]} 09:18:40 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 0x13b1350>, '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 0x13b2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13b26e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:18:40 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 09:18:40 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 09:18:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:18:40 INFO - Return code: 0 09:18:40 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:18:40 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:18:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:18:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:18:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:18:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:18:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:18:41 INFO - Return code: 0 09:18:41 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/1454942823/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:18:41 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/1454942823/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --webServer localhost 09:18:41 INFO - Using env: {'DISPLAY': ':0', 09:18:41 INFO - 'HOME': '/home/cltbld', 09:18:41 INFO - 'LANG': 'en_US.UTF-8', 09:18:41 INFO - 'LANGUAGE': 'en_US:en', 09:18:41 INFO - 'LOGNAME': 'cltbld', 09:18:41 INFO - 'MAIL': '/var/mail/cltbld', 09:18:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:18:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:41 INFO - 'MOZ_NO_REMOTE': '1', 09:18:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:18:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:41 INFO - 'NO_EM_RESTART': '1', 09:18:41 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:18:41 INFO - 'PWD': '/builds/slave/test-pgo', 09:18:41 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:18:41 INFO - 'SHELL': '/bin/bash', 09:18:41 INFO - 'SHLVL': '1', 09:18:41 INFO - 'TERM': 'linux', 09:18:41 INFO - 'TMOUT': '86400', 09:18:41 INFO - 'USER': 'cltbld', 09:18:41 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532', 09:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:41 INFO - '_': '/tools/buildbot/bin/python'} 09:18:41 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/1454942823/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--webServer', 'localhost'] with output_timeout 3600 09:18:42 INFO - mozversion INFO | application_buildid: 20160208064703 09:18:42 INFO - mozversion INFO | application_changeset: 6cf32abc9a72968c3a48c3a743ca6f6d47115ccf 09:18:42 INFO - mozversion INFO | application_display_name: Nightly 09:18:42 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:18:42 INFO - mozversion INFO | application_name: Firefox 09:18:42 INFO - mozversion INFO | application_remotingname: firefox 09:18:42 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:18:42 INFO - mozversion INFO | application_vendor: Mozilla 09:18:42 INFO - mozversion INFO | application_version: 45.0 09:18:42 INFO - mozversion INFO | platform_buildid: 20160208064703 09:18:42 INFO - mozversion INFO | platform_changeset: 6cf32abc9a72968c3a48c3a743ca6f6d47115ccf 09:18:42 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:18:42 INFO - mozversion INFO | platform_version: 45.0 09:18:42 INFO - 2016-02-08 09:18:42,003 DEBUG : using testdate: 1454951922 09:18:42 INFO - 2016-02-08 09:18:42,003 DEBUG : actual date: 1454951922 09:18:42 INFO - 2016-02-08 09:18:42,013 INFO : starting webserver on 'localhost:49553' 09:18:42 INFO - 2016-02-08 09:18:42,014 INFO : Starting test suite talos-linux64-ix-067 09:18:42 INFO - 2016-02-08 09:18:42,014 INFO : Starting test dromaeo_css 09:18:42 INFO - 2016-02-08 09:18:42,015 DEBUG : operating with platform_type : linux_ 09:18:42 INFO - 2016-02-08 09:18:42,015 INFO : Initialising browser for dromaeo_css test... 09:18:42 INFO - 2016-02-08 09:18:42,032 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphrgomS/profile http://localhost:49553/getInfo.html 09:18:45 INFO - 2016-02-08 09:18:45,248 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:18:45 INFO - 2016-02-08 09:18:45,248 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:18:45 INFO - 2016-02-08 09:18:45,248 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:18:45 INFO - 2016-02-08 09:18:45,248 DEBUG : BROWSER_OUTPUT: __metrics 09:18:45 INFO - 2016-02-08 09:18:45,405 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] 09:18:46 INFO - 2016-02-08 09:18:46,251 INFO : Browser initialized. 09:18:46 INFO - 2016-02-08 09:18:46,251 INFO : Running cycle 1/1 for dromaeo_css test... 09:18:46 INFO - 2016-02-08 09:18:46,252 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphrgomS/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:18:58 INFO - 2016-02-08 09:18:58,072 DEBUG : BROWSER_OUTPUT: RSS: Main: 135127040 09:18:58 INFO - 2016-02-08 09:18:58,073 DEBUG : BROWSER_OUTPUT: 09:19:48 INFO - 2016-02-08 09:19:48,819 DEBUG : BROWSER_OUTPUT: 1454951988819 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] 09:21:56 INFO - 2016-02-08 09:21:56,911 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49553/tests/dromaeo/cssquery-ext.html) 09:21:58 INFO - 2016-02-08 09:21:58,000 DEBUG : BROWSER_OUTPUT: RSS: Main: 245936128 09:21:58 INFO - 2016-02-08 09:21:58,001 DEBUG : BROWSER_OUTPUT: 09:25:00 INFO - 2016-02-08 09:25:00,041 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/dromaeo/cssquery-ext.html (next: http://localhost:49553/tests/dromaeo/cssquery-jquery.html) 09:25:00 INFO - 2016-02-08 09:25:00,606 DEBUG : BROWSER_OUTPUT: RSS: Main: 220991488 09:25:00 INFO - 2016-02-08 09:25:00,606 DEBUG : BROWSER_OUTPUT: 09:28:02 INFO - 2016-02-08 09:28:02,306 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49553/tests/dromaeo/cssquery-mootools.html) 09:28:02 INFO - 2016-02-08 09:28:02,868 DEBUG : BROWSER_OUTPUT: RSS: Main: 220119040 09:28:02 INFO - 2016-02-08 09:28:02,868 DEBUG : BROWSER_OUTPUT: 09:31:04 INFO - 2016-02-08 09:31:04,571 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49553/tests/dromaeo/cssquery-prototype.html) 09:31:05 INFO - 2016-02-08 09:31:05,140 DEBUG : BROWSER_OUTPUT: RSS: Main: 208142336 09:31:05 INFO - 2016-02-08 09:31:05,140 DEBUG : BROWSER_OUTPUT: 09:34:07 INFO - 2016-02-08 09:34:07,442 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49553/tests/dromaeo/cssquery-yui.html) 09:34:08 INFO - 2016-02-08 09:34:08,003 DEBUG : BROWSER_OUTPUT: RSS: Main: 200716288 09:34:08 INFO - 2016-02-08 09:34:08,003 DEBUG : BROWSER_OUTPUT: 09:37:09 INFO - 2016-02-08 09:37:09,934 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/dromaeo/cssquery-yui.html (next: http://localhost:49553/tests/dromaeo/cssquery-dojo.html) 09:37:10 INFO - 2016-02-08 09:37:10,184 DEBUG : BROWSER_OUTPUT: RSS: Main: 235114496 09:37:10 INFO - 2016-02-08 09:37:10,184 DEBUG : BROWSER_OUTPUT: 09:37:10 INFO - 2016-02-08 09:37:10,185 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:37:10 INFO - 2016-02-08 09:37:10,185 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:37:10 INFO - 2016-02-08 09:37:10,185 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:37:10 INFO - 2016-02-08 09:37:10,185 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:37:10 INFO - 2016-02-08 09:37:10,185 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3277;3358.7648864579487;3393.304372603615;3399.694027537521;3413.453847384419;5544.861378465535;5561.888762224753;5564.916526252105;5590.748416321265;5594.104943209077;5569.6658200508;5620.718964051802;5620.859478513039;5624;5624.803131890385;4881.536254055859;4941.876453088666;5088.135017047092;5114.360704911886;5176.430592634807;4963.72699501529;5015.974920125394;5021.799128034874;5024.773885175157;5025.497450254964;4983.07813054586;5052.797888084472;5082.186850103984;5099.260607211971;5101.617378696594;5004.699718016913;5080.161773307418;5093.79624815007;5096.082705113078;5102.591792656579;5080.847574572768;5156.92264616031;5183.611229157828;5193.506616871391;5207;5184.196449550326;5237.502437268454;5269.209618557247;5334.546563542103;5378.354597448292;5931.139984702194;5995.250593675791;6044.667543285118;6057.394260573951;6060.606060606091;6003.489703375219;6069.6054756441135;6092.482139018189;6096.878062438771;6110.266767987825;5369.40936496985;5424.376196737387;5479.6164268501225;5504.9449505504435;5518.834434966998;6085.208922840091;6124.969375153185;6127.417895299939;6129.632222066601;6133.6319820810895;5574.693391863405;5901.645901245853;5918.230630018156;5919.6744179070565;5927.555433342516;5680.40355762639;5883.294004719375;5928.911066334009;5942.910856337158;5964.880702385928;5876.236089308362;5891.852703682356;5922.7927022554;5924.318703349084;5932.377100404482;5652.0391533439415;5760.6255593386295;5919.348871624118;5919.733611987471;5929.822105336847;4403.229434848889;4446.510883802779;4447.310666846644;4450.043240703264;4450.821967121279;4028.1540876415693;4055.2497787909333;4065.654419374386;4119.176164767058;4134.37984302357;5750.108733146369;5809.82570522885;5810.970945145247;5811.680357580373;5813.069908814569;6077.301110372276;6101.664408457489;6104.298005729309;6109.572329936862;6113.174721412642;5498.560115190775;5504.366997795305;5556.388797232301;5574.721263936787;5691.430856914276;5166.974165129225;5175.663581867167;5177.9741101295;5187.584993200535;5203.713795741271;5965.910511342333;6205.81382558524;6209.254889413209;6220.502359811204;6220.844478887973;5960.105984102419;6035.486983606443;6137.478314343152;6144.3717414884195;6145.016797312409;6283.654399007915;6298.244210694746;6300.779472718433;6308.873822523432;6311.053341998737;5621.634593751393;5723.42765723441;5995.91006134917;6007.8197654070445;6026;5886.264216972879;5943.791967281124;5953.047512397996;5955.821325360246;5985.191999080069;5908.586398952032;5925.555583331354;5950.7917222896995;5967.9701601491715;5970;5683.488486036276;5744.454276843693;5749.568782341257;5957.3744756799915;5959.642421454727;5770.105633626795;5802.6808525531505;5817.214676018683;5821.243238379153;5830.53355731549;5892.616979896465;5916.822495325147;5922.940770592411;5925.525957923442;5935.109733540004;5461.726913654381;5597.468240517144;5609.80365687199;5729.656220626775;5791.247137872049 09:37:10 INFO - 2016-02-08 09:37:10,187 DEBUG : BROWSER_OUTPUT: |1;ext.html;599.9670018149;636.2778246690007;645.4481418387276;646.9805905822822;647.139304724716;32727;41554.7922260389;41799.58200417995;42134;42183.945401364945;45406.09187816242;46022;46117;46325;46350.30474542876;8317.20986506284;8414.49513029217;8423.073461919184;9037.819243615124;9223.308251881102;8157.55133467659;8216;10848.566057357735;11047.668569942914;11054.392008439532;10729.892701072968;10822.891771082306;10827;10831.945840270788;10875.619353322636;10587.73530661732;10675.41285229312;10687.83968240477;10691.144708423308;10770.138388928866;10491.632792852213;10615.203859710553;10624.893751062546;10683.732906677318;10753.51609177589;40455;40526.39210411846;40526.79736601328;40598.79700601508;40814.79592602047;3666.6883314918264;3768.7550309229814;3781.0925377909102;3914;4047.433359329667;32050.358992820245;32248.35503289921;32353.83823080893;32543.837280813445;32591;4142.233686767958;4195.328747400401;4222.746635201897;4317.330813723878;4344.7827608619755;285380;285563;286342.9999999979;286344;287394;62955;63444.36555634477;63464;63464.36535634677;63570.36429635644;67980.66009670019;68185.65907170433;68466.315336847;68516;68748;17367.392141274995;17376.65246695059;17491.912540437217;17602.207900644502;17614.735778963324;13360.39878205483;13426.664333391744;13432.529861454803;13445.058845880694;13451.529196478075;20038;21188.470288242916;21365.78634213669;21404.143834246457;21551.461213469476;7838.333741631911;8178.427510074238;8188.426810123353;8232.958835205785;8287.337013038943;1007.1590222164575;1035.1925498111484;1071.8981696738797;1134.6709454258173;1137.4199158429096;31068;31124.68875311263;31137.377252455277;31863.681363186533;32586.837065814994;1107.7175320293434;1172.5720112159001;1173.7287503913176;1220.6155061155707;1241.900647948162;12529.561465348852;15022.924885375503;15378.769318460232;15427.691446171013;15651.060936343629;267504;268739;269903;270445.6477717638;346157;42560.14879702389;54768.45231547713;55577.444225558036;55825;55993.16010259849;1398.881095106907;1443.256722787765;1463.4512057978259;1464.699736554004;1465.4358072142088;26983.46033079288;27001.594976074983;30424.239394014443;30709.23226919345;31729.68270317267;50966.74516627393;51328;51518.484815151365;51681;51787.74106129596;265229;266002;266333;267276;267299;35149.824250878584;36026.45960310545;36126.27747444984;36197.63802361942;36202;47212.527874720814;47991.52008479871;48236.51763482461;48309;48472.5152748468;39684.801575991936;40304.79847600743;40739;40841.64850326851;41183.176336472505;39022;39310.80344598259;39828.515287743656;39914;39995;38782.80608596939;39198.216035679696;39991;40049.399259010555;40050.59949400469;35381;36323.63676363203;36470.8176459116;36641.63358366489;36690.26619467542;27654.585181221897;27755.30611734642;27868.44263114686;27875.442491149657;28232.294192644527 09:37:10 INFO - 2016-02-08 09:37:10,190 DEBUG : BROWSER_OUTPUT: |2;jquery.html;389.8713424569891;390.23708649590054;392.20370702184;396.48852979656243;396.7936672930076;16774.580635484104;17321.826781732194;17418.390356337513;18232.179551920162;19023;17658.735118973225;17698.557536061606;17704.380346687867;18129.184186711598;18160.36438724645;5414.242006119146;5596.804111856086;5603.635763675348;5606.579506537026;5608;5424.945750542503;5499.4225606311165;5540.2243685884005;5570.192322113291;5573.916391254114;4207.474065741782;4301.591348821857;5529.889402211953;5531.419200983899;5677.744501497443;5752.942470575283;5783.363829978699;5803.042497987827;5808.88382232353;5812.796552120677;5196.142636464979;5249.553787928031;5449.945500544984;5450.991566560199;5451.509364157245;17496;17785.288588456446;20422.387328380024;21740.782592174033;22086.11655533777;2410.192585483865;2426.2478631624253;2426.745191754858;2462.5198086372984;2512.359348366155;13994.160350378908;14080.929595351958;14328.64178395538;14351.78472322916;14527.854721452755;1888.7827899791425;1921.164293532318;1934.806519348068;1941.679622862226;1948.814862588052;61211.38788612101;63492.682536587025;63616.36383636151;63656;64387.67806160939;10439.582416703246;10594.258401911946;10781.784364312669;10815.297005694605;10882;14076.929615351857;14082.295885205698;14189;14205.715885682228;14230.501932432315;8469;8474.279686226617;8489.915100848912;8553.871691924625;8592.269657079094;8395.202455766692;8457.873131903027;8476.152384761597;8521;8529;6291.087792270096;6326.43062124411;6329.968350158312;6353;6384.872302553923;1824.8448881845193;1872.7659042619673;1894.2801735340759;1902.3341830359263;1913.56944687447;894.6376717429451;1022.4121130350078;1085.8968398002178;1151.9654410367702;1172.3962159487871;16390.590235243977;17359.305627775;17515.649687006444;17749.112544372987;18197.7270340945;973.2943615878573;984.468387070988;1049.6850944716555;1066.57337065173;1131.6491887514896;8564.914350856412;9843.852342214872;10307.84538231927;10347.183776477586;10611.522481488351;600851;602903;608596;622703;624897;83116.58441707752;84167;85090.57454712811;85180.57409713036;86422.56788716016;1196.1925700152362;1216.5559570756786;1226.3622916083764;1232.8458942632171;1247.3389103775014;15670.686586267982;16235.269412876685;16319.918400407922;16478.67042659116;16644.001359918442;37456.438153427174;38153.61846381501;38169;39068.413973790986;39080;47942.76028619835;47966.5203347976;49402.75298623628;49795.751021244665;51249.23126153185;10275.280730348804;10532.736681583021;10699.839502407625;10708.196885233794;10709.410982396043;17504.82495175032;17754.28982840672;17780.643742283224;18356.35752748701;18635.161417735962;14245;14440.711185776016;14461.49384771528;14523.491677791226;14771.482998095014;14587.562373128823;14590.19753913545;15205;15666.921665391601;15869.04785712861;14644;14653.340599673253;14877.181755003146;15877.603059923595;16081.035137891764;8124.83750325002;9910.405375677483;10346.430946298025;10697.411642359744;10900.127989760642;10355.585776568852;11731.706707332043;15566.377344906077;15781.842181578497;15949.920250398674 09:37:10 INFO - 2016-02-08 09:37:10,191 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1511.9017263877847;1594.6970075685615;1628.4463282483953;1759.5689056181238;1873.7657792775904;8189.7133600324005;8416.747497575083;8438.746837594874;8445.493270403773;8446.028706698731;7366.815829604249;7564.167941526428;7962.124166341698;8230.012398512186;8234.341252699785;2362.244081893799;2364.527094581082;2394.7605239476;2397.7482364351754;2397.928062158138;2127.8084972352485;2373.252425485975;2386.367612582667;2429.9392515187087;2432.185217951979;2119.576084783042;2312.9421764455938;2365.124903785602;2365.44412063165;2366.25462979161;2294.391986123687;2318.8260880434036;2322.8374013818957;2339.368370539945;2339.4970081432402;2373.6439534069855;2378.5837478441376;2399.5560821248123;2404.711434627856;2407.963880541793;8315.210055044761;8317.708880189164;8367.95816020916;8371.874421883616;8398.622062007224;4959.280904268897;5014.84955451337;5018.096742586333;5028.64799464034;5028.673136246169;3138.168385377877;3144.2296637323775;3186.9203269918207;3316.7512436567113;3340.131565792887;1069.5133714160115;2060.6702927531524;2064.5251592133895;2068.617305798432;2069.2964392106755;11668.1832271741;11721.589744358907;11769.352685602289;11790.17468777186;11803;4264.1471705659;4325.848595299212;4389.539098394686;4389.62688171509;4395.978020109879;8968.596413161424;8972.775680608038;8987.281017518582;8992.055834137322;9002.954985225031;4932.728699921467;4954.653174277766;4963.156263435225;4964.776585053682;4969.27945447908;4474.686771925999;4531.297648864432;4584.4957054723955;4587.816487340536;4590.862274131781;3689.708601989282;3705.9073523162137;3733.4586484959527;3733.6266373362446;3756.7933763643264;1000.6297669862198;1029.2537910015178;1059.5708737961138;1064.6060957445795;1127.464454384161;1173.0908545877014;1183.408295852074;1196.5752157983964;1443.3432788081398;1471.7865909443071;8094.51432914027;8109.229623185788;8177.836443271221;8212.17878212174;8217.096119426858;680.2721088435327;681.7375310505392;689.1970853955187;696.2550071423528;833.5040650812878;6207.472364849038;6312.684365781693;6314.273858506331;6315.242170939425;6320.304766475684;9721.854172187423;9988.05113514215;10010.899891001143;10019.348742331726;10059.64791232318;9859.556319965619;9963.501824908726;9969.501524923724;9982.550785214682;9985.550650220757;7664.9616751917;7888.566128862853;7959.522428654299;7966.402519811038;7976.401769867283;7434.405247580289;7888;7944.245296696805;7946.8807967879375;7957.8408431832195;8724.389292749447;8817.294616430541;9036.186743193144;9044.728658140266;9066.138716821892;9171.954140229524;9219.631214751334;9351.85972210431;9410.05899410081;9671.758206044906;7577.772666820004;7620.618969051415;7657.578833164007;7671.578063206577;7696.7306144284685;8130.67477300925;8173.67305307781;8202.097769245498;8203.261706446448;8217.096119426978;6399.3600639937495;7682.769516914502;7724.92275077242;7802;7806.843863122592;7016.929830701628;7737.1102323233545;7741.225877412214;7770.0287464067;7830.569318687527;7610.353119984863;7620.580868052089;7650.31147196755;7656.272654097852;7667.578283194478;6778.152730908637;6812.591244525344;6827.829304267433;6855.485838562028;6865.27914568963;7229.891551626835;7247.710091596277;7298.087739032621;7298.452616053711;7308.5614863108385 09:37:10 INFO - 2016-02-08 09:37:10,193 DEBUG : BROWSER_OUTPUT: |4;prototype.html;343.6838108939775;350.82458770614693;355.5289241754676;357.6584361934353;357.69595843533;6060.878782424349;6131.7240724167405;6149.8770024599535;6159.661218632973;6167.105769663396;5775.249217601717;5844.970775146129;5930.822075337736;6036.245469316335;6036.396360363961;4689.953100468986;4692.648051396141;4696.295555666643;4713.269443236287;4716.551927566876;4518.254488009475;4688.718676879381;4702.8118875244945;4706.411698537683;4720.079584481027;4524.932126018112;4566.6575006874455;4646.9070618587775;4679.508651591585;4680.110778952006;4656.301554766761;4659.394278743775;4666.813327466897;4678.415198100238;4678.485366609671;4128.566500517462;4134.152498737752;4134.813933373005;4141.772202528859;4142.46148000761;5653.321601407857;5781.335146458169;5791.581062639638;5791.594588378815;5795.855103622401;5418.268533747933;5420.783168673248;5423.240746295522;5431.348238211401;5434.429384914606;6439.581427207217;6440.5491615586925;6441.516886233504;6442.06590044441;6449.322821103763;4470.284754439306;4528.162289976365;4532.252178390561;4535.659825513099;4547.818087276505;6565.244996825379;6595.0766892635065;6607.471402287805;6659.9001014984815;6677.699503522304;4574.588287054181;4575.062112266977;4578.084383123389;4578.816847326069;4601.447826260804;4641.094986477672;4641.582257596832;4690.882727931829;4700.7179569225955;4706.764661766898;5327.227552005015;5389.326334208224;5390.029794636925;5398.379186393615;5400.486953739389;6321.96839015802;6436.517261205428;6440.903386449207;6456.160699109086;6459.063435801782;1624.6913086513525;1646.7941507311587;1655.42887703742;1655.4454257823763;1655.998121136719;1052.6631477927142;1064.090202876545;1065.8294672852462;1082.1667316166508;1102.4377567440663;6281.811545653638;6284.622922624657;6289.905651415233;6290.685465726695;6296.244450665949;6420.807375778734;6648.800535983928;6651.567648102858;6655;6655.633940133339;6212.875742485125;6217.129601855744;6394.776182833579;6396.232452105777;6399.584027038228;3551.4317709166407;3599.136207310227;3601.7838929664304;3606.9278614427562;3611.3499570077115;6787.423069039089;6920.30796920314;6946.51374403797;6948.235694073648;6959.652017399109;6853.554518956252;6860.142482189726;6871.484638652121;6873.347032031949;6881.621510816954;6919.481038922101;6942.340477654615;6943.062686537354;6951.409130223988;6951.860962780716;6563.868722625616;6667.899981500184;6676.632785196861;6693.397594216543;6698.062271282122;6587.901181482377;6608.8347791305605;6614.074029635759;6624.470042396693;6625.17185351831;6933.0986971693355;6940.479464040116;6944.305569443015;6946.166460024628;6950.374466298057;4552.11233809404;4614.838480653161;4621.676482646182;4627.167109920247;4629.375034370317;6691.364320389555;6701.9947007949195;6710.899336509859;6712.597244165366;6713.295104014203;6521.347865213441;6527.445167160845;6530.8040758777315;6536.901946470902;6537.248216455168;6396.072569477493;6474.223093228843;6503.577267477599;6521.478281737544;6524.184476940382;6405.167328247298;6416.807495775134;6422.935770642233;6428;6430;6494.6752662367635;6506.251781045051;6553.377429144223;6555.836104097436;6560.606363618198;6258.374162583706;6351;6372.58578192416;6382.712777925098;6410.45511131559 09:37:10 INFO - 2016-02-08 09:37:10,194 DEBUG : BROWSER_OUTPUT: |5;yui.html;816.5957850863823;826.7681155078934;867.8481265778487;869.4261787220436;917.257021812332;14568;14843.332050057747;14844.777328340084;14859.40562377505;14869.330880110394;11410.600628977987;13735.96980226482;13909.513167039157;14016.509422170226;14041.578752637412;882.6337070115894;893.0399820193318;895.3598177303227;895.6417433026776;895.977600559988;893.9016708162097;897.7710683775628;897.9506127163004;898.6180873128927;898.7618281155499;936.6534382278567;942.528735632184;943.03810113684;943.3915124744535;944.0983860413289;935.3031991166556;935.471458625881;935.7847695030113;935.9812803743921;938.7090002099337;690.5545922879764;691.550492180087;692.2385376086315;693.9236683964759;695.5826504097552;13376.46494140243;13525.188488690612;13527.932360338134;13541.1198272112;13543.59369218925;11367.317960922372;11398.202125851194;11583.18917675763;11632.767344653144;11957.222780519325;10580.52387642558;10733.570657173703;10847.457627118612;10899.1280697544;10980.066694330992;3741.9438708419398;3835.1946091320856;3870.3807390817333;3882.107115363454;3891.1633998690418;42455.15089698189;43340.78329608363;47129.764351178026;48078.75960620175;48245.517544824455;9607.375520591138;9679.467629280316;9760.560774765152;9825.557849896772;10170.796584068277;18147.18337674808;18196.54508637268;18286.45140645783;18335.174917128763;18357.54106147357;8429.03066147401;8470.49177049379;8470.576471176417;8541.829163416696;8704;4633.582977532038;4676.929846052312;4707.764611769398;4761.4524329701835;4786.138495070921;3163;3186.1238159505856;3188.3623275345026;3190.0589326148843;3192.6009248843893;557.6263903184876;576.20771439271;577.6129992904764;582.3710392775791;588.5232961301274;690.4890381118037;700.0619170311705;725.2095216214352;727.105660038156;756.6632848382527;8101.432899697083;8405.537695426689;8476.576171191538;8506.064332923374;8686.913130868737;693.8404167041608;696.3210869402292;718.2207305074053;720.6648908257686;726.2048057377164;4274.829006839754;4295.463067116611;4324.200022995756;4329.523752387235;4429.461761983289;57250.42749572451;60174.699126504085;60583.697081515194;60706;60885.695571522745;49121;49524.50475495271;51061.23408148881;51624.74187629113;53417.46582534203;46737.2989405166;46905.765471172424;47253;47415.52584474111;47781.52218477771;8366.414350995372;8615.45832002544;8771.956140219258;8779.604917778588;8789.077146899483;36420.27159456743;36646.26707465889;37017.99999999892;37094;37853.242935140595;42492.57507424886;43473.56526434695;45549.77225113853;45614.543854562355;45838.77080614575;10719.303245289031;10742.73143171427;10781.676549703521;10874.510647021061;11028.393438360647;11313.151513636674;12064.698382540506;12313;12430.937845310715;12515.36317035859;11143.554257829595;11165.944170279097;11288.2662626929;11343.20597558163;11435.085193184692;10418.531166097695;10681.839772403264;10975.670729878117;11011.28426652265;11015.944920275348;10333;10564.207684423545;10829.350238985686;10902.18233632465;11000.724981875517;9927.453990030328;10044.748881278027;10244.129249013917;10419;10455.47722613851;9226.446413215228;9553.140217380469;9556.139947404767;9635.084666956627;9802.36284641496 09:37:10 INFO - 2016-02-08 09:37:10,194 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:37:10 INFO - 2016-02-08 09:37:10,194 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:37:10 INFO - 2016-02-08 09:37:10,194 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,830 09:37:10 INFO - 2016-02-08 09:37:10,195 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:37:10 INFO - 2016-02-08 09:37:10,195 DEBUG : BROWSER_OUTPUT: __startTimestamp1454953030181__endTimestamp 09:37:10 INFO - 2016-02-08 09:37:10,195 DEBUG : BROWSER_OUTPUT: 09:37:10 INFO - 2016-02-08 09:37:10,195 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:37:10 INFO - 2016-02-08 09:37:10,195 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:37:10 INFO - 2016-02-08 09:37:10,195 DEBUG : BROWSER_OUTPUT: 09:37:10 INFO - 2016-02-08 09:37:10,195 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5545.42 Median:5750.11 stddev:634.60 (11.0%) stddev-sans-first:611.09 09:37:10 INFO - 2016-02-08 09:37:10,195 DEBUG : BROWSER_OUTPUT: Values: 3277.0 3358.8 3393.3 3399.7 3413.5 5544.9 5561.9 5564.9 5590.7 5594.1 5569.7 5620.7 5620.9 5624.0 5624.8 4881.5 4941.9 5088.1 5114.4 5176.4 4963.7 5016.0 5021.8 5024.8 5025.5 4983.1 5052.8 5082.2 5099.3 5101.6 5004.7 5080.2 5093.8 5096.1 5102.6 5080.8 5156.9 5183.6 5193.5 5207.0 5184.2 5237.5 5269.2 5334.5 5378.4 5931.1 5995.3 6044.7 6057.4 6060.6 6003.5 6069.6 6092.5 6096.9 6110.3 5369.4 5424.4 5479.6 5504.9 5518.8 6085.2 6125.0 6127.4 6129.6 6133.6 5574.7 5901.6 5918.2 5919.7 5927.6 5680.4 5883.3 5928.9 5942.9 5964.9 5876.2 5891.9 5922.8 5924.3 5932.4 5652.0 5760.6 5919.3 5919.7 5929.8 4403.2 4446.5 4447.3 4450.0 4450.8 4028.2 4055.2 4065.7 4119.2 4134.4 5750.1 5809.8 5811.0 5811.7 5813.1 6077.3 6101.7 6104.3 6109.6 6113.2 5498.6 5504.4 5556.4 5574.7 5691.4 5167.0 5175.7 5178.0 5187.6 5203.7 5965.9 6205.8 6209.3 6220.5 6220.8 5960.1 6035.5 6137.5 6144.4 6145.0 6283.7 6298.2 6300.8 6308.9 6311.1 5621.6 5723.4 5995.9 6007.8 6026.0 5886.3 5943.8 5953.0 5955.8 5985.2 5908.6 5925.6 5950.8 5968.0 5970.0 5683.5 5744.5 5749.6 5957.4 5959.6 5770.1 5802.7 5817.2 5821.2 5830.5 5892.6 5916.8 5922.9 5925.5 5935.1 5461.7 5597.5 5609.8 5729.7 5791.2 09:37:10 INFO - 2016-02-08 09:37:10,195 DEBUG : BROWSER_OUTPUT: 09:37:10 INFO - 2016-02-08 09:37:10,195 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:47204.22 Median:35703.73 stddev:72720.99 (203.7%) stddev-sans-first:72840.79 09:37:10 INFO - 2016-02-08 09:37:10,196 DEBUG : BROWSER_OUTPUT: Values: 600.0 636.3 645.4 647.0 647.1 32727.0 41554.8 41799.6 42134.0 42183.9 45406.1 46022.0 46117.0 46325.0 46350.3 8317.2 8414.5 8423.1 9037.8 9223.3 8157.6 8216.0 10848.6 11047.7 11054.4 10729.9 10822.9 10827.0 10831.9 10875.6 10587.7 10675.4 10687.8 10691.1 10770.1 10491.6 10615.2 10624.9 10683.7 10753.5 40455.0 40526.4 40526.8 40598.8 40814.8 3666.7 3768.8 3781.1 3914.0 4047.4 32050.4 32248.4 32353.8 32543.8 32591.0 4142.2 4195.3 4222.7 4317.3 4344.8 285380.0 285563.0 286343.0 286344.0 287394.0 62955.0 63444.4 63464.0 63464.4 63570.4 67980.7 68185.7 68466.3 68516.0 68748.0 17367.4 17376.7 17491.9 17602.2 17614.7 13360.4 13426.7 13432.5 13445.1 13451.5 20038.0 21188.5 21365.8 21404.1 21551.5 7838.3 8178.4 8188.4 8233.0 8287.3 1007.2 1035.2 1071.9 1134.7 1137.4 31068.0 31124.7 31137.4 31863.7 32586.8 1107.7 1172.6 1173.7 1220.6 1241.9 12529.6 15022.9 15378.8 15427.7 15651.1 267504.0 268739.0 269903.0 270445.6 346157.0 42560.1 54768.5 55577.4 55825.0 55993.2 1398.9 1443.3 1463.5 1464.7 1465.4 26983.5 27001.6 30424.2 30709.2 31729.7 50966.7 51328.0 51518.5 51681.0 51787.7 265229.0 266002.0 266333.0 267276.0 267299.0 35149.8 36026.5 36126.3 36197.6 36202.0 47212.5 47991.5 48236.5 48309.0 48472.5 39684.8 40304.8 40739.0 40841.6 41183.2 39022.0 39310.8 39828.5 39914.0 39995.0 38782.8 39198.2 39991.0 40049.4 40050.6 35381.0 36323.6 36470.8 36641.6 36690.3 27654.6 27755.3 27868.4 27875.4 28232.3 09:37:10 INFO - 2016-02-08 09:37:10,197 DEBUG : BROWSER_OUTPUT: 09:37:10 INFO - 2016-02-08 09:37:10,197 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31776.93 Median:1917.37 stddev:99910.71 (5210.8%) stddev-sans-first:100163.19 09:37:10 INFO - 2016-02-08 09:37:10,198 DEBUG : BROWSER_OUTPUT: Values: 389.9 390.2 392.2 396.5 396.8 16774.6 17321.8 17418.4 18232.2 19023.0 17658.7 17698.6 17704.4 18129.2 18160.4 5414.2 5596.8 5603.6 5606.6 5608.0 5424.9 5499.4 5540.2 5570.2 5573.9 4207.5 4301.6 5529.9 5531.4 5677.7 5752.9 5783.4 5803.0 5808.9 5812.8 5196.1 5249.6 5449.9 5451.0 5451.5 17496.0 17785.3 20422.4 21740.8 22086.1 2410.2 2426.2 2426.7 2462.5 2512.4 13994.2 14080.9 14328.6 14351.8 14527.9 1888.8 1921.2 1934.8 1941.7 1948.8 61211.4 63492.7 63616.4 63656.0 64387.7 10439.6 10594.3 10781.8 10815.3 10882.0 14076.9 14082.3 14189.0 14205.7 14230.5 8469.0 8474.3 8489.9 8553.9 8592.3 8395.2 8457.9 8476.2 8521.0 8529.0 6291.1 6326.4 6330.0 6353.0 6384.9 1824.8 1872.8 1894.3 1902.3 1913.6 894.6 1022.4 1085.9 1152.0 1172.4 16390.6 17359.3 17515.6 17749.1 18197.7 973.3 984.5 1049.7 1066.6 1131.6 8564.9 9843.9 10307.8 10347.2 10611.5 600851.0 602903.0 608596.0 622703.0 624897.0 83116.6 84167.0 85090.6 85180.6 86422.6 1196.2 1216.6 1226.4 1232.8 1247.3 15670.7 16235.3 16319.9 16478.7 16644.0 37456.4 38153.6 38169.0 39068.4 39080.0 47942.8 47966.5 49402.8 49795.8 51249.2 10275.3 10532.7 10699.8 10708.2 10709.4 17504.8 17754.3 17780.6 18356.4 18635.2 14245.0 14440.7 14461.5 14523.5 14771.5 14587.6 14590.2 15205.0 15666.9 15869.0 14644.0 14653.3 14877.2 15877.6 16081.0 8124.8 9910.4 10346.4 10697.4 10900.1 10355.6 11731.7 15566.4 15781.8 15949.9 09:37:10 INFO - 2016-02-08 09:37:10,198 DEBUG : BROWSER_OUTPUT: 09:37:10 INFO - 2016-02-08 09:37:10,198 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5850.20 Median:681.00 stddev:3053.14 (448.3%) stddev-sans-first:3044.29 09:37:10 INFO - 2016-02-08 09:37:10,199 DEBUG : BROWSER_OUTPUT: Values: 1511.9 1594.7 1628.4 1759.6 1873.8 8189.7 8416.7 8438.7 8445.5 8446.0 7366.8 7564.2 7962.1 8230.0 8234.3 2362.2 2364.5 2394.8 2397.7 2397.9 2127.8 2373.3 2386.4 2429.9 2432.2 2119.6 2312.9 2365.1 2365.4 2366.3 2294.4 2318.8 2322.8 2339.4 2339.5 2373.6 2378.6 2399.6 2404.7 2408.0 8315.2 8317.7 8368.0 8371.9 8398.6 4959.3 5014.8 5018.1 5028.6 5028.7 3138.2 3144.2 3186.9 3316.8 3340.1 1069.5 2060.7 2064.5 2068.6 2069.3 11668.2 11721.6 11769.4 11790.2 11803.0 4264.1 4325.8 4389.5 4389.6 4396.0 8968.6 8972.8 8987.3 8992.1 9003.0 4932.7 4954.7 4963.2 4964.8 4969.3 4474.7 4531.3 4584.5 4587.8 4590.9 3689.7 3705.9 3733.5 3733.6 3756.8 1000.6 1029.3 1059.6 1064.6 1127.5 1173.1 1183.4 1196.6 1443.3 1471.8 8094.5 8109.2 8177.8 8212.2 8217.1 680.3 681.7 689.2 696.3 833.5 6207.5 6312.7 6314.3 6315.2 6320.3 9721.9 9988.1 10010.9 10019.3 10059.6 9859.6 9963.5 9969.5 9982.6 9985.6 7665.0 7888.6 7959.5 7966.4 7976.4 7434.4 7888.0 7944.2 7946.9 7957.8 8724.4 8817.3 9036.2 9044.7 9066.1 9172.0 9219.6 9351.9 9410.1 9671.8 7577.8 7620.6 7657.6 7671.6 7696.7 8130.7 8173.7 8202.1 8203.3 8217.1 6399.4 7682.8 7724.9 7802.0 7806.8 7016.9 7737.1 7741.2 7770.0 7830.6 7610.4 7620.6 7650.3 7656.3 7667.6 6778.2 6812.6 6827.8 6855.5 6865.3 7229.9 7247.7 7298.1 7298.5 7308.6 09:37:10 INFO - 2016-02-08 09:37:10,199 DEBUG : BROWSER_OUTPUT: 09:37:10 INFO - 2016-02-08 09:37:10,199 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5420.89 Median:6215.00 stddev:1631.22 (26.2%) stddev-sans-first:1590.66 09:37:10 INFO - 2016-02-08 09:37:10,199 DEBUG : BROWSER_OUTPUT: Values: 343.7 350.8 355.5 357.7 357.7 6060.9 6131.7 6149.9 6159.7 6167.1 5775.2 5845.0 5930.8 6036.2 6036.4 4690.0 4692.6 4696.3 4713.3 4716.6 4518.3 4688.7 4702.8 4706.4 4720.1 4524.9 4566.7 4646.9 4679.5 4680.1 4656.3 4659.4 4666.8 4678.4 4678.5 4128.6 4134.2 4134.8 4141.8 4142.5 5653.3 5781.3 5791.6 5791.6 5795.9 5418.3 5420.8 5423.2 5431.3 5434.4 6439.6 6440.5 6441.5 6442.1 6449.3 4470.3 4528.2 4532.3 4535.7 4547.8 6565.2 6595.1 6607.5 6659.9 6677.7 4574.6 4575.1 4578.1 4578.8 4601.4 4641.1 4641.6 4690.9 4700.7 4706.8 5327.2 5389.3 5390.0 5398.4 5400.5 6322.0 6436.5 6440.9 6456.2 6459.1 1624.7 1646.8 1655.4 1655.4 1656.0 1052.7 1064.1 1065.8 1082.2 1102.4 6281.8 6284.6 6289.9 6290.7 6296.2 6420.8 6648.8 6651.6 6655.0 6655.6 6212.9 6217.1 6394.8 6396.2 6399.6 3551.4 3599.1 3601.8 3606.9 3611.3 6787.4 6920.3 6946.5 6948.2 6959.7 6853.6 6860.1 6871.5 6873.3 6881.6 6919.5 6942.3 6943.1 6951.4 6951.9 6563.9 6667.9 6676.6 6693.4 6698.1 6587.9 6608.8 6614.1 6624.5 6625.2 6933.1 6940.5 6944.3 6946.2 6950.4 4552.1 4614.8 4621.7 4627.2 4629.4 6691.4 6702.0 6710.9 6712.6 6713.3 6521.3 6527.4 6530.8 6536.9 6537.2 6396.1 6474.2 6503.6 6521.5 6524.2 6405.2 6416.8 6422.9 6428.0 6430.0 6494.7 6506.3 6553.4 6555.8 6560.6 6258.4 6351.0 6372.6 6382.7 6410.5 09:37:10 INFO - 2016-02-08 09:37:10,199 DEBUG : BROWSER_OUTPUT: 09:37:10 INFO - 2016-02-08 09:37:10,199 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13950.13 Median:25806.77 stddev:16080.14 (62.3%) stddev-sans-first:16095.01 09:37:10 INFO - 2016-02-08 09:37:10,199 DEBUG : BROWSER_OUTPUT: Values: 816.6 826.8 867.8 869.4 917.3 14568.0 14843.3 14844.8 14859.4 14869.3 11410.6 13736.0 13909.5 14016.5 14041.6 882.6 893.0 895.4 895.6 896.0 893.9 897.8 898.0 898.6 898.8 936.7 942.5 943.0 943.4 944.1 935.3 935.5 935.8 936.0 938.7 690.6 691.6 692.2 693.9 695.6 13376.5 13525.2 13527.9 13541.1 13543.6 11367.3 11398.2 11583.2 11632.8 11957.2 10580.5 10733.6 10847.5 10899.1 10980.1 3741.9 3835.2 3870.4 3882.1 3891.2 42455.2 43340.8 47129.8 48078.8 48245.5 9607.4 9679.5 9760.6 9825.6 10170.8 18147.2 18196.5 18286.5 18335.2 18357.5 8429.0 8470.5 8470.6 8541.8 8704.0 4633.6 4676.9 4707.8 4761.5 4786.1 3163.0 3186.1 3188.4 3190.1 3192.6 557.6 576.2 577.6 582.4 588.5 690.5 700.1 725.2 727.1 756.7 8101.4 8405.5 8476.6 8506.1 8686.9 693.8 696.3 718.2 720.7 726.2 4274.8 4295.5 4324.2 4329.5 4429.5 57250.4 60174.7 60583.7 60706.0 60885.7 49121.0 49524.5 51061.2 51624.7 53417.5 46737.3 46905.8 47253.0 47415.5 47781.5 8366.4 8615.5 8772.0 8779.6 8789.1 36420.3 36646.3 37018.0 37094.0 37853.2 42492.6 43473.6 45549.8 45614.5 45838.8 10719.3 10742.7 10781.7 10874.5 11028.4 11313.2 12064.7 12313.0 12430.9 12515.4 11143.6 11165.9 11288.3 11343.2 11435.1 10418.5 10681.8 10975.7 11011.3 11015.9 10333.0 10564.2 10829.4 10902.2 11000.7 9927.5 10044.7 10244.1 10419.0 10455.5 9226.4 9553.1 9556.1 9635.1 9802.4 09:37:10 INFO - 2016-02-08 09:37:10,199 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:37:10 INFO - 2016-02-08 09:37:10,199 DEBUG : BROWSER_OUTPUT: 09:37:10 INFO - 2016-02-08 09:37:10,745 INFO : Browser exited with error code: 0 09:37:10 INFO - 2016-02-08 09:37:10,763 INFO : Completed test dromaeo_css (00:18:28) 09:37:10 INFO - 2016-02-08 09:37:10,764 INFO : Starting test kraken 09:37:10 INFO - 2016-02-08 09:37:10,764 DEBUG : operating with platform_type : linux_ 09:37:10 INFO - 2016-02-08 09:37:10,764 INFO : Initialising browser for kraken test... 09:37:10 INFO - 2016-02-08 09:37:10,770 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpG94zKe/profile http://localhost:49553/getInfo.html 09:37:14 INFO - 2016-02-08 09:37:14,238 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:37:14 INFO - 2016-02-08 09:37:14,239 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:37:14 INFO - 2016-02-08 09:37:14,239 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:37:14 INFO - 2016-02-08 09:37:14,239 DEBUG : BROWSER_OUTPUT: __metrics 09:37:14 INFO - 2016-02-08 09:37:14,361 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] 09:37:15 INFO - 2016-02-08 09:37:15,089 INFO : Browser initialized. 09:37:15 INFO - 2016-02-08 09:37:15,090 INFO : Running cycle 1/1 for kraken test... 09:37:15 INFO - 2016-02-08 09:37:15,090 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpG94zKe/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:37:26 INFO - 2016-02-08 09:37:26,854 DEBUG : BROWSER_OUTPUT: RSS: Main: 134520832 09:37:26 INFO - 2016-02-08 09:37:26,854 DEBUG : BROWSER_OUTPUT: 09:37:29 INFO - 2016-02-08 09:37:29,289 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49553/tests/kraken/driver.html?testName=audio-beat-detection) 09:37:29 INFO - 2016-02-08 09:37:29,870 DEBUG : BROWSER_OUTPUT: RSS: Main: 165621760 09:37:29 INFO - 2016-02-08 09:37:29,870 DEBUG : BROWSER_OUTPUT: 09:37:32 INFO - 2016-02-08 09:37:32,431 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49553/tests/kraken/driver.html?testName=audio-dft) 09:37:33 INFO - 2016-02-08 09:37:33,005 DEBUG : BROWSER_OUTPUT: RSS: Main: 162627584 09:37:33 INFO - 2016-02-08 09:37:33,005 DEBUG : BROWSER_OUTPUT: 09:37:35 INFO - 2016-02-08 09:37:35,802 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49553/tests/kraken/driver.html?testName=audio-fft) 09:37:36 INFO - 2016-02-08 09:37:36,368 DEBUG : BROWSER_OUTPUT: RSS: Main: 161206272 09:37:36 INFO - 2016-02-08 09:37:36,369 DEBUG : BROWSER_OUTPUT: 09:37:38 INFO - 2016-02-08 09:37:38,366 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49553/tests/kraken/driver.html?testName=audio-oscillator) 09:37:38 INFO - 2016-02-08 09:37:38,927 DEBUG : BROWSER_OUTPUT: RSS: Main: 160985088 09:37:38 INFO - 2016-02-08 09:37:38,927 DEBUG : BROWSER_OUTPUT: 09:37:41 INFO - 2016-02-08 09:37:41,082 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49553/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:37:41 INFO - 2016-02-08 09:37:41,644 DEBUG : BROWSER_OUTPUT: RSS: Main: 158978048 09:37:41 INFO - 2016-02-08 09:37:41,644 DEBUG : BROWSER_OUTPUT: 09:37:44 INFO - 2016-02-08 09:37:44,490 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49553/tests/kraken/driver.html?testName=imaging-darkroom) 09:37:45 INFO - 2016-02-08 09:37:45,060 DEBUG : BROWSER_OUTPUT: RSS: Main: 158138368 09:37:45 INFO - 2016-02-08 09:37:45,060 DEBUG : BROWSER_OUTPUT: 09:37:48 INFO - 2016-02-08 09:37:48,364 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49553/tests/kraken/driver.html?testName=imaging-desaturate) 09:37:48 INFO - 2016-02-08 09:37:48,926 DEBUG : BROWSER_OUTPUT: RSS: Main: 158498816 09:37:48 INFO - 2016-02-08 09:37:48,927 DEBUG : BROWSER_OUTPUT: 09:37:51 INFO - 2016-02-08 09:37:51,616 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49553/tests/kraken/driver.html?testName=json-parse-financial) 09:37:52 INFO - 2016-02-08 09:37:52,190 DEBUG : BROWSER_OUTPUT: RSS: Main: 159948800 09:37:52 INFO - 2016-02-08 09:37:52,190 DEBUG : BROWSER_OUTPUT: 09:37:53 INFO - 2016-02-08 09:37:53,751 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49553/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:37:54 INFO - 2016-02-08 09:37:54,318 DEBUG : BROWSER_OUTPUT: RSS: Main: 165892096 09:37:54 INFO - 2016-02-08 09:37:54,318 DEBUG : BROWSER_OUTPUT: 09:37:56 INFO - 2016-02-08 09:37:56,122 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49553/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:37:56 INFO - 2016-02-08 09:37:56,716 DEBUG : BROWSER_OUTPUT: RSS: Main: 162721792 09:37:56 INFO - 2016-02-08 09:37:56,716 DEBUG : BROWSER_OUTPUT: 09:37:58 INFO - 2016-02-08 09:37:58,628 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49553/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:37:59 INFO - 2016-02-08 09:37:59,190 DEBUG : BROWSER_OUTPUT: RSS: Main: 169148416 09:37:59 INFO - 2016-02-08 09:37:59,190 DEBUG : BROWSER_OUTPUT: 09:38:01 INFO - 2016-02-08 09:38:01,506 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49553/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:38:02 INFO - 2016-02-08 09:38:02,092 DEBUG : BROWSER_OUTPUT: RSS: Main: 174174208 09:38:02 INFO - 2016-02-08 09:38:02,092 DEBUG : BROWSER_OUTPUT: 09:38:04 INFO - 2016-02-08 09:38:04,558 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49553/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:38:05 INFO - 2016-02-08 09:38:05,124 DEBUG : BROWSER_OUTPUT: RSS: Main: 173244416 09:38:05 INFO - 2016-02-08 09:38:05,124 DEBUG : BROWSER_OUTPUT: 09:38:06 INFO - 2016-02-08 09:38:06,758 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49553/tests/kraken/driver.html?testName=ai-astar) 09:38:07 INFO - 2016-02-08 09:38:07,008 DEBUG : BROWSER_OUTPUT: RSS: Main: 230776832 09:38:07 INFO - 2016-02-08 09:38:07,008 DEBUG : BROWSER_OUTPUT: 09:38:07 INFO - 2016-02-08 09:38:07,008 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:38:07 INFO - 2016-02-08 09:38:07,008 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:38:07 INFO - 2016-02-08 09:38:07,008 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:38:07 INFO - 2016-02-08 09:38:07,009 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:38:07 INFO - 2016-02-08 09:38:07,009 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;110;107;111;113;109;100;109;110;109 09:38:07 INFO - 2016-02-08 09:38:07,009 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;142;122;143;154;135;150;157;124;157;122 09:38:07 INFO - 2016-02-08 09:38:07,009 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;159;161;158;159;161;161;162;160;160 09:38:07 INFO - 2016-02-08 09:38:07,009 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;70;72;149;74;72;72;71;152;71 09:38:07 INFO - 2016-02-08 09:38:07,009 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;93;133;92;92;92;147;92;92;133 09:38:07 INFO - 2016-02-08 09:38:07,009 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;109;109;109;110 09:38:07 INFO - 2016-02-08 09:38:07,010 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;145;146;147;146;146;145;146;145;145 09:38:07 INFO - 2016-02-08 09:38:07,010 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;96;95;95;95;95;95;95 09:38:07 INFO - 2016-02-08 09:38:07,010 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;59;57;56;56;58;57;57;58;57;56 09:38:07 INFO - 2016-02-08 09:38:07,010 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;72;63;64;64;65;64;65;64;65 09:38:07 INFO - 2016-02-08 09:38:07,010 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;75;77;78;77;80;79;79;78;78 09:38:07 INFO - 2016-02-08 09:38:07,010 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;114;112;111;114;114;114;115;114;111;117 09:38:07 INFO - 2016-02-08 09:38:07,010 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;142;140;146;141;138;143;138;139;130 09:38:07 INFO - 2016-02-08 09:38:07,011 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;63;67;64;64;63;67;65;64;63 09:38:07 INFO - 2016-02-08 09:38:07,011 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:38:07 INFO - 2016-02-08 09:38:07,011 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:38:07 INFO - 2016-02-08 09:38:07,011 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,902 09:38:07 INFO - 2016-02-08 09:38:07,011 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:38:07 INFO - 2016-02-08 09:38:07,011 DEBUG : BROWSER_OUTPUT: __startTimestamp1454953087006__endTimestamp 09:38:07 INFO - 2016-02-08 09:38:07,011 DEBUG : BROWSER_OUTPUT: 09:38:07 INFO - 2016-02-08 09:38:07,015 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:38:07 INFO - 2016-02-08 09:38:07,015 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:38:07 INFO - 2016-02-08 09:38:07,015 DEBUG : BROWSER_OUTPUT: 09:38:07 INFO - 2016-02-08 09:38:07,015 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.70 Median:109.50 stddev:3.43 (3.1%) stddev-sans-first:3.64 09:38:07 INFO - 2016-02-08 09:38:07,015 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 107.0 111.0 113.0 109.0 100.0 109.0 110.0 109.0 09:38:07 INFO - 2016-02-08 09:38:07,015 DEBUG : BROWSER_OUTPUT: 09:38:07 INFO - 2016-02-08 09:38:07,015 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.60 Median:146.50 stddev:14.19 (9.7%) stddev-sans-first:15.04 09:38:07 INFO - 2016-02-08 09:38:07,015 DEBUG : BROWSER_OUTPUT: Values: 142.0 122.0 143.0 154.0 135.0 150.0 157.0 124.0 157.0 122.0 09:38:07 INFO - 2016-02-08 09:38:07,015 DEBUG : BROWSER_OUTPUT: 09:38:07 INFO - 2016-02-08 09:38:07,015 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.10 Median:160.50 stddev:1.20 (0.7%) stddev-sans-first:1.27 09:38:07 INFO - 2016-02-08 09:38:07,015 DEBUG : BROWSER_OUTPUT: Values: 160.0 159.0 161.0 158.0 159.0 161.0 161.0 162.0 160.0 160.0 09:38:07 INFO - 2016-02-08 09:38:07,015 DEBUG : BROWSER_OUTPUT: 09:38:07 INFO - 2016-02-08 09:38:07,016 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.60 Median:72.00 stddev:33.18 (46.1%) stddev-sans-first:34.77 09:38:07 INFO - 2016-02-08 09:38:07,016 DEBUG : BROWSER_OUTPUT: Values: 73.0 70.0 72.0 149.0 74.0 72.0 72.0 71.0 152.0 71.0 09:38:07 INFO - 2016-02-08 09:38:07,016 DEBUG : BROWSER_OUTPUT: 09:38:07 INFO - 2016-02-08 09:38:07,016 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.80 Median:92.00 stddev:22.32 (24.3%) stddev-sans-first:23.11 09:38:07 INFO - 2016-02-08 09:38:07,016 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 133.0 92.0 92.0 92.0 147.0 92.0 92.0 133.0 09:38:07 INFO - 2016-02-08 09:38:07,016 DEBUG : BROWSER_OUTPUT: 09:38:07 INFO - 2016-02-08 09:38:07,016 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:38:07 INFO - 2016-02-08 09:38:07,016 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 110.0 09:38:07 INFO - 2016-02-08 09:38:07,016 DEBUG : BROWSER_OUTPUT: 09:38:07 INFO - 2016-02-08 09:38:07,016 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 09:38:07 INFO - 2016-02-08 09:38:07,016 DEBUG : BROWSER_OUTPUT: Values: 146.0 145.0 146.0 147.0 146.0 146.0 145.0 146.0 145.0 145.0 09:38:07 INFO - 2016-02-08 09:38:07,016 DEBUG : BROWSER_OUTPUT: 09:38:07 INFO - 2016-02-08 09:38:07,016 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 09:38:07 INFO - 2016-02-08 09:38:07,016 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 09:38:07 INFO - 2016-02-08 09:38:07,016 DEBUG : BROWSER_OUTPUT: 09:38:07 INFO - 2016-02-08 09:38:07,016 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.10 Median:57.00 stddev:0.99 (1.7%) stddev-sans-first:0.78 09:38:07 INFO - 2016-02-08 09:38:07,017 DEBUG : BROWSER_OUTPUT: Values: 59.0 57.0 56.0 56.0 58.0 57.0 57.0 58.0 57.0 56.0 09:38:07 INFO - 2016-02-08 09:38:07,017 DEBUG : BROWSER_OUTPUT: 09:38:07 INFO - 2016-02-08 09:38:07,017 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:65.00 Median:64.50 stddev:2.54 (3.9%) stddev-sans-first:2.67 09:38:07 INFO - 2016-02-08 09:38:07,017 DEBUG : BROWSER_OUTPUT: Values: 64.0 72.0 63.0 64.0 64.0 65.0 64.0 65.0 64.0 65.0 09:38:07 INFO - 2016-02-08 09:38:07,017 DEBUG : BROWSER_OUTPUT: 09:38:07 INFO - 2016-02-08 09:38:07,017 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.70 Median:78.00 stddev:1.49 (1.9%) stddev-sans-first:1.45 09:38:07 INFO - 2016-02-08 09:38:07,017 DEBUG : BROWSER_OUTPUT: Values: 76.0 75.0 77.0 78.0 77.0 80.0 79.0 79.0 78.0 78.0 09:38:07 INFO - 2016-02-08 09:38:07,017 DEBUG : BROWSER_OUTPUT: 09:38:07 INFO - 2016-02-08 09:38:07,017 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:113.60 Median:114.00 stddev:1.84 (1.6%) stddev-sans-first:1.94 09:38:07 INFO - 2016-02-08 09:38:07,017 DEBUG : BROWSER_OUTPUT: Values: 114.0 112.0 111.0 114.0 114.0 114.0 115.0 114.0 111.0 117.0 09:38:07 INFO - 2016-02-08 09:38:07,017 DEBUG : BROWSER_OUTPUT: 09:38:07 INFO - 2016-02-08 09:38:07,017 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:139.60 Median:140.50 stddev:4.20 (3.0%) stddev-sans-first:4.44 09:38:07 INFO - 2016-02-08 09:38:07,017 DEBUG : BROWSER_OUTPUT: Values: 139.0 142.0 140.0 146.0 141.0 138.0 143.0 138.0 139.0 130.0 09:38:07 INFO - 2016-02-08 09:38:07,017 DEBUG : BROWSER_OUTPUT: 09:38:07 INFO - 2016-02-08 09:38:07,017 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:64.50 stddev:1.58 (2.4%) stddev-sans-first:1.59 09:38:07 INFO - 2016-02-08 09:38:07,018 DEBUG : BROWSER_OUTPUT: Values: 66.0 63.0 67.0 64.0 64.0 63.0 67.0 65.0 64.0 63.0 09:38:07 INFO - 2016-02-08 09:38:07,018 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:38:07 INFO - 2016-02-08 09:38:07,018 DEBUG : BROWSER_OUTPUT: 09:38:07 INFO - 2016-02-08 09:38:07,665 INFO : Browser exited with error code: 0 09:38:07 INFO - 2016-02-08 09:38:07,721 INFO : Completed test kraken (00:00:56) 09:38:07 INFO - 2016-02-08 09:38:07,721 INFO : Starting test v8_7 09:38:07 INFO - 2016-02-08 09:38:07,721 DEBUG : operating with platform_type : linux_ 09:38:07 INFO - 2016-02-08 09:38:07,721 INFO : Initialising browser for v8_7 test... 09:38:07 INFO - 2016-02-08 09:38:07,727 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOUfT1v/profile http://localhost:49553/getInfo.html 09:38:11 INFO - 2016-02-08 09:38:11,138 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:38:11 INFO - 2016-02-08 09:38:11,139 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:38:11 INFO - 2016-02-08 09:38:11,139 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:38:11 INFO - 2016-02-08 09:38:11,139 DEBUG : BROWSER_OUTPUT: __metrics 09:38:11 INFO - 2016-02-08 09:38:11,260 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] 09:38:11 INFO - 2016-02-08 09:38:11,896 INFO : Browser initialized. 09:38:11 INFO - 2016-02-08 09:38:11,897 INFO : Running cycle 1/1 for v8_7 test... 09:38:11 INFO - 2016-02-08 09:38:11,897 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOUfT1v/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:38:23 INFO - 2016-02-08 09:38:23,672 DEBUG : BROWSER_OUTPUT: RSS: Main: 135016448 09:38:23 INFO - 2016-02-08 09:38:23,673 DEBUG : BROWSER_OUTPUT: 09:38:45 INFO - 2016-02-08 09:38:45,118 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49553/tests/v8_7/run.html (next: http://localhost:49553/tests/v8_7/run.html) 09:38:45 INFO - 2016-02-08 09:38:45,376 DEBUG : BROWSER_OUTPUT: RSS: Main: 422416384 09:38:45 INFO - 2016-02-08 09:38:45,376 DEBUG : BROWSER_OUTPUT: 09:38:45 INFO - 2016-02-08 09:38:45,376 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:38:45 INFO - 2016-02-08 09:38:45,376 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:38:45 INFO - 2016-02-08 09:38:45,376 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:38:45 INFO - 2016-02-08 09:38:45,376 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:38:45 INFO - 2016-02-08 09:38:45,377 DEBUG : BROWSER_OUTPUT: |0;Richards;166.8335001668335 09:38:45 INFO - 2016-02-08 09:38:45,377 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.58196181698486 09:38:45 INFO - 2016-02-08 09:38:45,377 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.78449958182324 09:38:45 INFO - 2016-02-08 09:38:45,377 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5154.639175257732 09:38:45 INFO - 2016-02-08 09:38:45,377 DEBUG : BROWSER_OUTPUT: |4;RayTrace;993.0486593843099 09:38:45 INFO - 2016-02-08 09:38:45,377 DEBUG : BROWSER_OUTPUT: |5;Earley;542.5935973955508 09:38:45 INFO - 2016-02-08 09:38:45,377 DEBUG : BROWSER_OUTPUT: |6;Boyer;10578.947368421053 09:38:45 INFO - 2016-02-08 09:38:45,378 DEBUG : BROWSER_OUTPUT: |7;RegExp;24926.829268292684 09:38:45 INFO - 2016-02-08 09:38:45,378 DEBUG : BROWSER_OUTPUT: |8;Splay;513.6106831022086 09:38:45 INFO - 2016-02-08 09:38:45,378 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 09:38:45 INFO - 2016-02-08 09:38:45,378 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:38:45 INFO - 2016-02-08 09:38:45,378 DEBUG : BROWSER_OUTPUT: __startTimestamp1454953125369__endTimestamp 09:38:45 INFO - 2016-02-08 09:38:45,378 DEBUG : BROWSER_OUTPUT: 09:38:45 INFO - 2016-02-08 09:38:45,378 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:38:45 INFO - 2016-02-08 09:38:45,378 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:38:45 INFO - 2016-02-08 09:38:45,379 DEBUG : BROWSER_OUTPUT: 09:38:45 INFO - 2016-02-08 09:38:45,379 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.83 Median:166.83 stddev:0.00 (0.0%) 09:38:45 INFO - 2016-02-08 09:38:45,379 DEBUG : BROWSER_OUTPUT: Values: 166.8 09:38:45 INFO - 2016-02-08 09:38:45,379 DEBUG : BROWSER_OUTPUT: 09:38:45 INFO - 2016-02-08 09:38:45,379 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.58 Median:164.58 stddev:0.00 (0.0%) 09:38:45 INFO - 2016-02-08 09:38:45,379 DEBUG : BROWSER_OUTPUT: Values: 164.6 09:38:45 INFO - 2016-02-08 09:38:45,379 DEBUG : BROWSER_OUTPUT: 09:38:45 INFO - 2016-02-08 09:38:45,380 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.78 Median:278.78 stddev:0.00 (0.0%) 09:38:45 INFO - 2016-02-08 09:38:45,380 DEBUG : BROWSER_OUTPUT: Values: 278.8 09:38:45 INFO - 2016-02-08 09:38:45,380 DEBUG : BROWSER_OUTPUT: 09:38:45 INFO - 2016-02-08 09:38:45,380 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5154.64 Median:5154.64 stddev:0.00 (0.0%) 09:38:45 INFO - 2016-02-08 09:38:45,380 DEBUG : BROWSER_OUTPUT: Values: 5154.6 09:38:45 INFO - 2016-02-08 09:38:45,380 DEBUG : BROWSER_OUTPUT: 09:38:45 INFO - 2016-02-08 09:38:45,380 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:993.05 Median:993.05 stddev:0.00 (0.0%) 09:38:45 INFO - 2016-02-08 09:38:45,381 DEBUG : BROWSER_OUTPUT: Values: 993.0 09:38:45 INFO - 2016-02-08 09:38:45,381 DEBUG : BROWSER_OUTPUT: 09:38:45 INFO - 2016-02-08 09:38:45,381 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:542.59 Median:542.59 stddev:0.00 (0.0%) 09:38:45 INFO - 2016-02-08 09:38:45,381 DEBUG : BROWSER_OUTPUT: Values: 542.6 09:38:45 INFO - 2016-02-08 09:38:45,381 DEBUG : BROWSER_OUTPUT: 09:38:45 INFO - 2016-02-08 09:38:45,381 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10578.95 Median:10578.95 stddev:0.00 (0.0%) 09:38:45 INFO - 2016-02-08 09:38:45,381 DEBUG : BROWSER_OUTPUT: Values: 10578.9 09:38:45 INFO - 2016-02-08 09:38:45,382 DEBUG : BROWSER_OUTPUT: 09:38:45 INFO - 2016-02-08 09:38:45,382 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24926.83 Median:24926.83 stddev:0.00 (0.0%) 09:38:45 INFO - 2016-02-08 09:38:45,382 DEBUG : BROWSER_OUTPUT: Values: 24926.8 09:38:45 INFO - 2016-02-08 09:38:45,382 DEBUG : BROWSER_OUTPUT: 09:38:45 INFO - 2016-02-08 09:38:45,382 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:513.61 Median:513.61 stddev:0.00 (0.0%) 09:38:45 INFO - 2016-02-08 09:38:45,382 DEBUG : BROWSER_OUTPUT: Values: 513.6 09:38:45 INFO - 2016-02-08 09:38:45,382 DEBUG : BROWSER_OUTPUT: 09:38:45 INFO - 2016-02-08 09:38:45,382 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 09:38:45 INFO - 2016-02-08 09:38:45,383 DEBUG : BROWSER_OUTPUT: Values: 4835.7 09:38:45 INFO - 2016-02-08 09:38:45,383 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:38:45 INFO - 2016-02-08 09:38:45,383 DEBUG : BROWSER_OUTPUT: 09:38:46 INFO - 2016-02-08 09:38:46,109 INFO : Browser exited with error code: 0 09:38:46 INFO - 2016-02-08 09:38:46,123 INFO : Completed test v8_7 (00:00:38) 09:38:46 INFO - 2016-02-08 09:38:46,300 INFO : Completed test suite (00:20:04) 09:38:46 INFO - 2016-02-08 09:38:46,301 DEBUG : Working with test: dromaeo_css 09:38:46 INFO - 2016-02-08 09:38:46,301 DEBUG : Generating results file: dromaeo_css 09:38:46 INFO - 2016-02-08 09:38:46,302 DEBUG : Working with test: kraken 09:38:46 INFO - 2016-02-08 09:38:46,302 DEBUG : Generating results file: kraken 09:38:46 INFO - 2016-02-08 09:38:46,302 INFO : javascript benchmark 09:38:46 INFO - 2016-02-08 09:38:46,302 DEBUG : Working with test: v8_7 09:38:46 INFO - 2016-02-08 09:38:46,303 DEBUG : Generating results file: v8_7 09:38:46 INFO - 2016-02-08 09:38:46,303 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:38:46 INFO - 2016-02-08 09:38:46,355 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 09:38:46 INFO - 2016-02-08 09:38:46,355 DEBUG : process_Request line: dromaeo_css 7378.54 graph.html#tests=[[72,53,35]] 09:38:46 INFO - 2016-02-08 09:38:46,355 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:38:46 INFO - 2016-02-08 09:38:46,382 DEBUG : process_Request line: kraken 1470.40 graph.html#tests=[[232,53,35]] 09:38:46 INFO - 2016-02-08 09:38:46,382 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:38:46 INFO - 2016-02-08 09:38:46,423 DEBUG : process_Request line: v8_7 23175.94 graph.html#tests=[[230,53,35]] 09:38:46 INFO - 2016-02-08 09:38:46,425 INFO : javascript benchmark 09:38:46 INFO - 2016-02-08 09:38:46,429 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3277.0, 3358.7648864579487, 3393.304372603615, 3399.694027537521, 3413.453847384419, 5544.861378465535, 5561.888762224753, 5564.916526252105, 5590.748416321265, 5594.104943209077, 5569.6658200508, 5620.718964051802, 5620.859478513039, 5624.0, 5624.803131890385, 4881.536254055859, 4941.876453088666, 5088.135017047092, 5114.360704911886, 5176.430592634807, 4963.72699501529, 5015.974920125394, 5021.799128034874, 5024.773885175157, 5025.497450254964, 4983.07813054586, 5052.797888084472, 5082.186850103984, 5099.260607211971, 5101.617378696594, 5004.699718016913, 5080.161773307418, 5093.79624815007, 5096.082705113078, 5102.591792656579, 5080.847574572768, 5156.92264616031, 5183.611229157828, 5193.506616871391, 5207.0, 5184.196449550326, 5237.502437268454, 5269.209618557247, 5334.546563542103, 5378.354597448292, 5931.139984702194, 5995.250593675791, 6044.667543285118, 6057.394260573951, 6060.606060606091, 6003.489703375219, 6069.6054756441135, 6092.482139018189, 6096.878062438771, 6110.266767987825, 5369.40936496985, 5424.376196737387, 5479.6164268501225, 5504.9449505504435, 5518.834434966998, 6085.208922840091, 6124.969375153185, 6127.417895299939, 6129.632222066601, 6133.6319820810895, 5574.693391863405, 5901.645901245853, 5918.230630018156, 5919.6744179070565, 5927.555433342516, 5680.40355762639, 5883.294004719375, 5928.911066334009, 5942.910856337158, 5964.880702385928, 5876.236089308362, 5891.852703682356, 5922.7927022554, 5924.318703349084, 5932.377100404482, 5652.0391533439415, 5760.6255593386295, 5919.348871624118, 5919.733611987471, 5929.822105336847, 4403.229434848889, 4446.510883802779, 4447.310666846644, 4450.043240703264, 4450.821967121279, 4028.1540876415693, 4055.2497787909333, 4065.654419374386, 4119.176164767058, 4134.37984302357, 5750.108733146369, 5809.82570522885, 5810.970945145247, 5811.680357580373, 5813.069908814569, 6077.301110372276, 6101.664408457489, 6104.298005729309, 6109.572329936862, 6113.174721412642, 5498.560115190775, 5504.366997795305, 5556.388797232301, 5574.721263936787, 5691.430856914276, 5166.974165129225, 5175.663581867167, 5177.9741101295, 5187.584993200535, 5203.713795741271, 5965.910511342333, 6205.81382558524, 6209.254889413209, 6220.502359811204, 6220.844478887973, 5960.105984102419, 6035.486983606443, 6137.478314343152, 6144.3717414884195, 6145.016797312409, 6283.654399007915, 6298.244210694746, 6300.779472718433, 6308.873822523432, 6311.053341998737, 5621.634593751393, 5723.42765723441, 5995.91006134917, 6007.8197654070445, 6026.0, 5886.264216972879, 5943.791967281124, 5953.047512397996, 5955.821325360246, 5985.191999080069, 5908.586398952032, 5925.555583331354, 5950.7917222896995, 5967.9701601491715, 5970.0, 5683.488486036276, 5744.454276843693, 5749.568782341257, 5957.3744756799915, 5959.642421454727, 5770.105633626795, 5802.6808525531505, 5817.214676018683, 5821.243238379153, 5830.53355731549, 5892.616979896465, 5916.822495325147, 5922.940770592411, 5925.525957923442, 5935.109733540004, 5461.726913654381, 5597.468240517144, 5609.80365687199, 5729.656220626775, 5791.247137872049], "unit": "ms", "name": "dojo.html", "value": 5503.598249215723}, {"lowerIsBetter": false, "replicates": [599.9670018149, 636.2778246690007, 645.4481418387276, 646.9805905822822, 647.139304724716, 32727.0, 41554.7922260389, 41799.58200417995, 42134.0, 42183.945401364945, 45406.09187816242, 46022.0, 46117.0, 46325.0, 46350.30474542876, 8317.20986506284, 8414.49513029217, 8423.073461919184, 9037.819243615124, 9223.308251881102, 8157.55133467659, 8216.0, 10848.566057357735, 11047.668569942914, 11054.392008439532, 10729.892701072968, 10822.891771082306, 10827.0, 10831.945840270788, 10875.619353322636, 10587.73530661732, 10675.41285229312, 10687.83968240477, 10691.144708423308, 10770.138388928866, 10491.632792852213, 10615.203859710553, 10624.893751062546, 10683.732906677318, 10753.51609177589, 40455.0, 40526.39210411846, 40526.79736601328, 40598.79700601508, 40814.79592602047, 3666.6883314918264, 3768.7550309229814, 3781.0925377909102, 3914.0, 4047.433359329667, 32050.358992820245, 32248.35503289921, 32353.83823080893, 32543.837280813445, 32591.0, 4142.233686767958, 4195.328747400401, 4222.746635201897, 4317.330813723878, 4344.7827608619755, 285380.0, 285563.0, 286342.9999999979, 286344.0, 287394.0, 62955.0, 63444.36555634477, 63464.0, 63464.36535634677, 63570.36429635644, 67980.66009670019, 68185.65907170433, 68466.315336847, 68516.0, 68748.0, 17367.392141274995, 17376.65246695059, 17491.912540437217, 17602.207900644502, 17614.735778963324, 13360.39878205483, 13426.664333391744, 13432.529861454803, 13445.058845880694, 13451.529196478075, 20038.0, 21188.470288242916, 21365.78634213669, 21404.143834246457, 21551.461213469476, 7838.333741631911, 8178.427510074238, 8188.426810123353, 8232.958835205785, 8287.337013038943, 1007.1590222164575, 1035.1925498111484, 1071.8981696738797, 1134.6709454258173, 1137.4199158429096, 31068.0, 31124.68875311263, 31137.377252455277, 31863.681363186533, 32586.837065814994, 1107.7175320293434, 1172.5720112159001, 1173.7287503913176, 1220.6155061155707, 1241.900647948162, 12529.561465348852, 15022.924885375503, 15378.769318460232, 15427.691446171013, 15651.060936343629, 267504.0, 268739.0, 269903.0, 270445.6477717638, 346157.0, 42560.14879702389, 54768.45231547713, 55577.444225558036, 55825.0, 55993.16010259849, 1398.881095106907, 1443.256722787765, 1463.4512057978259, 1464.699736554004, 1465.4358072142088, 26983.46033079288, 27001.594976074983, 30424.239394014443, 30709.23226919345, 31729.68270317267, 50966.74516627393, 51328.0, 51518.484815151365, 51681.0, 51787.74106129596, 265229.0, 266002.0, 266333.0, 267276.0, 267299.0, 35149.824250878584, 36026.45960310545, 36126.27747444984, 36197.63802361942, 36202.0, 47212.527874720814, 47991.52008479871, 48236.51763482461, 48309.0, 48472.5152748468, 39684.801575991936, 40304.79847600743, 40739.0, 40841.64850326851, 41183.176336472505, 39022.0, 39310.80344598259, 39828.515287743656, 39914.0, 39995.0, 38782.80608596939, 39198.216035679696, 39991.0, 40049.399259010555, 40050.59949400469, 35381.0, 36323.63676363203, 36470.8176459116, 36641.63358366489, 36690.26619467542, 27654.585181221897, 27755.30611734642, 27868.44263114686, 27875.442491149657, 28232.294192644527], "unit": "ms", "name": "ext.html", "value": 20063.844449937496}, {"lowerIsBetter": false, "replicates": [389.8713424569891, 390.23708649590054, 392.20370702184, 396.48852979656243, 396.7936672930076, 16774.580635484104, 17321.826781732194, 17418.390356337513, 18232.179551920162, 19023.0, 17658.735118973225, 17698.557536061606, 17704.380346687867, 18129.184186711598, 18160.36438724645, 5414.242006119146, 5596.804111856086, 5603.635763675348, 5606.579506537026, 5608.0, 5424.945750542503, 5499.4225606311165, 5540.2243685884005, 5570.192322113291, 5573.916391254114, 4207.474065741782, 4301.591348821857, 5529.889402211953, 5531.419200983899, 5677.744501497443, 5752.942470575283, 5783.363829978699, 5803.042497987827, 5808.88382232353, 5812.796552120677, 5196.142636464979, 5249.553787928031, 5449.945500544984, 5450.991566560199, 5451.509364157245, 17496.0, 17785.288588456446, 20422.387328380024, 21740.782592174033, 22086.11655533777, 2410.192585483865, 2426.2478631624253, 2426.745191754858, 2462.5198086372984, 2512.359348366155, 13994.160350378908, 14080.929595351958, 14328.64178395538, 14351.78472322916, 14527.854721452755, 1888.7827899791425, 1921.164293532318, 1934.806519348068, 1941.679622862226, 1948.814862588052, 61211.38788612101, 63492.682536587025, 63616.36383636151, 63656.0, 64387.67806160939, 10439.582416703246, 10594.258401911946, 10781.784364312669, 10815.297005694605, 10882.0, 14076.929615351857, 14082.295885205698, 14189.0, 14205.715885682228, 14230.501932432315, 8469.0, 8474.279686226617, 8489.915100848912, 8553.871691924625, 8592.269657079094, 8395.202455766692, 8457.873131903027, 8476.152384761597, 8521.0, 8529.0, 6291.087792270096, 6326.43062124411, 6329.968350158312, 6353.0, 6384.872302553923, 1824.8448881845193, 1872.7659042619673, 1894.2801735340759, 1902.3341830359263, 1913.56944687447, 894.6376717429451, 1022.4121130350078, 1085.8968398002178, 1151.9654410367702, 1172.3962159487871, 16390.590235243977, 17359.305627775, 17515.649687006444, 17749.112544372987, 18197.7270340945, 973.2943615878573, 984.468387070988, 1049.6850944716555, 1066.57337065173, 1131.6491887514896, 8564.914350856412, 9843.852342214872, 10307.84538231927, 10347.183776477586, 10611.522481488351, 600851.0, 602903.0, 608596.0, 622703.0, 624897.0, 83116.58441707752, 84167.0, 85090.57454712811, 85180.57409713036, 86422.56788716016, 1196.1925700152362, 1216.5559570756786, 1226.3622916083764, 1232.8458942632171, 1247.3389103775014, 15670.686586267982, 16235.269412876685, 16319.918400407922, 16478.67042659116, 16644.001359918442, 37456.438153427174, 38153.61846381501, 38169.0, 39068.413973790986, 39080.0, 47942.76028619835, 47966.5203347976, 49402.75298623628, 49795.751021244665, 51249.23126153185, 10275.280730348804, 10532.736681583021, 10699.839502407625, 10708.196885233794, 10709.410982396043, 17504.82495175032, 17754.28982840672, 17780.643742283224, 18356.35752748701, 18635.161417735962, 14245.0, 14440.711185776016, 14461.49384771528, 14523.491677791226, 14771.482998095014, 14587.562373128823, 14590.19753913545, 15205.0, 15666.921665391601, 15869.04785712861, 14644.0, 14653.340599673253, 14877.181755003146, 15877.603059923595, 16081.035137891764, 8124.83750325002, 9910.405375677483, 10346.430946298025, 10697.411642359744, 10900.127989760642, 10355.585776568852, 11731.706707332043, 15566.377344906077, 15781.842181578497, 15949.920250398674], "unit": "ms", "name": "jquery.html", "value": 9590.548724622106}, {"lowerIsBetter": false, "replicates": [1511.9017263877847, 1594.6970075685615, 1628.4463282483953, 1759.5689056181238, 1873.7657792775904, 8189.7133600324005, 8416.747497575083, 8438.746837594874, 8445.493270403773, 8446.028706698731, 7366.815829604249, 7564.167941526428, 7962.124166341698, 8230.012398512186, 8234.341252699785, 2362.244081893799, 2364.527094581082, 2394.7605239476, 2397.7482364351754, 2397.928062158138, 2127.8084972352485, 2373.252425485975, 2386.367612582667, 2429.9392515187087, 2432.185217951979, 2119.576084783042, 2312.9421764455938, 2365.124903785602, 2365.44412063165, 2366.25462979161, 2294.391986123687, 2318.8260880434036, 2322.8374013818957, 2339.368370539945, 2339.4970081432402, 2373.6439534069855, 2378.5837478441376, 2399.5560821248123, 2404.711434627856, 2407.963880541793, 8315.210055044761, 8317.708880189164, 8367.95816020916, 8371.874421883616, 8398.622062007224, 4959.280904268897, 5014.84955451337, 5018.096742586333, 5028.64799464034, 5028.673136246169, 3138.168385377877, 3144.2296637323775, 3186.9203269918207, 3316.7512436567113, 3340.131565792887, 1069.5133714160115, 2060.6702927531524, 2064.5251592133895, 2068.617305798432, 2069.2964392106755, 11668.1832271741, 11721.589744358907, 11769.352685602289, 11790.17468777186, 11803.0, 4264.1471705659, 4325.848595299212, 4389.539098394686, 4389.62688171509, 4395.978020109879, 8968.596413161424, 8972.775680608038, 8987.281017518582, 8992.055834137322, 9002.954985225031, 4932.728699921467, 4954.653174277766, 4963.156263435225, 4964.776585053682, 4969.27945447908, 4474.686771925999, 4531.297648864432, 4584.4957054723955, 4587.816487340536, 4590.862274131781, 3689.708601989282, 3705.9073523162137, 3733.4586484959527, 3733.6266373362446, 3756.7933763643264, 1000.6297669862198, 1029.2537910015178, 1059.5708737961138, 1064.6060957445795, 1127.464454384161, 1173.0908545877014, 1183.408295852074, 1196.5752157983964, 1443.3432788081398, 1471.7865909443071, 8094.51432914027, 8109.229623185788, 8177.836443271221, 8212.17878212174, 8217.096119426858, 680.2721088435327, 681.7375310505392, 689.1970853955187, 696.2550071423528, 833.5040650812878, 6207.472364849038, 6312.684365781693, 6314.273858506331, 6315.242170939425, 6320.304766475684, 9721.854172187423, 9988.05113514215, 10010.899891001143, 10019.348742331726, 10059.64791232318, 9859.556319965619, 9963.501824908726, 9969.501524923724, 9982.550785214682, 9985.550650220757, 7664.9616751917, 7888.566128862853, 7959.522428654299, 7966.402519811038, 7976.401769867283, 7434.405247580289, 7888.0, 7944.245296696805, 7946.8807967879375, 7957.8408431832195, 8724.389292749447, 8817.294616430541, 9036.186743193144, 9044.728658140266, 9066.138716821892, 9171.954140229524, 9219.631214751334, 9351.85972210431, 9410.05899410081, 9671.758206044906, 7577.772666820004, 7620.618969051415, 7657.578833164007, 7671.578063206577, 7696.7306144284685, 8130.67477300925, 8173.67305307781, 8202.097769245498, 8203.261706446448, 8217.096119426978, 6399.3600639937495, 7682.769516914502, 7724.92275077242, 7802.0, 7806.843863122592, 7016.929830701628, 7737.1102323233545, 7741.225877412214, 7770.0287464067, 7830.569318687527, 7610.353119984863, 7620.580868052089, 7650.31147196755, 7656.272654097852, 7667.578283194478, 6778.152730908637, 6812.591244525344, 6827.829304267433, 6855.485838562028, 6865.27914568963, 7229.891551626835, 7247.710091596277, 7298.087739032621, 7298.452616053711, 7308.5614863108385], "unit": "ms", "name": "mootools.html", "value": 4771.64178665903}, {"lowerIsBetter": false, "replicates": [343.6838108939775, 350.82458770614693, 355.5289241754676, 357.6584361934353, 357.69595843533, 6060.878782424349, 6131.7240724167405, 6149.8770024599535, 6159.661218632973, 6167.105769663396, 5775.249217601717, 5844.970775146129, 5930.822075337736, 6036.245469316335, 6036.396360363961, 4689.953100468986, 4692.648051396141, 4696.295555666643, 4713.269443236287, 4716.551927566876, 4518.254488009475, 4688.718676879381, 4702.8118875244945, 4706.411698537683, 4720.079584481027, 4524.932126018112, 4566.6575006874455, 4646.9070618587775, 4679.508651591585, 4680.110778952006, 4656.301554766761, 4659.394278743775, 4666.813327466897, 4678.415198100238, 4678.485366609671, 4128.566500517462, 4134.152498737752, 4134.813933373005, 4141.772202528859, 4142.46148000761, 5653.321601407857, 5781.335146458169, 5791.581062639638, 5791.594588378815, 5795.855103622401, 5418.268533747933, 5420.783168673248, 5423.240746295522, 5431.348238211401, 5434.429384914606, 6439.581427207217, 6440.5491615586925, 6441.516886233504, 6442.06590044441, 6449.322821103763, 4470.284754439306, 4528.162289976365, 4532.252178390561, 4535.659825513099, 4547.818087276505, 6565.244996825379, 6595.0766892635065, 6607.471402287805, 6659.9001014984815, 6677.699503522304, 4574.588287054181, 4575.062112266977, 4578.084383123389, 4578.816847326069, 4601.447826260804, 4641.094986477672, 4641.582257596832, 4690.882727931829, 4700.7179569225955, 4706.764661766898, 5327.227552005015, 5389.326334208224, 5390.029794636925, 5398.379186393615, 5400.486953739389, 6321.96839015802, 6436.517261205428, 6440.903386449207, 6456.160699109086, 6459.063435801782, 1624.6913086513525, 1646.7941507311587, 1655.42887703742, 1655.4454257823763, 1655.998121136719, 1052.6631477927142, 1064.090202876545, 1065.8294672852462, 1082.1667316166508, 1102.4377567440663, 6281.811545653638, 6284.622922624657, 6289.905651415233, 6290.685465726695, 6296.244450665949, 6420.807375778734, 6648.800535983928, 6651.567648102858, 6655.0, 6655.633940133339, 6212.875742485125, 6217.129601855744, 6394.776182833579, 6396.232452105777, 6399.584027038228, 3551.4317709166407, 3599.136207310227, 3601.7838929664304, 3606.9278614427562, 3611.3499570077115, 6787.423069039089, 6920.30796920314, 6946.51374403797, 6948.235694073648, 6959.652017399109, 6853.554518956252, 6860.142482189726, 6871.484638652121, 6873.347032031949, 6881.621510816954, 6919.481038922101, 6942.340477654615, 6943.062686537354, 6951.409130223988, 6951.860962780716, 6563.868722625616, 6667.899981500184, 6676.632785196861, 6693.397594216543, 6698.062271282122, 6587.901181482377, 6608.8347791305605, 6614.074029635759, 6624.470042396693, 6625.17185351831, 6933.0986971693355, 6940.479464040116, 6944.305569443015, 6946.166460024628, 6950.374466298057, 4552.11233809404, 4614.838480653161, 4621.676482646182, 4627.167109920247, 4629.375034370317, 6691.364320389555, 6701.9947007949195, 6710.899336509859, 6712.597244165366, 6713.295104014203, 6521.347865213441, 6527.445167160845, 6530.8040758777315, 6536.901946470902, 6537.248216455168, 6396.072569477493, 6474.223093228843, 6503.577267477599, 6521.478281737544, 6524.184476940382, 6405.167328247298, 6416.807495775134, 6422.935770642233, 6428.0, 6430.0, 6494.6752662367635, 6506.251781045051, 6553.377429144223, 6555.836104097436, 6560.606363618198, 6258.374162583706, 6351.0, 6372.58578192416, 6382.712777925098, 6410.45511131559], "unit": "ms", "name": "prototype.html", "value": 4891.516293742267}, {"lowerIsBetter": false, "replicates": [816.5957850863823, 826.7681155078934, 867.8481265778487, 869.4261787220436, 917.257021812332, 14568.0, 14843.332050057747, 14844.777328340084, 14859.40562377505, 14869.330880110394, 11410.600628977987, 13735.96980226482, 13909.513167039157, 14016.509422170226, 14041.578752637412, 882.6337070115894, 893.0399820193318, 895.3598177303227, 895.6417433026776, 895.977600559988, 893.9016708162097, 897.7710683775628, 897.9506127163004, 898.6180873128927, 898.7618281155499, 936.6534382278567, 942.528735632184, 943.03810113684, 943.3915124744535, 944.0983860413289, 935.3031991166556, 935.471458625881, 935.7847695030113, 935.9812803743921, 938.7090002099337, 690.5545922879764, 691.550492180087, 692.2385376086315, 693.9236683964759, 695.5826504097552, 13376.46494140243, 13525.188488690612, 13527.932360338134, 13541.1198272112, 13543.59369218925, 11367.317960922372, 11398.202125851194, 11583.18917675763, 11632.767344653144, 11957.222780519325, 10580.52387642558, 10733.570657173703, 10847.457627118612, 10899.1280697544, 10980.066694330992, 3741.9438708419398, 3835.1946091320856, 3870.3807390817333, 3882.107115363454, 3891.1633998690418, 42455.15089698189, 43340.78329608363, 47129.764351178026, 48078.75960620175, 48245.517544824455, 9607.375520591138, 9679.467629280316, 9760.560774765152, 9825.557849896772, 10170.796584068277, 18147.18337674808, 18196.54508637268, 18286.45140645783, 18335.174917128763, 18357.54106147357, 8429.03066147401, 8470.49177049379, 8470.576471176417, 8541.829163416696, 8704.0, 4633.582977532038, 4676.929846052312, 4707.764611769398, 4761.4524329701835, 4786.138495070921, 3163.0, 3186.1238159505856, 3188.3623275345026, 3190.0589326148843, 3192.6009248843893, 557.6263903184876, 576.20771439271, 577.6129992904764, 582.3710392775791, 588.5232961301274, 690.4890381118037, 700.0619170311705, 725.2095216214352, 727.105660038156, 756.6632848382527, 8101.432899697083, 8405.537695426689, 8476.576171191538, 8506.064332923374, 8686.913130868737, 693.8404167041608, 696.3210869402292, 718.2207305074053, 720.6648908257686, 726.2048057377164, 4274.829006839754, 4295.463067116611, 4324.200022995756, 4329.523752387235, 4429.461761983289, 57250.42749572451, 60174.699126504085, 60583.697081515194, 60706.0, 60885.695571522745, 49121.0, 49524.50475495271, 51061.23408148881, 51624.74187629113, 53417.46582534203, 46737.2989405166, 46905.765471172424, 47253.0, 47415.52584474111, 47781.52218477771, 8366.414350995372, 8615.45832002544, 8771.956140219258, 8779.604917778588, 8789.077146899483, 36420.27159456743, 36646.26707465889, 37017.99999999892, 37094.0, 37853.242935140595, 42492.57507424886, 43473.56526434695, 45549.77225113853, 45614.543854562355, 45838.77080614575, 10719.303245289031, 10742.73143171427, 10781.676549703521, 10874.510647021061, 11028.393438360647, 11313.151513636674, 12064.698382540506, 12313.0, 12430.937845310715, 12515.36317035859, 11143.554257829595, 11165.944170279097, 11288.2662626929, 11343.20597558163, 11435.085193184692, 10418.531166097695, 10681.839772403264, 10975.670729878117, 11011.28426652265, 11015.944920275348, 10333.0, 10564.207684423545, 10829.350238985686, 10902.18233632465, 11000.724981875517, 9927.453990030328, 10044.748881278027, 10244.129249013917, 10419.0, 10455.47722613851, 9226.446413215228, 9553.140217380469, 9556.139947404767, 9635.084666956627, 9802.36284641496], "unit": "ms", "name": "yui.html", "value": 6527.806114623281}], "name": "dromaeo_css", "value": 7378.538037733409}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 107.0, 111.0, 113.0, 109.0, 100.0, 109.0, 110.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 108.7}, {"lowerIsBetter": true, "replicates": [142.0, 122.0, 143.0, 154.0, 135.0, 150.0, 157.0, 124.0, 157.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.6}, {"lowerIsBetter": true, "replicates": [160.0, 159.0, 161.0, 158.0, 159.0, 161.0, 161.0, 162.0, 160.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.1}, {"lowerIsBetter": true, "replicates": [73.0, 70.0, 72.0, 149.0, 74.0, 72.0, 72.0, 71.0, 152.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 87.6}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 133.0, 92.0, 92.0, 92.0, 147.0, 92.0, 92.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 105.8}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 146.0, 147.0, 146.0, 146.0, 145.0, 146.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [59.0, 57.0, 56.0, 56.0, 58.0, 57.0, 57.0, 58.0, 57.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 57.1}, {"lowerIsBetter": true, "replicates": [64.0, 72.0, 63.0, 64.0, 64.0, 65.0, 64.0, 65.0, 64.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 65.0}, {"lowerIsBetter": true, "replicates": [76.0, 75.0, 77.0, 78.0, 77.0, 80.0, 79.0, 79.0, 78.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.7}, {"lowerIsBetter": true, "replicates": [114.0, 112.0, 111.0, 114.0, 114.0, 114.0, 115.0, 114.0, 111.0, 117.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 113.6}, {"lowerIsBetter": true, "replicates": [139.0, 142.0, 140.0, 146.0, 141.0, 138.0, 143.0, 138.0, 139.0, 130.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 139.6}, {"lowerIsBetter": true, "replicates": [66.0, 63.0, 67.0, 64.0, 64.0, 63.0, 67.0, 65.0, 64.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.6}], "name": "kraken", "value": 1470.3999999999999}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.8335001668335], "unit": "score", "name": "Richards", "value": 211.6001880000001}, {"lowerIsBetter": false, "replicates": [164.58196181698486], "unit": "score", "name": "DeltaBlue", "value": 401.7329680000002}, {"lowerIsBetter": false, "replicates": [278.78449958182324], "unit": "score", "name": "Encrypt", "value": 954.7912470000005}, {"lowerIsBetter": false, "replicates": [5154.639175257732], "unit": "score", "name": "Decrypt", "value": 51.63911399999997}, {"lowerIsBetter": false, "replicates": [993.0486593843099], "unit": "score", "name": "RayTrace", "value": 745.1689229999997}, {"lowerIsBetter": false, "replicates": [542.5935973955508], "unit": "score", "name": "Earley", "value": 1228.2913090000002}, {"lowerIsBetter": false, "replicates": [10578.947368421053], "unit": "score", "name": "Boyer", "value": 62.99899004975127}, {"lowerIsBetter": false, "replicates": [24926.829268292684], "unit": "score", "name": "RegExp", "value": 36.54636497064577}, {"lowerIsBetter": false, "replicates": [513.6106831022086], "unit": "score", "name": "Splay", "value": 158.66297699999996}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 23175.942261060576}]} 09:38:46 INFO - RETURN: dromaeo_css: 7378.54 09:38:46 INFO - RETURN: kraken: 1470.40 09:38:46 INFO - RETURN: v8_7: 23175.94 09:38:46 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23175.94"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1470.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7378.54"}}} 09:38:46 INFO - Return code: 0 09:38:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 09:38:46 INFO - # TBPL SUCCESS # 09:38:46 INFO - Running post-action listener: _resource_record_post_action 09:38:46 INFO - Running post-run listener: _resource_record_post_run 09:38:47 INFO - Total resource usage - Wall time: 1220s; CPU: 13.0%; Read bytes: 4096; Write bytes: 506109952; Read time: 8; Write time: 397248 09:38:47 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 239370240; Read time: 0; Write time: 251560 09:38:47 INFO - run-tests - Wall time: 1206s; CPU: 13.0%; Read bytes: 4096; Write bytes: 266739712; Read time: 8; Write time: 145688 09:38:47 INFO - Running post-run listener: _upload_blobber_files 09:38:47 INFO - Blob upload gear active. 09:38:47 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:38:47 INFO - Copying logs to upload dir... 09:38:47 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1250.935785 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-02-08 09:38:47.528683) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-08 09:38:47.532677) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009914 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942823/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-02-08 09:38:47.584594) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:38:47.584913) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1454951874.953308-1488325532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004624 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:38:47.634932) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-08 09:38:47.635251) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-08 09:38:47.635540) ========= ========= Total master_lag: 0.24 =========