builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-006 starttime: 1457370904.24 results: success (0) buildid: 20160307062827 builduid: 38a85bdf782342b3a82a08a136ddc8b1 revision: 8849dd1a4a796c2fd70d6f002912c3f799c59e69 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:04.238054) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:04.238442) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:04.238722) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.005253 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:04.260335) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:04.260639) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:04.284129) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:04.284446) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020435 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:04.333983) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:04.334294) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:04.334648) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:04.334916) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-03-07 09:15:04-- 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% 39.7M=0s 2016-03-07 09:15:04 (39.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089078 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:04.435929) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:04.436245) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.076132 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:04.523987) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-07 09:15:04.524341) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8849dd1a4a796c2fd70d6f002912c3f799c59e69 --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-aurora --rev 8849dd1a4a796c2fd70d6f002912c3f799c59e69 --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=e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-03-07 09:15:04,607 truncating revision to first 12 chars 2016-03-07 09:15:04,607 Setting DEBUG logging. 2016-03-07 09:15:04,607 attempt 1/10 2016-03-07 09:15:04,607 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8849dd1a4a79?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-07 09:15:05,474 unpacking tar archive at: mozilla-aurora-8849dd1a4a79/testing/mozharness/ program finished with exit code 0 elapsedTime=1.174074 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-07 09:15:05.711013) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:05.711356) ========= echo 8849dd1a4a796c2fd70d6f002912c3f799c59e69 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'8849dd1a4a796c2fd70d6f002912c3f799c59e69'] 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=e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 8849dd1a4a796c2fd70d6f002912c3f799c59e69 program finished with exit code 0 elapsedTime=0.004658 script_repo_revision: '8849dd1a4a796c2fd70d6f002912c3f799c59e69' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:05.762383) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:05.762692) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-07 09:15:05.772965) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2016-03-07 09:15:05.773272) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:15:05 INFO - MultiFileLogger online at 20160307 09:15:05 in /builds/slave/test-pgo 09:15:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:15:05 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:15:05 INFO - {'append_to_log': False, 09:15:05 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:15:05 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:15:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:15:05 INFO - 'branch': 'Mozilla-Aurora', 09:15:05 INFO - 'buildbot_json_path': 'buildprops.json', 09:15:05 INFO - 'config_files': ('talos/linux_config.py',), 09:15:05 INFO - 'default_actions': ('clobber', 09:15:05 INFO - 'read-buildbot-config', 09:15:05 INFO - 'download-and-extract', 09:15:05 INFO - 'populate-webroot', 09:15:05 INFO - 'create-virtualenv', 09:15:05 INFO - 'install', 09:15:05 INFO - 'run-tests'), 09:15:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:15:05 INFO - 'download_minidump_stackwalk': True, 09:15:05 INFO - 'download_symbols': 'ondemand', 09:15:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:15:05 INFO - 'tooltool.py': '/tools/tooltool.py', 09:15:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:15:05 INFO - '/tools/misc-python/virtualenv.py')}, 09:15:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:15:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:15:05 INFO - 'installer_path': 'installer.exe', 09:15:05 INFO - 'log_level': 'info', 09:15:05 INFO - 'log_name': 'talos', 09:15:05 INFO - 'log_to_console': True, 09:15:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:15:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:15:05 INFO - 'opt_config_files': (), 09:15:05 INFO - 'pip_index': False, 09:15:05 INFO - 'sps_profile': False, 09:15:05 INFO - 'sps_profile_interval': 0, 09:15:05 INFO - 'suite': 'dromaeojs', 09:15:05 INFO - 'system_bits': '32', 09:15:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:15:05 INFO - 'title': 'talos-linux64-ix-006', 09:15:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:15:05 INFO - 'use_talos_json': True, 09:15:05 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:15:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:15:05 INFO - 'work_dir': 'build'} 09:15:05 INFO - ##### 09:15:05 INFO - ##### Running clobber step. 09:15:05 INFO - ##### 09:15:05 INFO - Running pre-action listener: _resource_record_pre_action 09:15:05 INFO - Running main action method: clobber 09:15:05 INFO - rmtree: /builds/slave/test-pgo/build 09:15:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:15:07 INFO - Running post-action listener: _resource_record_post_action 09:15:07 INFO - ##### 09:15:07 INFO - ##### Running read-buildbot-config step. 09:15:07 INFO - ##### 09:15:07 INFO - Running pre-action listener: _resource_record_pre_action 09:15:07 INFO - Running main action method: read_buildbot_config 09:15:07 INFO - Using buildbot properties: 09:15:07 INFO - { 09:15:07 INFO - "project": "", 09:15:07 INFO - "product": "firefox", 09:15:07 INFO - "script_repo_revision": "production", 09:15:07 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:15:07 INFO - "repository": "", 09:15:07 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:15:07 INFO - "buildid": "20160307062827", 09:15:07 INFO - "pgo_build": "True", 09:15:07 INFO - "basedir": "/builds/slave/test-pgo", 09:15:07 INFO - "buildnumber": 97, 09:15:07 INFO - "slavename": "talos-linux64-ix-006", 09:15:07 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:15:07 INFO - "platform": "ubuntu64_hw", 09:15:07 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:15:07 INFO - "branch": "mozilla-aurora", 09:15:07 INFO - "repo_path": "releases/mozilla-aurora", 09:15:07 INFO - "revision": "8849dd1a4a796c2fd70d6f002912c3f799c59e69", 09:15:07 INFO - "stage_platform": "linux64-pgo", 09:15:07 INFO - "builduid": "38a85bdf782342b3a82a08a136ddc8b1", 09:15:07 INFO - "slavebuilddir": "test" 09:15:07 INFO - } 09:15:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2. 09:15:07 INFO - Running post-action listener: _resource_record_post_action 09:15:07 INFO - ##### 09:15:07 INFO - ##### Running download-and-extract step. 09:15:07 INFO - ##### 09:15:07 INFO - Running pre-action listener: _resource_record_pre_action 09:15:07 INFO - Running main action method: download_and_extract 09:15:07 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:15: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:15:07 INFO - https://queue.taskcluster.net/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:15:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 09:15:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 09:15:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 09:15:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:15:07 INFO - Downloaded 1448 bytes. 09:15:07 INFO - Reading from file /builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 09:15:07 INFO - Using the following test package requirements: 09:15:07 INFO - {u'common': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'], 09:15:07 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 09:15:07 INFO - u'firefox-47.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:15:07 INFO - u'gtest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 09:15:07 INFO - u'firefox-47.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:15:07 INFO - u'jittest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 09:15:07 INFO - u'jsshell-linux-x86_64.zip'], 09:15:07 INFO - u'mochitest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 09:15:07 INFO - u'firefox-47.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:15:07 INFO - u'mozbase': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'], 09:15:07 INFO - u'reftest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 09:15:07 INFO - u'firefox-47.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:15:07 INFO - u'talos': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 09:15:07 INFO - u'firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:15:07 INFO - u'web-platform': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 09:15:07 INFO - u'firefox-47.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:15:07 INFO - u'webapprt': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'], 09:15:07 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 09:15:07 INFO - u'firefox-47.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:15:07 INFO - Downloading packages: [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:15: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:15:07 INFO - https://queue.taskcluster.net/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:15:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 09:15:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 09:15:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 09:15:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:15:39 WARNING - Socket error when accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip: The read operation timed out 09:15:39 INFO - retry: attempt #1 caught exception: The read operation timed out 09:15:39 INFO - retry: Failed, sleeping 30 seconds before retrying 09:16:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 09:16:10 INFO - Downloaded 23305417 bytes. 09:16:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 09:16:10 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 09:16:11 INFO - Return code: 0 09:16:11 INFO - Downloading packages: [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:11 INFO - https://queue.taskcluster.net/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:16:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 09:16:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 09:16:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 09:16:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:16:12 INFO - Downloaded 23305417 bytes. 09:16:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 09:16:12 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 09:16:14 INFO - Return code: 0 09:16:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:14 INFO - https://queue.taskcluster.net/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:16:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip 09:16:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip 09:16:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip 09:16:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:16:15 INFO - Downloaded 10845161 bytes. 09:16:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 09:16:15 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test-pgo/build/tests 09:16:15 INFO - Return code: 0 09:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:15 INFO - https://queue.taskcluster.net/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:16:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 09:16:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 09:16:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 09:16:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:16:16 INFO - Downloaded 60418743 bytes. 09:16:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 09:16:16 INFO - mkdir: /builds/slave/test-pgo/properties 09:16:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:16:16 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:16:16 INFO - Contents: 09:16:16 INFO - build_url:https://queue.taskcluster.net/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 09:16:17 INFO - Running post-action listener: _resource_record_post_action 09:16:17 INFO - Running post-action listener: set_extra_try_arguments 09:16:17 INFO - ##### 09:16:17 INFO - ##### Running populate-webroot step. 09:16:17 INFO - ##### 09:16:17 INFO - Running pre-action listener: _resource_record_pre_action 09:16:17 INFO - Running main action method: populate_webroot 09:16:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:16:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:16:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:16:17 INFO - '--tppagecycles', 09:16:17 INFO - '7'], 09:16:17 INFO - 'tests': ['tsvgm']}}, 09:16:17 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:16:17 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:16:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:16:17 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 09:16:17 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:16:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:16:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:17 INFO - 'talos_options': ['--e10s'], 09:16:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:17 INFO - 'tests': ['damp', 'tps']}, 09:16:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:17 INFO - 'talos_options': ['--e10s'], 09:16:17 INFO - 'tests': ['damp', 'tps']}, 09:16:17 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:16:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:16:17 INFO - 'other': {'tests': ['a11yr', 09:16:17 INFO - 'ts_paint', 09:16:17 INFO - 'tpaint', 09:16:17 INFO - 'sessionrestore', 09:16:17 INFO - 'sessionrestore_no_auto_restore', 09:16:17 INFO - 'tabpaint']}, 09:16:17 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:16:17 INFO - 'tests': ['a11yr', 09:16:17 INFO - 'ts_paint', 09:16:17 INFO - 'tpaint', 09:16:17 INFO - 'sessionrestore', 09:16:17 INFO - 'sessionrestore_no_auto_restore', 09:16:17 INFO - 'tabpaint']}, 09:16:17 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:16:17 INFO - 'tests': ['a11yr', 09:16:17 INFO - 'ts_paint', 09:16:17 INFO - 'tpaint', 09:16:17 INFO - 'sessionrestore', 09:16:17 INFO - 'sessionrestore_no_auto_restore', 09:16:17 INFO - 'tabpaint']}, 09:16:17 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:16:17 INFO - 'tests': ['a11yr', 09:16:17 INFO - 'ts_paint', 09:16:17 INFO - 'tpaint', 09:16:17 INFO - 'sessionrestore', 09:16:17 INFO - 'sessionrestore_no_auto_restore', 09:16:17 INFO - 'tabpaint']}, 09:16:17 INFO - 'other_l64': {'tests': ['a11yr', 09:16:17 INFO - 'ts_paint', 09:16:17 INFO - 'tpaint', 09:16:17 INFO - 'sessionrestore', 09:16:17 INFO - 'sessionrestore_no_auto_restore', 09:16:17 INFO - 'tabpaint']}, 09:16:17 INFO - 'other_nol64': {'tests': ['a11yr', 09:16:17 INFO - 'ts_paint', 09:16:17 INFO - 'tpaint', 09:16:17 INFO - 'sessionrestore', 09:16:17 INFO - 'sessionrestore_no_auto_restore', 09:16:17 INFO - 'tabpaint']}, 09:16:17 INFO - 'svgr': {'tests': ['tsvgx', 09:16:17 INFO - 'tsvgr_opacity', 09:16:17 INFO - 'tart', 09:16:17 INFO - 'tscrollx', 09:16:17 INFO - 'cart']}, 09:16:17 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:16:17 INFO - 'tests': ['tsvgx', 09:16:17 INFO - 'tsvgr_opacity', 09:16:17 INFO - 'tart', 09:16:17 INFO - 'tscrollx', 09:16:17 INFO - 'cart']}, 09:16:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:17 INFO - 'tests': ['tp5o']}, 09:16:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:17 INFO - 'talos_options': ['--e10s'], 09:16:17 INFO - 'tests': ['tp5o']}, 09:16:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:17 INFO - 'talos_options': ['--xperf_path', 09:16:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:17 INFO - 'tests': ['tp5n']}, 09:16:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:17 INFO - 'talos_options': ['--e10s', 09:16:17 INFO - '--xperf_path', 09:16:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:17 INFO - 'tests': ['tp5n']}}, 09:16:17 INFO - 'talos.zip': {'path': '', 09:16:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:16:17 INFO - Running post-action listener: _resource_record_post_action 09:16:17 INFO - ##### 09:16:17 INFO - ##### Running create-virtualenv step. 09:16:17 INFO - ##### 09:16:17 INFO - Running pre-action listener: _resource_record_pre_action 09:16:17 INFO - Running main action method: create_virtualenv 09:16:17 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:16:17 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:16:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:16:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:16:17 INFO - Using real prefix '/usr' 09:16:17 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:16:18 INFO - Installing distribute.............................................................................................................................................................................................done. 09:16:20 INFO - Installing pip.................done. 09:16:20 INFO - Return code: 0 09:16:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:16: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:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2be08e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13676d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6920>, '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 0x13f7820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f7cb0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', '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:16: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', 'pip>=1.5'] in /builds/slave/test-pgo/build 09:16: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 pip>=1.5 09:16:20 INFO - Using env: {'DISPLAY': ':0', 09:16:20 INFO - 'HOME': '/home/cltbld', 09:16:20 INFO - 'LANG': 'en_US.UTF-8', 09:16:20 INFO - 'LANGUAGE': 'en_US:en', 09:16:20 INFO - 'LOGNAME': 'cltbld', 09:16:20 INFO - 'MAIL': '/var/mail/cltbld', 09:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:20 INFO - 'MOZ_NO_REMOTE': '1', 09:16:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:20 INFO - 'NO_EM_RESTART': '1', 09:16:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:16:20 INFO - 'PWD': '/builds/slave/test-pgo', 09:16:20 INFO - 'SHELL': '/bin/bash', 09:16:20 INFO - 'SHLVL': '1', 09:16:20 INFO - 'TERM': 'linux', 09:16:20 INFO - 'TMOUT': '86400', 09:16:20 INFO - 'USER': 'cltbld', 09:16:20 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', 09:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:20 INFO - '_': '/tools/buildbot/bin/python'} 09:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:20 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:16:20 INFO - Cleaning up... 09:16:20 INFO - Return code: 0 09:16:20 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:16: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:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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', '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 0x7f2be08e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13676d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6920>, '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 0x13f7820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f7cb0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', '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:16: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', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 09:16: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 psutil>=0.7.1 09:16:20 INFO - Using env: {'DISPLAY': ':0', 09:16:20 INFO - 'HOME': '/home/cltbld', 09:16:20 INFO - 'LANG': 'en_US.UTF-8', 09:16:20 INFO - 'LANGUAGE': 'en_US:en', 09:16:20 INFO - 'LOGNAME': 'cltbld', 09:16:20 INFO - 'MAIL': '/var/mail/cltbld', 09:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:20 INFO - 'MOZ_NO_REMOTE': '1', 09:16:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:20 INFO - 'NO_EM_RESTART': '1', 09:16:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:16:20 INFO - 'PWD': '/builds/slave/test-pgo', 09:16:20 INFO - 'SHELL': '/bin/bash', 09:16:20 INFO - 'SHLVL': '1', 09:16:20 INFO - 'TERM': 'linux', 09:16:20 INFO - 'TMOUT': '86400', 09:16:20 INFO - 'USER': 'cltbld', 09:16:20 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', 09:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:20 INFO - '_': '/tools/buildbot/bin/python'} 09:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:20 INFO - Downloading/unpacking psutil>=0.7.1 09:16: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:16: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:16: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:16: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:16: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:16: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:16:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:16:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:22 INFO - Installing collected packages: psutil 09:16:22 INFO - Running setup.py install for psutil 09:16:22 INFO - building 'psutil._psutil_linux' extension 09:16:22 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:16:22 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:16:22 INFO - building 'psutil._psutil_posix' extension 09:16:22 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:16:23 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:16:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:23 INFO - Successfully installed psutil 09:16:23 INFO - Cleaning up... 09:16:23 INFO - Return code: 0 09:16:23 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:16:23 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:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:23 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:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:23 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 0x7f2be08e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13676d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6920>, '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 0x13f7820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f7cb0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', '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:16:23 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:16:23 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:16:23 INFO - Using env: {'DISPLAY': ':0', 09:16:23 INFO - 'HOME': '/home/cltbld', 09:16:23 INFO - 'LANG': 'en_US.UTF-8', 09:16:23 INFO - 'LANGUAGE': 'en_US:en', 09:16:23 INFO - 'LOGNAME': 'cltbld', 09:16:23 INFO - 'MAIL': '/var/mail/cltbld', 09:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:23 INFO - 'MOZ_NO_REMOTE': '1', 09:16:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:23 INFO - 'NO_EM_RESTART': '1', 09:16:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:16:23 INFO - 'PWD': '/builds/slave/test-pgo', 09:16:23 INFO - 'SHELL': '/bin/bash', 09:16:23 INFO - 'SHLVL': '1', 09:16:23 INFO - 'TERM': 'linux', 09:16:23 INFO - 'TMOUT': '86400', 09:16:23 INFO - 'USER': 'cltbld', 09:16:23 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', 09:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:23 INFO - '_': '/tools/buildbot/bin/python'} 09:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:23 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:16: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:16: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:16: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:16: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:16: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:16: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:16:25 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:16:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:16:25 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:16:25 INFO - Installing collected packages: mozsystemmonitor 09:16:25 INFO - Running setup.py install for mozsystemmonitor 09:16:25 INFO - Successfully installed mozsystemmonitor 09:16:25 INFO - Cleaning up... 09:16:25 INFO - Return code: 0 09:16:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:16:25 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:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:25 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:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:25 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 0x7f2be08e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13676d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6920>, '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 0x13f7820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f7cb0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', '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:16:25 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:16:25 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:16:25 INFO - Using env: {'DISPLAY': ':0', 09:16:25 INFO - 'HOME': '/home/cltbld', 09:16:25 INFO - 'LANG': 'en_US.UTF-8', 09:16:25 INFO - 'LANGUAGE': 'en_US:en', 09:16:25 INFO - 'LOGNAME': 'cltbld', 09:16:25 INFO - 'MAIL': '/var/mail/cltbld', 09:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:25 INFO - 'MOZ_NO_REMOTE': '1', 09:16:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:25 INFO - 'NO_EM_RESTART': '1', 09:16:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:16:25 INFO - 'PWD': '/builds/slave/test-pgo', 09:16:25 INFO - 'SHELL': '/bin/bash', 09:16:25 INFO - 'SHLVL': '1', 09:16:25 INFO - 'TERM': 'linux', 09:16:25 INFO - 'TMOUT': '86400', 09:16:25 INFO - 'USER': 'cltbld', 09:16:25 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', 09:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:25 INFO - '_': '/tools/buildbot/bin/python'} 09:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:25 INFO - Downloading/unpacking blobuploader==1.2.4 09:16: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:16: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:16: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:16: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:16: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:16: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:16:27 INFO - Downloading blobuploader-1.2.4.tar.gz 09:16:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:16:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:16:27 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:16:27 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:16:27 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:16:27 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:16:27 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:16:27 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:16:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:16:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:16:27 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:16:27 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:16:27 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:16:27 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:16:27 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:16:27 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:16:28 INFO - Downloading docopt-0.6.1.tar.gz 09:16:28 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:16:28 INFO - Installing collected packages: blobuploader, requests, docopt 09:16:28 INFO - Running setup.py install for blobuploader 09:16:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:16:28 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:16:28 INFO - Running setup.py install for requests 09:16:28 INFO - Running setup.py install for docopt 09:16:28 INFO - Successfully installed blobuploader requests docopt 09:16:28 INFO - Cleaning up... 09:16:28 INFO - Return code: 0 09:16:28 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:16:28 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:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:28 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:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:28 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 0x7f2be08e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13676d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6920>, '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 0x13f7820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f7cb0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', '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:16:28 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:16:28 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:16:28 INFO - Using env: {'DISPLAY': ':0', 09:16:28 INFO - 'HOME': '/home/cltbld', 09:16:28 INFO - 'LANG': 'en_US.UTF-8', 09:16:28 INFO - 'LANGUAGE': 'en_US:en', 09:16:28 INFO - 'LOGNAME': 'cltbld', 09:16:28 INFO - 'MAIL': '/var/mail/cltbld', 09:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:28 INFO - 'MOZ_NO_REMOTE': '1', 09:16:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:28 INFO - 'NO_EM_RESTART': '1', 09:16:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:16:28 INFO - 'PWD': '/builds/slave/test-pgo', 09:16:28 INFO - 'SHELL': '/bin/bash', 09:16:28 INFO - 'SHLVL': '1', 09:16:28 INFO - 'TERM': 'linux', 09:16:28 INFO - 'TMOUT': '86400', 09:16:28 INFO - 'USER': 'cltbld', 09:16:28 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', 09:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:28 INFO - '_': '/tools/buildbot/bin/python'} 09:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:16:28 INFO - Running setup.py (path:/tmp/pip-0thour-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:16:29 INFO - Running setup.py (path:/tmp/pip-Va0UUP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:16:29 INFO - Running setup.py (path:/tmp/pip-Bx4TEX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:16:29 INFO - Running setup.py (path:/tmp/pip-02gPR0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:16:29 INFO - Running setup.py (path:/tmp/pip-kWQ7iN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:16:29 INFO - Running setup.py (path:/tmp/pip-odbT7F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:16:29 INFO - Running setup.py (path:/tmp/pip-CQrxKD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:16:29 INFO - Running setup.py (path:/tmp/pip-Fimjbg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:16:29 INFO - Running setup.py (path:/tmp/pip-ucweaj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:16:29 INFO - Running setup.py (path:/tmp/pip-_5jjG8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:16:29 INFO - Running setup.py (path:/tmp/pip-4YOw3k-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:16:29 INFO - Running setup.py (path:/tmp/pip-aw4b5v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:16:29 INFO - Running setup.py (path:/tmp/pip-ubt_KB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:16:29 INFO - Running setup.py (path:/tmp/pip-tOsDzp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:16:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:16:29 INFO - Running setup.py (path:/tmp/pip-HGhLAO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:16:30 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:16:30 INFO - Running setup.py (path:/tmp/pip-nTtHuA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:16:30 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:16:30 INFO - Running setup.py (path:/tmp/pip-DI7zP3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:16:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:16:30 INFO - Running setup.py install for manifestparser 09:16:30 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:16:30 INFO - Running setup.py install for mozcrash 09:16:30 INFO - Running setup.py install for mozdebug 09:16:30 INFO - Running setup.py install for mozdevice 09:16:30 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:16:30 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:16:30 INFO - Running setup.py install for mozfile 09:16:30 INFO - Running setup.py install for mozhttpd 09:16:30 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:16:30 INFO - Running setup.py install for mozinfo 09:16:30 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:16:30 INFO - Running setup.py install for mozInstall 09:16:30 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:16:30 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:16:30 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:16:30 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:16:30 INFO - Running setup.py install for mozleak 09:16:31 INFO - Running setup.py install for mozlog 09:16:31 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:16:31 INFO - Running setup.py install for moznetwork 09:16:31 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:16:31 INFO - Running setup.py install for mozprocess 09:16:31 INFO - Running setup.py install for mozprofile 09:16:31 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:16:31 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:16:31 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:16:31 INFO - Running setup.py install for mozrunner 09:16:31 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:16:31 INFO - Running setup.py install for mozscreenshot 09:16:31 INFO - Running setup.py install for moztest 09:16:31 INFO - Running setup.py install for mozversion 09:16:31 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:16:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:16:31 INFO - Cleaning up... 09:16:31 INFO - Return code: 0 09:16:31 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:16:31 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:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:31 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:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:31 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 0x7f2be08e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13676d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6920>, '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 0x13f7820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f7cb0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', '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:16:31 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:16:31 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:16:31 INFO - Using env: {'DISPLAY': ':0', 09:16:31 INFO - 'HOME': '/home/cltbld', 09:16:31 INFO - 'LANG': 'en_US.UTF-8', 09:16:31 INFO - 'LANGUAGE': 'en_US:en', 09:16:31 INFO - 'LOGNAME': 'cltbld', 09:16:31 INFO - 'MAIL': '/var/mail/cltbld', 09:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:31 INFO - 'MOZ_NO_REMOTE': '1', 09:16:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:31 INFO - 'NO_EM_RESTART': '1', 09:16:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:16:31 INFO - 'PWD': '/builds/slave/test-pgo', 09:16:31 INFO - 'SHELL': '/bin/bash', 09:16:31 INFO - 'SHLVL': '1', 09:16:31 INFO - 'TERM': 'linux', 09:16:31 INFO - 'TMOUT': '86400', 09:16:31 INFO - 'USER': 'cltbld', 09:16:31 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', 09:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:31 INFO - '_': '/tools/buildbot/bin/python'} 09:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:16:32 INFO - Running setup.py (path:/tmp/pip-SUb0iF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:16:32 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:16:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:16:32 INFO - Running setup.py (path:/tmp/pip-PU2IcI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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:16:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:16:32 INFO - Running setup.py (path:/tmp/pip-MmjfQC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:16:32 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:16:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:16:32 INFO - Running setup.py (path:/tmp/pip-ElSqlS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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:16:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:16:32 INFO - Running setup.py (path:/tmp/pip-5BTsTk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:16:32 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:16:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:16:32 INFO - Running setup.py (path:/tmp/pip-z0ijOd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:16:32 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:16:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:16:32 INFO - Running setup.py (path:/tmp/pip-sncO8y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:16:32 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:16:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:16:32 INFO - Running setup.py (path:/tmp/pip-RV0Nyi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:16:32 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:16:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:16:32 INFO - Running setup.py (path:/tmp/pip-nFMOpg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:16:32 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:16:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:16:32 INFO - Running setup.py (path:/tmp/pip-bmvAVO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:16:32 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:16:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:16:32 INFO - Running setup.py (path:/tmp/pip-p6ikbt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:16:32 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:16:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:16:32 INFO - Running setup.py (path:/tmp/pip-TCjqws-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:16:33 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:16:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:16:33 INFO - Running setup.py (path:/tmp/pip-hDkQNn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 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:16:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:16:33 INFO - Running setup.py (path:/tmp/pip-3P4ell-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:16:33 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:16:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:16:33 INFO - Running setup.py (path:/tmp/pip-07H6a8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:16:33 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:16:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:16:33 INFO - Running setup.py (path:/tmp/pip-rZ4Zkf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:16:33 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:16:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:16:33 INFO - Running setup.py (path:/tmp/pip-VQ7DUd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:16:33 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:16:33 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.17->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:16:33 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.17->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:16:33 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:16:33 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.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:33 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.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:33 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:16:33 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:16:33 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:16:33 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:16:33 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:16:33 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:16:35 INFO - Downloading blessings-1.6.tar.gz 09:16:35 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:16:35 INFO - Installing collected packages: blessings 09:16:35 INFO - Running setup.py install for blessings 09:16:35 INFO - Successfully installed blessings 09:16:35 INFO - Cleaning up... 09:16:35 INFO - Return code: 0 09:16:35 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:16:35 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:16:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:16:35 INFO - Reading from file tmpfile_stdout 09:16:35 INFO - Current package versions: 09:16:35 INFO - argparse == 1.2.1 09:16:35 INFO - blessings == 1.6 09:16:35 INFO - blobuploader == 1.2.4 09:16:35 INFO - docopt == 0.6.1 09:16:35 INFO - manifestparser == 1.1 09:16:35 INFO - mozInstall == 1.12 09:16:35 INFO - mozcrash == 0.17 09:16:35 INFO - mozdebug == 0.1 09:16:35 INFO - mozdevice == 0.48 09:16:35 INFO - mozfile == 1.2 09:16:35 INFO - mozhttpd == 0.7 09:16:35 INFO - mozinfo == 0.9 09:16:35 INFO - mozleak == 0.1 09:16:35 INFO - mozlog == 3.1 09:16:35 INFO - moznetwork == 0.27 09:16:35 INFO - mozprocess == 0.22 09:16:35 INFO - mozprofile == 0.28 09:16:35 INFO - mozrunner == 6.11 09:16:35 INFO - mozscreenshot == 0.1 09:16:35 INFO - mozsystemmonitor == 0.0 09:16:35 INFO - moztest == 0.7 09:16:35 INFO - mozversion == 1.4 09:16:35 INFO - psutil == 3.1.1 09:16:35 INFO - requests == 1.2.3 09:16:35 INFO - wsgiref == 0.1.2 09:16:35 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:35 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 0x7f2be08e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13676d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6920>, '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 0x13f7820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f7cb0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', '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:16:35 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:16:35 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:16:35 INFO - Using env: {'DISPLAY': ':0', 09:16:35 INFO - 'HOME': '/home/cltbld', 09:16:35 INFO - 'LANG': 'en_US.UTF-8', 09:16:35 INFO - 'LANGUAGE': 'en_US:en', 09:16:35 INFO - 'LOGNAME': 'cltbld', 09:16:35 INFO - 'MAIL': '/var/mail/cltbld', 09:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:35 INFO - 'MOZ_NO_REMOTE': '1', 09:16:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:35 INFO - 'NO_EM_RESTART': '1', 09:16:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:16:35 INFO - 'PWD': '/builds/slave/test-pgo', 09:16:35 INFO - 'SHELL': '/bin/bash', 09:16:35 INFO - 'SHLVL': '1', 09:16:35 INFO - 'TERM': 'linux', 09:16:35 INFO - 'TMOUT': '86400', 09:16:35 INFO - 'USER': 'cltbld', 09:16:35 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', 09:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:35 INFO - '_': '/tools/buildbot/bin/python'} 09:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.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 1)) 09:16:35 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 2)) 09:16:35 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 3)) 09:16:35 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 4)) 09:16:35 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 5)) 09:16:35 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 6)) 09:16:35 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 7)) 09:16:35 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 8)) 09:16:35 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 9)) 09:16:35 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.1->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:16:35 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 4)) 09:16:35 INFO - Cleaning up... 09:16:35 INFO - Return code: 0 09:16:35 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:35 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 0x7f2be08e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13676d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6920>, '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 0x13f7820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f7cb0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', '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:16:35 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:16:35 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:16:35 INFO - Using env: {'DISPLAY': ':0', 09:16:35 INFO - 'HOME': '/home/cltbld', 09:16:35 INFO - 'LANG': 'en_US.UTF-8', 09:16:35 INFO - 'LANGUAGE': 'en_US:en', 09:16:35 INFO - 'LOGNAME': 'cltbld', 09:16:35 INFO - 'MAIL': '/var/mail/cltbld', 09:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:35 INFO - 'MOZ_NO_REMOTE': '1', 09:16:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:35 INFO - 'NO_EM_RESTART': '1', 09:16:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:16:35 INFO - 'PWD': '/builds/slave/test-pgo', 09:16:35 INFO - 'SHELL': '/bin/bash', 09:16:35 INFO - 'SHLVL': '1', 09:16:35 INFO - 'TERM': 'linux', 09:16:35 INFO - 'TMOUT': '86400', 09:16:35 INFO - 'USER': 'cltbld', 09:16:35 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', 09:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:35 INFO - '_': '/tools/buildbot/bin/python'} 09:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:36 INFO - Downloading/unpacking jsonschema 09:16:36 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:16:36 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:16:36 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:16:36 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:16:36 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:16:36 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:16:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:16:37 INFO - Downloading/unpacking functools32 (from jsonschema) 09:16:37 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:16:37 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:16:37 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:16:37 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:16:37 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:16:37 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:16:38 INFO - Downloading functools32-3.2.3-2.tar.gz 09:16:38 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:16:38 INFO - warning: no files found matching '*.txt' 09:16:38 INFO - no previously-included directories found matching 'build' 09:16:38 INFO - no previously-included directories found matching 'dist' 09:16:38 INFO - no previously-included directories found matching '.git*' 09:16:38 INFO - Installing collected packages: jsonschema, functools32 09:16:38 INFO - Running setup.py install for functools32 09:16:38 INFO - warning: no files found matching '*.txt' 09:16:38 INFO - no previously-included directories found matching 'build' 09:16:38 INFO - no previously-included directories found matching 'dist' 09:16:38 INFO - no previously-included directories found matching '.git*' 09:16:38 INFO - Successfully installed jsonschema functools32 09:16:38 INFO - Cleaning up... 09:16:38 INFO - Return code: 0 09:16:38 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:16:38 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:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:38 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:16:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:38 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 0x7f2be08e7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13676d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6920>, '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 0x13f7820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f7cb0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', '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:16:38 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:16:38 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:16:38 INFO - Using env: {'DISPLAY': ':0', 09:16:38 INFO - 'HOME': '/home/cltbld', 09:16:38 INFO - 'LANG': 'en_US.UTF-8', 09:16:38 INFO - 'LANGUAGE': 'en_US:en', 09:16:38 INFO - 'LOGNAME': 'cltbld', 09:16:38 INFO - 'MAIL': '/var/mail/cltbld', 09:16:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:38 INFO - 'MOZ_NO_REMOTE': '1', 09:16:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:38 INFO - 'NO_EM_RESTART': '1', 09:16:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:16:38 INFO - 'PWD': '/builds/slave/test-pgo', 09:16:38 INFO - 'SHELL': '/bin/bash', 09:16:38 INFO - 'SHLVL': '1', 09:16:38 INFO - 'TERM': 'linux', 09:16:38 INFO - 'TMOUT': '86400', 09:16:38 INFO - 'USER': 'cltbld', 09:16:38 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', 09:16:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:38 INFO - '_': '/tools/buildbot/bin/python'} 09:16:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:38 INFO - Downloading/unpacking flake8 09:16:38 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:16:38 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:16:38 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:16:38 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:16:38 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:16:38 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:16:40 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:16:40 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:16:40 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:16:40 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:16:40 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:16:40 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:16:40 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:16:40 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:16:40 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:16:40 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:16:40 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:16:40 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:16:40 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:16:40 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:16:40 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:16:40 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:16:40 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:16:40 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:16:40 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:16:40 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:16:40 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:16:40 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:16:40 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:16:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:16:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:16:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:16:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:16:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:16:41 INFO - no previously-included directories found matching 'docs/_build' 09:16:41 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:16:41 INFO - Running setup.py install for pep8 09:16:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:16:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:16:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:16:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:16:41 INFO - no previously-included directories found matching 'docs/_build' 09:16:41 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:16:41 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:16:41 INFO - Cleaning up... 09:16:41 INFO - Return code: 0 09:16:41 INFO - Running post-action listener: _resource_record_post_action 09:16:41 INFO - Running post-action listener: _start_resource_monitoring 09:16:41 INFO - Starting resource monitoring. 09:16:41 INFO - ##### 09:16:41 INFO - ##### Running install step. 09:16:41 INFO - ##### 09:16:41 INFO - Running pre-action listener: _resource_record_pre_action 09:16:41 INFO - Running main action method: install 09:16:41 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:16:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:16:41 INFO - Reading from file tmpfile_stdout 09:16:41 INFO - Detecting whether we're running mozinstall >=1.0... 09:16:41 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:16:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:16:41 INFO - Reading from file tmpfile_stdout 09:16:41 INFO - Output received: 09:16:41 INFO - Usage: mozinstall [options] installer 09:16:41 INFO - Options: 09:16:41 INFO - -h, --help show this help message and exit 09:16:41 INFO - -d DEST, --destination=DEST 09:16:41 INFO - Directory to install application into. [default: 09:16:41 INFO - "/builds/slave/test-pgo"] 09:16:41 INFO - --app=APP Application being installed. [default: firefox] 09:16:41 INFO - mkdir: /builds/slave/test-pgo/build/application 09:16:41 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 09:16:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 09:16:56 INFO - Reading from file tmpfile_stdout 09:16:56 INFO - Output received: 09:16:56 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:16:56 INFO - Running post-action listener: _resource_record_post_action 09:16:56 INFO - ##### 09:16:56 INFO - ##### Running run-tests step. 09:16:56 INFO - ##### 09:16:56 INFO - Running pre-action listener: _resource_record_pre_action 09:16:56 INFO - Running main action method: run_tests 09:16:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:16:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:16:56 INFO - Python 2.7.3 09:16:56 INFO - Return code: 0 09:16:56 INFO - grabbing minidump binary from tooltool 09:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:56 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 0x13f6920>, '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 0x13f7820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f7cb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:16:56 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:16:56 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:16:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:16:56 INFO - Return code: 0 09:16:56 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:16:56 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:16:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:16:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:16:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:16:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:16:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:16:57 INFO - Return code: 0 09:16:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test-pgo/build 09:16:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log 09:16:57 INFO - Using env: {'DISPLAY': ':0', 09:16:57 INFO - 'HOME': '/home/cltbld', 09:16:57 INFO - 'LANG': 'en_US.UTF-8', 09:16:57 INFO - 'LANGUAGE': 'en_US:en', 09:16:57 INFO - 'LOGNAME': 'cltbld', 09:16:57 INFO - 'MAIL': '/var/mail/cltbld', 09:16:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:16:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:57 INFO - 'MOZ_NO_REMOTE': '1', 09:16:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:16:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:57 INFO - 'NO_EM_RESTART': '1', 09:16:57 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:16:57 INFO - 'PWD': '/builds/slave/test-pgo', 09:16:57 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:16:57 INFO - 'SHELL': '/bin/bash', 09:16:57 INFO - 'SHLVL': '1', 09:16:57 INFO - 'TERM': 'linux', 09:16:57 INFO - 'TMOUT': '86400', 09:16:57 INFO - 'USER': 'cltbld', 09:16:57 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338', 09:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:57 INFO - '_': '/tools/buildbot/bin/python'} 09:16:57 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:16:58 INFO - mozversion application_buildid: 20160307062827 09:16:58 INFO - mozversion application_changeset: 8849dd1a4a796c2fd70d6f002912c3f799c59e69 09:16:58 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:16:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:16:58 INFO - mozversion application_name: Firefox 09:16:58 INFO - mozversion application_remotingname: firefox-dev 09:16:58 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:16:58 INFO - mozversion application_vendor: Mozilla 09:16:58 INFO - mozversion application_version: 47.0a2 09:16:58 INFO - mozversion platform_buildid: 20160307062827 09:16:58 INFO - mozversion platform_changeset: 8849dd1a4a796c2fd70d6f002912c3f799c59e69 09:16:58 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:16:58 INFO - mozversion platform_version: 47.0a2 09:16:58 INFO - using testdate: 1457371018 09:16:58 INFO - actual date: 1457371018 09:16:58 INFO - starting webserver on 'localhost:51797' 09:16:58 INFO - SUITE-START | Running 2 tests 09:16:58 INFO - TEST-START | dromaeo_css 09:16:58 INFO - operating with platform_type : linux_ 09:16:58 INFO - Initialising browser for dromaeo_css test... 09:16:58 INFO - TEST-INFO | started process 16855 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpu5hBo_/profile http://localhost:51797/getInfo.html) 09:17:02 INFO - PROCESS | 16855 | __metrics Screen width/height:1600/1200 09:17:02 INFO - PROCESS | 16855 | colorDepth:24 09:17:02 INFO - PROCESS | 16855 | Browser inner width/height: 1024/697 09:17:02 INFO - PROCESS | 16855 | __metrics 09:17:03 INFO - PROCESS | 16855 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:17:03 INFO - PROCESS | 16855 | JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 09:17:03 INFO - TEST-INFO | 16855: exit 0 09:17:03 INFO - Browser initialized. 09:17:03 INFO - Running cycle 1/1 for dromaeo_css test... 09:17:03 INFO - TEST-INFO | started process 16946 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpu5hBo_/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:17:15 INFO - PROCESS | 16946 | RSS: Main: 144510976 09:17:15 INFO - PROCESS | 16946 | 09:18:06 INFO - PROCESS | 16946 | 1457371086887 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:20:14 INFO - PROCESS | 16946 | Cycle 1(1): loaded http://localhost:51797/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51797/tests/dromaeo/cssquery-ext.html) 09:20:15 INFO - PROCESS | 16946 | RSS: Main: 260206592 09:20:15 INFO - PROCESS | 16946 | 09:23:17 INFO - PROCESS | 16946 | Cycle 1(1): loaded http://localhost:51797/tests/dromaeo/cssquery-ext.html (next: http://localhost:51797/tests/dromaeo/cssquery-jquery.html) 09:23:17 INFO - PROCESS | 16946 | RSS: Main: 230596608 09:23:17 INFO - PROCESS | 16946 | 09:26:19 INFO - PROCESS | 16946 | Cycle 1(1): loaded http://localhost:51797/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51797/tests/dromaeo/cssquery-mootools.html) 09:26:20 INFO - PROCESS | 16946 | RSS: Main: 224874496 09:26:20 INFO - PROCESS | 16946 | 09:29:22 INFO - PROCESS | 16946 | Cycle 1(1): loaded http://localhost:51797/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51797/tests/dromaeo/cssquery-prototype.html) 09:29:22 INFO - PROCESS | 16946 | RSS: Main: 190201856 09:29:22 INFO - PROCESS | 16946 | 09:32:25 INFO - PROCESS | 16946 | Cycle 1(1): loaded http://localhost:51797/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51797/tests/dromaeo/cssquery-yui.html) 09:32:25 INFO - PROCESS | 16946 | RSS: Main: 215339008 09:32:25 INFO - PROCESS | 16946 | 09:35:27 INFO - PROCESS | 16946 | Cycle 1(1): loaded http://localhost:51797/tests/dromaeo/cssquery-yui.html (next: http://localhost:51797/tests/dromaeo/cssquery-dojo.html) 09:35:28 INFO - PROCESS | 16946 | RSS: Main: 261730304 09:35:28 INFO - PROCESS | 16946 | 09:35:28 INFO - PROCESS | 16946 | __start_tp_report 09:35:28 INFO - PROCESS | 16946 | _x_x_mozilla_page_load 09:35:28 INFO - PROCESS | 16946 | _x_x_mozilla_page_load_details 09:35:28 INFO - PROCESS | 16946 | |i|pagename|runs| 09:35:28 INFO - PROCESS | 16946 | |0;dojo.html;3202.647708752037;3271.738260939125;3294.1270563300714;3295.983520082401;3340.114869559567;5292.603054770893;5293.311869456975;5305.151175811871;5316.415194328631;5369.3825210100895;5330.573554115671;5342.759575819087;5343.118385466403;5344.35867695877;5355.196720491928;4748.548887855665;4873.269009648546;4877.853664390074;4916.852494425173;4949.455559888409;4847.248676455155;4890.315355850184;4938.407391113072;4942.15983282841;4944.87637809054;4888.022395520892;4936.950630493685;5000.549950504454;5012.573931215833;5025.0954828130925;4898.828541001048;4908.950910490885;4926.926096108561;4928.137575924235;4959.057779021992;4683.555062269079;4767.4994125617;4783.689060211075;4797.496262892416;4797.688150270221;5169.121249387613;5199.324087868591;5211.895762084775;5214.687118772886;5222.268882356473;5586.078297081018;5681.147827825818;5686.687232202227;5748;5753.827385178452;5764.538836893081;5775.047117225653;5783.421657834225;5788.8842223155725;5810.099434587646;5415.404305526389;5416.187571864212;5418.133098704188;5424.755885985141;5424.891502169974;5791.333996590446;5857.26784151981;5878.794242201588;5950.107483877453;5963.463288303987;5651.152327150878;5687.8862422752145;5688.943110568925;5691.231683722644;5694.9430505695245;5770;5773.653580785082;5774.2782152230975;5777.45114214149;5779.797707080316;5348.438413966555;5683.062294721325;5731.111677689964;5747.5689323300085;5767.509761670305;5709.3434255060365;5728.198052272685;5729.455701708331;5733;5735.770569177186;4322.200392927319;4375.5405682403525;4385.890352741207;4393.648508119343;4413.227685155085;3933.8033098344968;3976.5824588418377;4001.159756451178;4003.01926028125;4021.879343619696;5390.245365648812;5441.972790136024;5452.672839629635;5455.290812194468;5456.072467680504;5631.802886898939;5736.426357364314;5738.913916291259;5770.480656740913;5792.79725209624;5383.192521121785;5416.945830541723;5432.837014889559;5436.782528698808;5464.3442786865835;4917.655764096479;4942.085714142823;4943.530364615428;4944.357233559686;4968.354113965162;5811.0411782056335;5861.325947516005;5901.616394934402;5915.408459154136;5945.732442040118;5768.942310576925;5796.449337312949;5804.071348584206;5808.680522571299;5813.505852002627;5945.524358051432;5962.16529685853;5966.224390829165;5972.850678733066;5977.133315669291;5627.943720562906;5706.172604972338;5708.457696518824;5713.143028545585;5784.392638772868;5773.393793651606;5805.303363596396;5808.273965754281;5811.27359080115;5812.447817457335;5594.580406469449;5605.719714014364;5606.6916319602815;5636.605437619492;5661.66030038199;5428.972855135699;5538.6122971391615;5606.775728970795;5644.858878527905;5658.943410565842;5535.557155427476;5545.140503222007;5548.306461692288;5575.721213939206;5575.8884822304135;5475.233467314659;5571.8885622288135;5643.181738647955;5664.830055098354;5667.3482549507335;5462.071447853795;5462.617616766788;5485.588580856372;5545.973994810973;5600.215969764155 09:35:28 INFO - PROCESS | 16946 | |1;ext.html;546.5518275014489;549.3819453115245;571.6627189957924;571.7512881896378;608.0848323273473;31220.687793122062;38890.22219555611;39278.85769138469;39343.21313573731;39480;42970.355444668356;43480.56519434805;43485.34771978423;43532.129357412836;43573.564264357345;8899.199072083511;9170.862437063415;9174.357794954356;9178.908210917873;9179.357444978854;9017.143371379709;9181.95409022954;9184.448933064004;9197.080291970817;9197.540122993856;9174.220191283748;9247.306452016091;9264.119908608705;9272.629094836233;9274.536273186313;9100.817983640323;9180.954095229548;9191.816163676689;9194.126557977015;9223.861642075375;9031.954840225757;9160;9166.450012999241;9171;9179.265658747285;42879.35680964788;43087.78456107731;43304.3504347435;43325;43364.78317608423;3661.9633803662155;3705.5368078990127;3834.884953451401;4124.546299907008;4433.9556604433865;32926;33373.66626333754;33563.6643633563;33611.15972100692;33627.495587566205;4124.896877578055;4171.812268447927;4237.957620423787;4246.851360202378;4250.213710463574;263754;264408.6779566109;264846;265434;265770;60854.39145608487;61474.0778888317;61577;61624.69187654123;61694.38305616976;71080.28919710737;71814.64092679607;71824.64087679528;71886.64056679788;71903.4048297583;13073.869261307455;13115.737685246242;13120.934395328153;13156.40796164175;13191;10590.735231619272;10606.098481629147;10622.94688526568;10623.893761062445;10625.309354891908;22004.449888752588;22499.775002249768;22627.547449050926;22631.094756209895;22669.319920402413;8443.00273402933;8698.260647845003;8740.825183496294;8771.89474526122;8856.512891791013;1031.3244824639874;1061.3578784835217;1106.9169812264113;1161.3786624156037;1178.4932479033926;30431;30498.085057448312;30525.084247472612;30544.69455305463;30589.541156882664;1123.500042481088;1132.6092498088146;1145.2841973766397;1175.894169524747;1191.4817054581113;15674.451394201149;15962.680746385007;16166.110863902599;16223.756643650355;16471.505854824376;348586;352737;353590;353888;354816;56676.716616416656;57142.42857571371;57200.42799572034;57268.42731572631;57313.713431432574;1271.8728127187208;1357.280641260114;1436.9281535923162;1478.4160256698544;1483.347327176039;27441;27594;27992.160235192976;28208.85895570509;28365.858170709016;41929.58070419379;42309.57690423179;42525.36211956885;42758.14483710246;42919.57080429156;185253;186367;186641;186926;186989;29912.25219369533;49425;49897.75051124866;50244.49755502398;51819.48180518297;47309.290360645304;47346;48021;48679;48854;38457.23085538329;38748.80625596854;39076.2184756309;39099.80450097731;40071.39892901667;38249.426258605585;40799.18401631891;42009.57990420057;42240.36639450472;43532.564674354115;42079.36880946727;42930.57069429391;42998.35502467403;43583.78208109066;44086;37257;37682.43476347912;37766.324053820106;38119.61880381161;38139.42790858084;28722.425551488435;29181.124566263046;29211;29388;29405.85297073501 09:35:28 INFO - PROCESS | 16946 | |2;jquery.html;378.9503076478154;383.48421373252984;388.5686887554814;389.2642904909721;390.5547675649758;17401.564960875967;18711.4386568403;19069.332573359934;19242.326518571834;19271.229150833984;18287.268509259648;18295.817041829578;18860.905695471505;19079.14143863526;19084.522886927833;5611.887762244763;5763.366029736726;5763.971180144114;5802.941970580282;5804.158397032428;4256.765877876715;6055.7274922628585;6295.213098362705;6320;6320.462760665326;5981.192539007244;6249.843753906143;6256.155419018442;6270.905936410957;6277.748890044393;6124.387561243884;6146.969265153691;6147.877042459126;6153.815385538441;6157.692115394235;3841.635044670752;5285.233641122016;5510.889782204374;5520.613557051008;5525.254090697745;20523.897380513;20691.172353105856;21059.157633694635;22225.666615000788;22248.88875555628;2391.4380120671626;2414.1912459326145;2440.987795061031;2573.1508602161243;2635.7232490588594;13558.254296013716;13954.58136255914;14125.71748565023;14391.928040359731;14516.709665806624;1894.6494898443832;1938.6122775444967;1946.27987644571;1993.6511110555596;1997.131247907165;67796;68046;68498.31501685019;68910.65544672245;69676;10591;10926.617568385227;11295.887041129648;11355.318680879174;11472.139589530652;14794.334254958552;14824.110553366832;14911.328990195467;14999.775003374958;15026.849731502545;7670.654820532978;7676.347510461562;7692.9615351924;7709.575973321521;7843.843123137505;7173.282671732889;7293.088363954505;7332.120145582564;7439.479236453504;7456.105267367951;4384.079343337935;4542.046170304273;4726.976365118152;4860.708357498562;4923.89212427201;1840.530664680498;1901.8953957532192;1974.8222659960672;1985.2356842615698;1985.3448362040492;898.820235952799;963.9036096390305;1038.1798379280435;1054.5992522841425;1055.0346433013706;16307.429239976544;16744.665106697797;16762.58093547671;16927.0690112045;17347.21937512815;970.1850445625709;998.9150922171697;1018.7656838927088;1048.7168464514539;1086.6305456144948;9984.750381240527;10077.193824494023;10121.746956326151;10133.240006999356;10336.224783141295;412975;418490;418764;419609;423647;74098.6295068532;75926.62036689892;76759.23240767521;77084;77230;1011.1607365886297;1026.1329176845554;1038.6105210545895;1040.3185913226848;1041.0110395124511;15633.843661563238;15764.448244311394;15783.68432631364;15811.288492017657;16062.839371606135;32076;32857.50713739248;33293.50059749154;33966.66033339635;34854.30291394208;40381.596184037786;42026;44069.33895991499;44754.55245447634;45820;8173.18268173178;8276.83446331082;8290.544020078954;8492.447990880573;8500.277476414327;9560.043995600385;11479.36863472517;12395.876041239833;12584.30786306762;13010.674733131369;10623.150147988297;10913.508892100033;10945.781084378426;11062.668119956414;11165.944170279097;11211.271267367594;11347.489362978851;11524.481398336928;11757.588484403004;11760.411979400824;10776.191785616267;10780.622678206226;10917.508712108132;10967.670969870916;10989.450527473755;8244.793880153044;8263.338932885476;8297.211764882326;8469.957650211709;8502.979642442699;10358.896411035794;10631.94684026575;10853;10908.509117089909;11177.944110279397 09:35:28 INFO - PROCESS | 16946 | |3;mootools.html;1436.5762100180445;1542.652903096803;1550.341105030362;1786.571222906502;1923.451816232374;7905.13043565208;8099.757007289776;8117.512949223051;8124.878126828103;8136.755897323075;7762.611869406535;7907.288344049049;7924.564148971805;7929.286364227219;7934.206579342077;2022.3427386099577;2052.445839623301;2314.143766806284;2334.8015418689433;2368.857868527894;2384.1178763857397;2405.0139442828445;2430.5503481855826;2431.051889762994;2434.172381390327;2343.379004563792;2390.5696974544576;2396.412878844677;2400.12395475651;2408.89159987801;2434.379233295516;2446.1683027770555;2468.8518688878635;2469.394998225446;2473.2209354053416;2387.2599494156866;2409.3133456964856;2424.260600516842;2430.6961629796274;2436.878156092206;8132.105468398471;8188.836223275501;8198.672053117869;8202.056763472217;8205.425620206588;4868.513148685139;4974.4776798436005;5005.6496045276845;5014.2979982802435;5030.119729047402;3210.181403742054;3311.867525298985;3355.429576971896;3377.4764911438765;3416.282580658065;1024.354656566366;1031.0411317474745;1043.57213542448;1043.9686809395655;1078.8489611454402;11631.418429078512;11780.941095294555;11788.410579471078;11790.823137652942;11794.469248883735;4551.385562949025;4651.790669419885;4661.930071048937;4692.085043416501;4710.717356958594;9441.103095205945;9460.716178514656;9531;9535.952320238355;9545.188658964065;5091.439941606421;5091.694498330112;5136.101182293084;5137.741253392943;5140.691558506502;4074.124063326399;4431;4550.954490455053;4556.066006468666;4556.088782243564;3775.716821238418;3868.5551161616213;3881.514810648669;3907.3552863777727;3974.741641793332;1110.9777804444027;1138.2243699828293;1138.5957984915235;1149.6665966869682;1153.798085335063;1403.333416627094;1409.6194027612487;1425.4868247430916;1441.1929319581068;1455.3596417576232;7812.1797211292;7900.209979002168;7903.486273392326;8069.596520173968;8076.8384632307025;445.5588966922723;449.79309517621607;451.35456297494244;452.1431886574922;470.1255664464094;6142.477889379364;6152.354002829638;6204.906926396107;6208.81373558794;6256.343083976117;9596.76008099803;10013.799724005625;10041.899581004243;10067.395956242648;10205.438700871524;9949.154321882575;10012.749681258027;10053.396796192252;10061.245406594535;10075.748106297402;7708.03649543807;7873.25204105609;7932.0878099019355;7954.125046244792;7961.36309095271;7527.247275272648;7569.545827250383;7603.353714934071;7608.543487390774;7674.501157424749;8653.653853845775;8953.104689530996;9009;9023.909760902307;9064;8963.731088067369;9150.908490915006;9314.20829229496;9319.627214911328;9472.28957828152;6340.239171299474;6467.417932386108;6734.057231987427;7125.8574828502105;7258.745943891939;7954.323882470055;7957.602119893866;7988.600569971594;7993.160718124514;8006.599670016592;7161.069061022034;7348.265173482609;7369.8157546061775;7381.8154546136775;7445.664945077593;7196.604186769778;7208.207097219197;7211.531250468703;7255.876586727908;7261.491695581259;7511.51175173612;7586.886196707164;7589.962050189934;7591.013168288087;7595.240475952361;6797.422219111431;6887.896681549681;6896.655167241517;6900.861982760417;6907.758228461979;6860.0738900247825;7099.219085900443;7104.538205016657;7112.50212485121;7128.821779455555 09:35:28 INFO - PROCESS | 16946 | |4;prototype.html;354.7162270183852;356.1470278682555;363.36048554543993;363.5346756152126;366.3680151738251;5979.282486101669;5984.910226346608;5994.280686317637;5997.850053748659;6014.699265036753;5863.736131874065;5874.794382196624;5882.058870580698;5896.204012458317;5900.085486749561;4425.734455932655;4538.8865278367975;4647.23320652092;4653.255479123341;4676.7427791471455;4642.723251105939;4659.4175728034;4663.207254766698;4667.789949452266;4676.415448068991;4619.653525985547;4629.166749985001;4630.606398456135;4633;4648.65135114866;4440.134173836103;4508.075844451896;4576.519465456146;4579.473360563545;4595.333676616872;4092.774897380643;4117.402976568381;4118.938215926763;4127.690423218271;4128.318827393507;5623;5631.915521267184;5633.436656334375;5634.971825140848;5638.689872057035;5394.190871369326;5400.054990376708;5402.054640437907;5405.351357837046;5410.97294513525;6264.3422440643535;6271.905921411182;6276.089966954766;6276.780312689079;6277.246730392337;4487.439070116236;4572.634189264851;4581.198290299184;4596.195665758479;4597.540245975376;6479.64361960092;6509.121268628722;6517.4786017118995;6517.934820651828;6537.378948999837;4666.789994450258;4698.2952557116705;4703.459102203222;4709.293605959065;4713.505081966413;4753.809847606126;4761.761911904391;4762.52374762521;4777.307290442866;4777.641676874179;5383.865403364947;5385.623006389594;5387.138057910715;5397.433269506645;5402.405735369185;6085.847853803691;6288.056791481315;6301.464375528131;6302.180716506825;6307.864584374856;1617.6360318928148;1630.6331075507917;1637.7297745871797;1639.8442147995922;1641.4911377473022;1038.3199004651965;1059.232056758859;1069.2301542889106;1124.4153040418937;1124.7356871135278;6057.969710151421;6077.696115194222;6106.603070800384;6114.602550834182;6114.633122012693;6488.0916671666;6488.967555162194;6490.935090649033;6506.674666266667;6506.837329066811;5981.551383646244;6112.2970858351855;6120.724567394478;6123.724432400552;6125.846853828601;3478.826058697055;3513;3515.8593656253975;3517.2262102337445;3519.630438803889;6550.70521826519;6603.438707709898;6679;6681.365270299314;6824.4199243064895;6745.966270168716;6758.695858686371;6770.390664840188;6770.763023294259;6771.593704377753;6793.320667933266;6794.150731158606;6797.116374871235;6797.966010169917;6803.047573339637;6614.966925165343;6617.205935287796;6620.2386725527185;6623.370779775913;6630.436412904957;6299.338569450142;6467.450266727381;6493.123428337114;6534.803955881332;6575.671216439063;6748.358905903931;6791.592504449749;6795.7961261162245;6796.2524122347495;6796.422304104207;4518.9322160168285;4538.2511885538515;4557.931631025604;4567.794449249725;4568;6597.703103360264;6607.273199948098;6609.074729537965;6609.239937407259;6616.569922954993;6207.099970504161;6390.297067322684;6402.87194256103;6413.807585772434;6414.390632889868;6335.683215839282;6353.697492014218;6413.262474815456;6430.324815894263;6431;6304.306526282197;6321.241451025722;6325.96837015812;6327.493800496041;6336;6367.61794292344;6403.711832967434;6422.710978005907;6428.453581445629;6429.7428102875365;5941.673207973603;6019.879602408015;6145.754169833157;6265.8120256392385;6280.214973128359 09:35:28 INFO - PROCESS | 16946 | |5;yui.html;748.5059860492076;835.5321020228669;902.318749344245;903.7830920579061;909.1226965977833;14490.130592164463;14719.264036798171;14769.187694676802;14778.408863645454;14782.556523304318;13850.445982160727;13921.58235252944;15183.240837958114;15485.845141548582;15496.14771187587;755.022246191181;896.7937374403891;914.1772404835634;928.1785619726556;931.65063101337;912.5893347993397;925.4539821505344;926.272875792503;926.6617684545126;927.4388994658236;945.8723072385243;953.7806304549957;953.9761505962372;954.1126752120525;955.1451450951395;779.5400713579015;793.6428607126787;937.0863408177041;943.2312665177858;957.9760505987337;693.5214701855704;700.0199720391441;702.4310308649959;702.5433468245631;702.662721893494;15180.240987950669;15214.16322102284;15237.314320855588;15270.31283592241;15275.923620381938;13666.658333541742;13701.725965480635;13948.16311021342;13979.930100349535;14120.293985300797;11299.717507062389;11307.830382544269;11318.547258109666;11347.716307092307;11582.131340149523;3927.175293188434;3943.960560394388;3954.960450395488;3961.4652021976954;3962.7820469874146;53224;53398;53488.465115348736;53626.73186634081;53732.731336343066;10979.670609881716;11042.447877606248;11085.27945683528;11146.721331966766;11254.94372528132;19415.61168776645;19472.513187170433;19522;19531.316403925794;19546.804531954782;8154.306883914934;8177.345812334999;8228.30059444942;8344.582770861432;8545.102764209792;4501.437320334958;4648.953510464919;4665.4634717007975;4728.668993170514;4732.929006064912;3451.5340429041757;3457.2048428861503;3458.8270586470576;3482.6169121396624;3489.5114683944266;592.2510508481303;605.6124080588427;612.7457105301339;625.0904933322006;649.8732747114267;631.1857703562354;668.5053060735021;681.2574294019543;697.4594689115976;718.2135561560083;8865.335099867536;8980.236679882153;9109.407888487227;9124.908750912406;9229.861552076723;564.9548036157098;609.8810731907236;682.7303215230056;705.1326867952345;719.3813320544327;3915.6671682906704;3928;4110.17796440713;4178;4251.383549385322;58029;60292;60370;60632.69683651553;61100.694496527234;54580.45419545833;54787.41087332935;55129.448705513234;56132.438675613535;56628.716856415456;47890.9999999993;47932.760336198095;47997.76001120042;48474.75762621235;49187;7825.1783562725095;8411.663533458594;8428.283595894172;9007.369484136048;9336.439813611205;34337.656623433446;34908.47637285392;36175.81912090423;36918.44622330721;37946.43080353852;47344.289835651805;48942;49593.00813983772;49785.75107124441;50198.49801501938;12105.515779368727;12360.196587221802;12468.750624987631;12518.311492867979;12572.937135314265;12422.565210217599;12511.436985335864;12511.749765004832;12685.175463595206;13233.933830330787;12060.879391206327;12091.81862272049;12515.812262816247;12558.183718058297;12759.170653907466;11238.213325067494;11479.25384849982;11923.582674606347;12192.451339689558;12238.571649992207;11963.222390544586;12122.75754484923;12422.751544969231;12480.56318028901;12542.24746515212;9920.553575088994;9973.750656233653;10094.19246460296;10212.489375531046;10389.428581428094;10180.898191018292;10418.441518326626;10637.14902807758;10697.290086215566;10706.357618542912 09:35:28 INFO - PROCESS | 16946 | __end_tp_report 09:35:28 INFO - PROCESS | 16946 | __start_cc_report 09:35:28 INFO - PROCESS | 16946 | _x_x_mozilla_cycle_collect,909 09:35:28 INFO - PROCESS | 16946 | __end_cc_report 09:35:28 INFO - PROCESS | 16946 | __startTimestamp1457372128132__endTimestamp 09:35:28 INFO - PROCESS | 16946 | 09:35:28 INFO - PROCESS | 16946 | ------- Summary: start ------- 09:35:28 INFO - PROCESS | 16946 | Number of tests: 6 09:35:28 INFO - PROCESS | 16946 | 09:35:28 INFO - PROCESS | 16946 | [#0] dojo.html Cycles:165 Average:5337.99 Median:5538.61 stddev:583.56 (10.5%) stddev-sans-first:560.80 09:35:28 INFO - PROCESS | 16946 | Values: 3202.6 3271.7 3294.1 3296.0 3340.1 5292.6 5293.3 5305.2 5316.4 5369.4 5330.6 5342.8 5343.1 5344.4 5355.2 4748.5 4873.3 4877.9 4916.9 4949.5 4847.2 4890.3 4938.4 4942.2 4944.9 4888.0 4937.0 5000.5 5012.6 5025.1 4898.8 4909.0 4926.9 4928.1 4959.1 4683.6 4767.5 4783.7 4797.5 4797.7 5169.1 5199.3 5211.9 5214.7 5222.3 5586.1 5681.1 5686.7 5748.0 5753.8 5764.5 5775.0 5783.4 5788.9 5810.1 5415.4 5416.2 5418.1 5424.8 5424.9 5791.3 5857.3 5878.8 5950.1 5963.5 5651.2 5687.9 5688.9 5691.2 5694.9 5770.0 5773.7 5774.3 5777.5 5779.8 5348.4 5683.1 5731.1 5747.6 5767.5 5709.3 5728.2 5729.5 5733.0 5735.8 4322.2 4375.5 4385.9 4393.6 4413.2 3933.8 3976.6 4001.2 4003.0 4021.9 5390.2 5442.0 5452.7 5455.3 5456.1 5631.8 5736.4 5738.9 5770.5 5792.8 5383.2 5416.9 5432.8 5436.8 5464.3 4917.7 4942.1 4943.5 4944.4 4968.4 5811.0 5861.3 5901.6 5915.4 5945.7 5768.9 5796.4 5804.1 5808.7 5813.5 5945.5 5962.2 5966.2 5972.9 5977.1 5627.9 5706.2 5708.5 5713.1 5784.4 5773.4 5805.3 5808.3 5811.3 5812.4 5594.6 5605.7 5606.7 5636.6 5661.7 5429.0 5538.6 5606.8 5644.9 5658.9 5535.6 5545.1 5548.3 5575.7 5575.9 5475.2 5571.9 5643.2 5664.8 5667.3 5462.1 5462.6 5485.6 5546.0 5600.2 09:35:28 INFO - PROCESS | 16946 | 09:35:28 INFO - PROCESS | 16946 | [#1] ext.html Cycles:180 Average:46305.53 Median:22461.87 stddev:72389.83 (322.3%) stddev-sans-first:72511.36 09:35:28 INFO - PROCESS | 16946 | Values: 546.6 549.4 571.7 571.8 608.1 31220.7 38890.2 39278.9 39343.2 39480.0 42970.4 43480.6 43485.3 43532.1 43573.6 8899.2 9170.9 9174.4 9178.9 9179.4 9017.1 9182.0 9184.4 9197.1 9197.5 9174.2 9247.3 9264.1 9272.6 9274.5 9100.8 9181.0 9191.8 9194.1 9223.9 9032.0 9160.0 9166.5 9171.0 9179.3 42879.4 43087.8 43304.4 43325.0 43364.8 3662.0 3705.5 3834.9 4124.5 4434.0 32926.0 33373.7 33563.7 33611.2 33627.5 4124.9 4171.8 4238.0 4246.9 4250.2 263754.0 264408.7 264846.0 265434.0 265770.0 60854.4 61474.1 61577.0 61624.7 61694.4 71080.3 71814.6 71824.6 71886.6 71903.4 13073.9 13115.7 13120.9 13156.4 13191.0 10590.7 10606.1 10622.9 10623.9 10625.3 22004.4 22499.8 22627.5 22631.1 22669.3 8443.0 8698.3 8740.8 8771.9 8856.5 1031.3 1061.4 1106.9 1161.4 1178.5 30431.0 30498.1 30525.1 30544.7 30589.5 1123.5 1132.6 1145.3 1175.9 1191.5 15674.5 15962.7 16166.1 16223.8 16471.5 348586.0 352737.0 353590.0 353888.0 354816.0 56676.7 57142.4 57200.4 57268.4 57313.7 1271.9 1357.3 1436.9 1478.4 1483.3 27441.0 27594.0 27992.2 28208.9 28365.9 41929.6 42309.6 42525.4 42758.1 42919.6 185253.0 186367.0 186641.0 186926.0 186989.0 29912.3 49425.0 49897.8 50244.5 51819.5 47309.3 47346.0 48021.0 48679.0 48854.0 38457.2 38748.8 39076.2 39099.8 40071.4 38249.4 40799.2 42009.6 42240.4 43532.6 42079.4 42930.6 42998.4 43583.8 44086.0 37257.0 37682.4 37766.3 38119.6 38139.4 28722.4 29181.1 29211.0 29388.0 29405.9 09:35:28 INFO - PROCESS | 16946 | 09:35:28 INFO - PROCESS | 16946 | [#2] jquery.html Cycles:180 Average:25370.70 Median:2427.59 stddev:68727.12 (2831.1%) stddev-sans-first:68894.30 09:35:28 INFO - PROCESS | 16946 | Values: 379.0 383.5 388.6 389.3 390.6 17401.6 18711.4 19069.3 19242.3 19271.2 18287.3 18295.8 18860.9 19079.1 19084.5 5611.9 5763.4 5764.0 5802.9 5804.2 4256.8 6055.7 6295.2 6320.0 6320.5 5981.2 6249.8 6256.2 6270.9 6277.7 6124.4 6147.0 6147.9 6153.8 6157.7 3841.6 5285.2 5510.9 5520.6 5525.3 20523.9 20691.2 21059.2 22225.7 22248.9 2391.4 2414.2 2441.0 2573.2 2635.7 13558.3 13954.6 14125.7 14391.9 14516.7 1894.6 1938.6 1946.3 1993.7 1997.1 67796.0 68046.0 68498.3 68910.7 69676.0 10591.0 10926.6 11295.9 11355.3 11472.1 14794.3 14824.1 14911.3 14999.8 15026.8 7670.7 7676.3 7693.0 7709.6 7843.8 7173.3 7293.1 7332.1 7439.5 7456.1 4384.1 4542.0 4727.0 4860.7 4923.9 1840.5 1901.9 1974.8 1985.2 1985.3 898.8 963.9 1038.2 1054.6 1055.0 16307.4 16744.7 16762.6 16927.1 17347.2 970.2 998.9 1018.8 1048.7 1086.6 9984.8 10077.2 10121.7 10133.2 10336.2 412975.0 418490.0 418764.0 419609.0 423647.0 74098.6 75926.6 76759.2 77084.0 77230.0 1011.2 1026.1 1038.6 1040.3 1041.0 15633.8 15764.4 15783.7 15811.3 16062.8 32076.0 32857.5 33293.5 33966.7 34854.3 40381.6 42026.0 44069.3 44754.6 45820.0 8173.2 8276.8 8290.5 8492.4 8500.3 9560.0 11479.4 12395.9 12584.3 13010.7 10623.2 10913.5 10945.8 11062.7 11165.9 11211.3 11347.5 11524.5 11757.6 11760.4 10776.2 10780.6 10917.5 10967.7 10989.5 8244.8 8263.3 8297.2 8470.0 8503.0 10358.9 10631.9 10853.0 10908.5 11177.9 09:35:28 INFO - PROCESS | 16946 | 09:35:28 INFO - PROCESS | 16946 | [#3] mootools.html Cycles:180 Average:5780.60 Median:5136.92 stddev:3046.50 (59.3%) stddev-sans-first:3037.55 09:35:28 INFO - PROCESS | 16946 | Values: 1436.6 1542.7 1550.3 1786.6 1923.5 7905.1 8099.8 8117.5 8124.9 8136.8 7762.6 7907.3 7924.6 7929.3 7934.2 2022.3 2052.4 2314.1 2334.8 2368.9 2384.1 2405.0 2430.6 2431.1 2434.2 2343.4 2390.6 2396.4 2400.1 2408.9 2434.4 2446.2 2468.9 2469.4 2473.2 2387.3 2409.3 2424.3 2430.7 2436.9 8132.1 8188.8 8198.7 8202.1 8205.4 4868.5 4974.5 5005.6 5014.3 5030.1 3210.2 3311.9 3355.4 3377.5 3416.3 1024.4 1031.0 1043.6 1044.0 1078.8 11631.4 11780.9 11788.4 11790.8 11794.5 4551.4 4651.8 4661.9 4692.1 4710.7 9441.1 9460.7 9531.0 9536.0 9545.2 5091.4 5091.7 5136.1 5137.7 5140.7 4074.1 4431.0 4551.0 4556.1 4556.1 3775.7 3868.6 3881.5 3907.4 3974.7 1111.0 1138.2 1138.6 1149.7 1153.8 1403.3 1409.6 1425.5 1441.2 1455.4 7812.2 7900.2 7903.5 8069.6 8076.8 445.6 449.8 451.4 452.1 470.1 6142.5 6152.4 6204.9 6208.8 6256.3 9596.8 10013.8 10041.9 10067.4 10205.4 9949.2 10012.7 10053.4 10061.2 10075.7 7708.0 7873.3 7932.1 7954.1 7961.4 7527.2 7569.5 7603.4 7608.5 7674.5 8653.7 8953.1 9009.0 9023.9 9064.0 8963.7 9150.9 9314.2 9319.6 9472.3 6340.2 6467.4 6734.1 7125.9 7258.7 7954.3 7957.6 7988.6 7993.2 8006.6 7161.1 7348.3 7369.8 7381.8 7445.7 7196.6 7208.2 7211.5 7255.9 7261.5 7511.5 7586.9 7590.0 7591.0 7595.2 6797.4 6887.9 6896.7 6900.9 6907.8 6860.1 7099.2 7104.5 7112.5 7128.8 09:35:28 INFO - PROCESS | 16946 | 09:35:28 INFO - PROCESS | 16946 | [#4] prototype.html Cycles:180 Average:5335.49 Median:6006.27 stddev:1581.22 (26.3%) stddev-sans-first:1540.83 09:35:28 INFO - PROCESS | 16946 | Values: 354.7 356.1 363.4 363.5 366.4 5979.3 5984.9 5994.3 5997.9 6014.7 5863.7 5874.8 5882.1 5896.2 5900.1 4425.7 4538.9 4647.2 4653.3 4676.7 4642.7 4659.4 4663.2 4667.8 4676.4 4619.7 4629.2 4630.6 4633.0 4648.7 4440.1 4508.1 4576.5 4579.5 4595.3 4092.8 4117.4 4118.9 4127.7 4128.3 5623.0 5631.9 5633.4 5635.0 5638.7 5394.2 5400.1 5402.1 5405.4 5411.0 6264.3 6271.9 6276.1 6276.8 6277.2 4487.4 4572.6 4581.2 4596.2 4597.5 6479.6 6509.1 6517.5 6517.9 6537.4 4666.8 4698.3 4703.5 4709.3 4713.5 4753.8 4761.8 4762.5 4777.3 4777.6 5383.9 5385.6 5387.1 5397.4 5402.4 6085.8 6288.1 6301.5 6302.2 6307.9 1617.6 1630.6 1637.7 1639.8 1641.5 1038.3 1059.2 1069.2 1124.4 1124.7 6058.0 6077.7 6106.6 6114.6 6114.6 6488.1 6489.0 6490.9 6506.7 6506.8 5981.6 6112.3 6120.7 6123.7 6125.8 3478.8 3513.0 3515.9 3517.2 3519.6 6550.7 6603.4 6679.0 6681.4 6824.4 6746.0 6758.7 6770.4 6770.8 6771.6 6793.3 6794.2 6797.1 6798.0 6803.0 6615.0 6617.2 6620.2 6623.4 6630.4 6299.3 6467.5 6493.1 6534.8 6575.7 6748.4 6791.6 6795.8 6796.3 6796.4 4518.9 4538.3 4557.9 4567.8 4568.0 6597.7 6607.3 6609.1 6609.2 6616.6 6207.1 6390.3 6402.9 6413.8 6414.4 6335.7 6353.7 6413.3 6430.3 6431.0 6304.3 6321.2 6326.0 6327.5 6336.0 6367.6 6403.7 6422.7 6428.5 6429.7 5941.7 6019.9 6145.8 6265.8 6280.2 09:35:28 INFO - PROCESS | 16946 | 09:35:28 INFO - PROCESS | 16946 | [#5] yui.html Cycles:180 Average:14851.25 Median:4697.07 stddev:17064.60 (363.3%) stddev-sans-first:17079.61 09:35:28 INFO - PROCESS | 16946 | Values: 748.5 835.5 902.3 903.8 909.1 14490.1 14719.3 14769.2 14778.4 14782.6 13850.4 13921.6 15183.2 15485.8 15496.1 755.0 896.8 914.2 928.2 931.7 912.6 925.5 926.3 926.7 927.4 945.9 953.8 954.0 954.1 955.1 779.5 793.6 937.1 943.2 958.0 693.5 700.0 702.4 702.5 702.7 15180.2 15214.2 15237.3 15270.3 15275.9 13666.7 13701.7 13948.2 13979.9 14120.3 11299.7 11307.8 11318.5 11347.7 11582.1 3927.2 3944.0 3955.0 3961.5 3962.8 53224.0 53398.0 53488.5 53626.7 53732.7 10979.7 11042.4 11085.3 11146.7 11254.9 19415.6 19472.5 19522.0 19531.3 19546.8 8154.3 8177.3 8228.3 8344.6 8545.1 4501.4 4649.0 4665.5 4728.7 4732.9 3451.5 3457.2 3458.8 3482.6 3489.5 592.3 605.6 612.7 625.1 649.9 631.2 668.5 681.3 697.5 718.2 8865.3 8980.2 9109.4 9124.9 9229.9 565.0 609.9 682.7 705.1 719.4 3915.7 3928.0 4110.2 4178.0 4251.4 58029.0 60292.0 60370.0 60632.7 61100.7 54580.5 54787.4 55129.4 56132.4 56628.7 47891.0 47932.8 47997.8 48474.8 49187.0 7825.2 8411.7 8428.3 9007.4 9336.4 34337.7 34908.5 36175.8 36918.4 37946.4 47344.3 48942.0 49593.0 49785.8 50198.5 12105.5 12360.2 12468.8 12518.3 12572.9 12422.6 12511.4 12511.7 12685.2 13233.9 12060.9 12091.8 12515.8 12558.2 12759.2 11238.2 11479.3 11923.6 12192.5 12238.6 11963.2 12122.8 12422.8 12480.6 12542.2 9920.6 9973.8 10094.2 10212.5 10389.4 10180.9 10418.4 10637.1 10697.3 10706.4 09:35:28 INFO - PROCESS | 16946 | -------- Summary: end -------- 09:35:28 INFO - PROCESS | 16946 | 09:35:28 INFO - TEST-INFO | 16946: exit 0 09:35:28 INFO - TEST-OK | dromaeo_css | took 1110639ms 09:35:28 INFO - TEST-START | kraken 09:35:28 INFO - operating with platform_type : linux_ 09:35:28 INFO - Initialising browser for kraken test... 09:35:28 INFO - TEST-INFO | started process 17136 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpo3u81y/profile http://localhost:51797/getInfo.html) 09:35:32 INFO - PROCESS | 17136 | __metrics Screen width/height:1600/1200 09:35:32 INFO - PROCESS | 17136 | colorDepth:24 09:35:32 INFO - PROCESS | 17136 | Browser inner width/height: 1024/697 09:35:32 INFO - PROCESS | 17136 | __metrics 09:35:33 INFO - PROCESS | 17136 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:35:33 INFO - PROCESS | 17136 | JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 09:35:34 INFO - TEST-INFO | 17136: exit 0 09:35:34 INFO - Browser initialized. 09:35:34 INFO - Running cycle 1/1 for kraken test... 09:35:34 INFO - TEST-INFO | started process 17226 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpo3u81y/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:46 INFO - PROCESS | 17226 | RSS: Main: 144019456 09:35:46 INFO - PROCESS | 17226 | 09:35:48 INFO - PROCESS | 17226 | Cycle 1(1): loaded http://localhost:51797/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51797/tests/kraken/driver.html?testName=audio-beat-detection) 09:35:49 INFO - PROCESS | 17226 | RSS: Main: 178757632 09:35:49 INFO - PROCESS | 17226 | 09:35:52 INFO - PROCESS | 17226 | Cycle 1(1): loaded http://localhost:51797/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51797/tests/kraken/driver.html?testName=audio-dft) 09:35:52 INFO - PROCESS | 17226 | RSS: Main: 177016832 09:35:52 INFO - PROCESS | 17226 | 09:35:55 INFO - PROCESS | 17226 | Cycle 1(1): loaded http://localhost:51797/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51797/tests/kraken/driver.html?testName=audio-fft) 09:35:56 INFO - PROCESS | 17226 | RSS: Main: 173797376 09:35:56 INFO - PROCESS | 17226 | 09:35:58 INFO - PROCESS | 17226 | Cycle 1(1): loaded http://localhost:51797/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51797/tests/kraken/driver.html?testName=audio-oscillator) 09:35:58 INFO - PROCESS | 17226 | RSS: Main: 171974656 09:35:58 INFO - PROCESS | 17226 | 09:36:00 INFO - PROCESS | 17226 | Cycle 1(1): loaded http://localhost:51797/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51797/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:36:01 INFO - PROCESS | 17226 | RSS: Main: 173027328 09:36:01 INFO - PROCESS | 17226 | 09:36:04 INFO - PROCESS | 17226 | Cycle 1(1): loaded http://localhost:51797/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51797/tests/kraken/driver.html?testName=imaging-darkroom) 09:36:05 INFO - PROCESS | 17226 | RSS: Main: 172630016 09:36:05 INFO - PROCESS | 17226 | 09:36:08 INFO - PROCESS | 17226 | Cycle 1(1): loaded http://localhost:51797/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51797/tests/kraken/driver.html?testName=imaging-desaturate) 09:36:09 INFO - PROCESS | 17226 | RSS: Main: 174026752 09:36:09 INFO - PROCESS | 17226 | 09:36:11 INFO - PROCESS | 17226 | Cycle 1(1): loaded http://localhost:51797/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51797/tests/kraken/driver.html?testName=json-parse-financial) 09:36:12 INFO - PROCESS | 17226 | RSS: Main: 173436928 09:36:12 INFO - PROCESS | 17226 | 09:36:13 INFO - PROCESS | 17226 | Cycle 1(1): loaded http://localhost:51797/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51797/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:36:14 INFO - PROCESS | 17226 | RSS: Main: 178757632 09:36:14 INFO - PROCESS | 17226 | 09:36:16 INFO - PROCESS | 17226 | Cycle 1(1): loaded http://localhost:51797/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51797/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:36:16 INFO - PROCESS | 17226 | RSS: Main: 175099904 09:36:16 INFO - PROCESS | 17226 | 09:36:18 INFO - PROCESS | 17226 | Cycle 1(1): loaded http://localhost:51797/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51797/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:36:19 INFO - PROCESS | 17226 | RSS: Main: 179761152 09:36:19 INFO - PROCESS | 17226 | 09:36:21 INFO - PROCESS | 17226 | Cycle 1(1): loaded http://localhost:51797/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51797/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:36:22 INFO - PROCESS | 17226 | RSS: Main: 190541824 09:36:22 INFO - PROCESS | 17226 | 09:36:24 INFO - PROCESS | 17226 | Cycle 1(1): loaded http://localhost:51797/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51797/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:36:25 INFO - PROCESS | 17226 | RSS: Main: 189476864 09:36:25 INFO - PROCESS | 17226 | 09:36:26 INFO - PROCESS | 17226 | Cycle 1(1): loaded http://localhost:51797/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51797/tests/kraken/driver.html?testName=ai-astar) 09:36:27 INFO - PROCESS | 17226 | RSS: Main: 249499648 09:36:27 INFO - PROCESS | 17226 | 09:36:27 INFO - PROCESS | 17226 | __start_tp_report 09:36:27 INFO - PROCESS | 17226 | _x_x_mozilla_page_load 09:36:27 INFO - PROCESS | 17226 | _x_x_mozilla_page_load_details 09:36:27 INFO - PROCESS | 17226 | |i|pagename|runs| 09:36:27 INFO - PROCESS | 17226 | |0;ai-astar;109;109;104;109;110;109;100;111;109;110 09:36:27 INFO - PROCESS | 17226 | |1;audio-beat-detection;150;123;151;165;125;149;153;130;179;124 09:36:27 INFO - PROCESS | 17226 | |2;audio-dft;159;161;160;160;162;160;158;164;162;160 09:36:27 INFO - PROCESS | 17226 | |3;audio-fft;73;75;76;157;75;75;74;74;164;77 09:36:27 INFO - PROCESS | 17226 | |4;audio-oscillator;93;93;145;92;94;93;143;92;93;146 09:36:27 INFO - PROCESS | 17226 | |5;imaging-gaussian-blur;116;117;116;117;116;117;116;117;116;116 09:36:27 INFO - PROCESS | 17226 | |6;imaging-darkroom;146;146;145;146;147;146;145;146;145;146 09:36:27 INFO - PROCESS | 17226 | |7;imaging-desaturate;96;95;96;96;95;96;95;95;95;95 09:36:27 INFO - PROCESS | 17226 | |8;json-parse-financial;55;55;53;55;55;53;58;63;53;53 09:36:27 INFO - PROCESS | 17226 | |9;json-stringify-tinderbox;64;65;64;64;64;65;64;63;64;65 09:36:27 INFO - PROCESS | 17226 | |10;stanford-crypto-aes;76;76;77;78;77;79;77;77;78;78 09:36:27 INFO - PROCESS | 17226 | |11;stanford-crypto-ccm;114;113;110;117;110;111;112;116;114;110 09:36:27 INFO - PROCESS | 17226 | |12;stanford-crypto-pbkdf2;145;133;149;145;139;137;150;148;145;158 09:36:27 INFO - PROCESS | 17226 | |13;stanford-crypto-sha256-iterative;66;70;66;65;65;65;65;67;65;67 09:36:27 INFO - PROCESS | 17226 | __end_tp_report 09:36:27 INFO - PROCESS | 17226 | __start_cc_report 09:36:27 INFO - PROCESS | 17226 | _x_x_mozilla_cycle_collect,1044 09:36:27 INFO - PROCESS | 17226 | __end_cc_report 09:36:27 INFO - PROCESS | 17226 | __startTimestamp1457372187192__endTimestamp 09:36:27 INFO - PROCESS | 17226 | 09:36:27 INFO - PROCESS | 17226 | ------- Summary: start ------- 09:36:27 INFO - PROCESS | 17226 | Number of tests: 14 09:36:27 INFO - PROCESS | 17226 | 09:36:27 INFO - PROCESS | 17226 | [#0] ai-astar Cycles:10 Average:108.00 Median:109.00 stddev:3.37 (3.1%) stddev-sans-first:3.55 09:36:27 INFO - PROCESS | 17226 | Values: 109.0 109.0 104.0 109.0 110.0 109.0 100.0 111.0 109.0 110.0 09:36:27 INFO - PROCESS | 17226 | 09:36:27 INFO - PROCESS | 17226 | [#1] audio-beat-detection Cycles:10 Average:144.90 Median:150.50 stddev:18.99 (12.6%) stddev-sans-first:20.06 09:36:27 INFO - PROCESS | 17226 | Values: 150.0 123.0 151.0 165.0 125.0 149.0 153.0 130.0 179.0 124.0 09:36:27 INFO - PROCESS | 17226 | 09:36:27 INFO - PROCESS | 17226 | [#2] audio-dft Cycles:10 Average:160.60 Median:160.50 stddev:1.71 (1.1%) stddev-sans-first:1.72 09:36:27 INFO - PROCESS | 17226 | Values: 159.0 161.0 160.0 160.0 162.0 160.0 158.0 164.0 162.0 160.0 09:36:27 INFO - PROCESS | 17226 | 09:36:27 INFO - PROCESS | 17226 | [#3] audio-fft Cycles:10 Average:92.00 Median:75.00 stddev:36.16 (48.2%) stddev-sans-first:37.69 09:36:27 INFO - PROCESS | 17226 | Values: 73.0 75.0 76.0 157.0 75.0 75.0 74.0 74.0 164.0 77.0 09:36:27 INFO - PROCESS | 17226 | 09:36:27 INFO - PROCESS | 17226 | [#4] audio-oscillator Cycles:10 Average:108.40 Median:93.00 stddev:25.04 (26.9%) stddev-sans-first:25.93 09:36:27 INFO - PROCESS | 17226 | Values: 93.0 93.0 145.0 92.0 94.0 93.0 143.0 92.0 93.0 146.0 09:36:27 INFO - PROCESS | 17226 | 09:36:27 INFO - PROCESS | 17226 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:36:27 INFO - PROCESS | 17226 | Values: 116.0 117.0 116.0 117.0 116.0 117.0 116.0 117.0 116.0 116.0 09:36:27 INFO - PROCESS | 17226 | 09:36:27 INFO - PROCESS | 17226 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 09:36:27 INFO - PROCESS | 17226 | Values: 146.0 146.0 145.0 146.0 147.0 146.0 145.0 146.0 145.0 146.0 09:36:27 INFO - PROCESS | 17226 | 09:36:27 INFO - PROCESS | 17226 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 09:36:27 INFO - PROCESS | 17226 | Values: 96.0 95.0 96.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 09:36:27 INFO - PROCESS | 17226 | 09:36:27 INFO - PROCESS | 17226 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.00 stddev:3.13 (5.7%) stddev-sans-first:3.32 09:36:27 INFO - PROCESS | 17226 | Values: 55.0 55.0 53.0 55.0 55.0 53.0 58.0 63.0 53.0 53.0 09:36:27 INFO - PROCESS | 17226 | 09:36:27 INFO - PROCESS | 17226 | [#9] json-stringify-tinderbox Cycles:10 Average:64.20 Median:64.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 09:36:27 INFO - PROCESS | 17226 | Values: 64.0 65.0 64.0 64.0 64.0 65.0 64.0 63.0 64.0 65.0 09:36:27 INFO - PROCESS | 17226 | 09:36:27 INFO - PROCESS | 17226 | [#10] stanford-crypto-aes Cycles:10 Average:77.30 Median:77.50 stddev:0.95 (1.2%) stddev-sans-first:0.88 09:36:27 INFO - PROCESS | 17226 | Values: 76.0 76.0 77.0 78.0 77.0 79.0 77.0 77.0 78.0 78.0 09:36:27 INFO - PROCESS | 17226 | 09:36:27 INFO - PROCESS | 17226 | [#11] stanford-crypto-ccm Cycles:10 Average:112.70 Median:113.50 stddev:2.54 (2.2%) stddev-sans-first:2.65 09:36:27 INFO - PROCESS | 17226 | Values: 114.0 113.0 110.0 117.0 110.0 111.0 112.0 116.0 114.0 110.0 09:36:27 INFO - PROCESS | 17226 | 09:36:27 INFO - PROCESS | 17226 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:144.90 Median:146.50 stddev:7.17 (4.9%) stddev-sans-first:7.61 09:36:27 INFO - PROCESS | 17226 | Values: 145.0 133.0 149.0 145.0 139.0 137.0 150.0 148.0 145.0 158.0 09:36:27 INFO - PROCESS | 17226 | 09:36:27 INFO - PROCESS | 17226 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:1.60 (2.4%) stddev-sans-first:1.69 09:36:27 INFO - PROCESS | 17226 | Values: 66.0 70.0 66.0 65.0 65.0 65.0 65.0 67.0 65.0 67.0 09:36:27 INFO - PROCESS | 17226 | -------- Summary: end -------- 09:36:27 INFO - PROCESS | 17226 | 09:36:28 INFO - TEST-INFO | 17226: exit 0 09:36:28 INFO - TEST-OK | kraken | took 59301ms 09:36:28 INFO - SUITE-END | took 1169s 09:36:28 INFO - Completed test suite (00:19:30) 09:36:28 INFO - javascript benchmark 09:36:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3202.647708752037, 3271.738260939125, 3294.1270563300714, 3295.983520082401, 3340.114869559567, 5292.603054770893, 5293.311869456975, 5305.151175811871, 5316.415194328631, 5369.3825210100895, 5330.573554115671, 5342.759575819087, 5343.118385466403, 5344.35867695877, 5355.196720491928, 4748.548887855665, 4873.269009648546, 4877.853664390074, 4916.852494425173, 4949.455559888409, 4847.248676455155, 4890.315355850184, 4938.407391113072, 4942.15983282841, 4944.87637809054, 4888.022395520892, 4936.950630493685, 5000.549950504454, 5012.573931215833, 5025.0954828130925, 4898.828541001048, 4908.950910490885, 4926.926096108561, 4928.137575924235, 4959.057779021992, 4683.555062269079, 4767.4994125617, 4783.689060211075, 4797.496262892416, 4797.688150270221, 5169.121249387613, 5199.324087868591, 5211.895762084775, 5214.687118772886, 5222.268882356473, 5586.078297081018, 5681.147827825818, 5686.687232202227, 5748.0, 5753.827385178452, 5764.538836893081, 5775.047117225653, 5783.421657834225, 5788.8842223155725, 5810.099434587646, 5415.404305526389, 5416.187571864212, 5418.133098704188, 5424.755885985141, 5424.891502169974, 5791.333996590446, 5857.26784151981, 5878.794242201588, 5950.107483877453, 5963.463288303987, 5651.152327150878, 5687.8862422752145, 5688.943110568925, 5691.231683722644, 5694.9430505695245, 5770.0, 5773.653580785082, 5774.2782152230975, 5777.45114214149, 5779.797707080316, 5348.438413966555, 5683.062294721325, 5731.111677689964, 5747.5689323300085, 5767.509761670305, 5709.3434255060365, 5728.198052272685, 5729.455701708331, 5733.0, 5735.770569177186, 4322.200392927319, 4375.5405682403525, 4385.890352741207, 4393.648508119343, 4413.227685155085, 3933.8033098344968, 3976.5824588418377, 4001.159756451178, 4003.01926028125, 4021.879343619696, 5390.245365648812, 5441.972790136024, 5452.672839629635, 5455.290812194468, 5456.072467680504, 5631.802886898939, 5736.426357364314, 5738.913916291259, 5770.480656740913, 5792.79725209624, 5383.192521121785, 5416.945830541723, 5432.837014889559, 5436.782528698808, 5464.3442786865835, 4917.655764096479, 4942.085714142823, 4943.530364615428, 4944.357233559686, 4968.354113965162, 5811.0411782056335, 5861.325947516005, 5901.616394934402, 5915.408459154136, 5945.732442040118, 5768.942310576925, 5796.449337312949, 5804.071348584206, 5808.680522571299, 5813.505852002627, 5945.524358051432, 5962.16529685853, 5966.224390829165, 5972.850678733066, 5977.133315669291, 5627.943720562906, 5706.172604972338, 5708.457696518824, 5713.143028545585, 5784.392638772868, 5773.393793651606, 5805.303363596396, 5808.273965754281, 5811.27359080115, 5812.447817457335, 5594.580406469449, 5605.719714014364, 5606.6916319602815, 5636.605437619492, 5661.66030038199, 5428.972855135699, 5538.6122971391615, 5606.775728970795, 5644.858878527905, 5658.943410565842, 5535.557155427476, 5545.140503222007, 5548.306461692288, 5575.721213939206, 5575.8884822304135, 5475.233467314659, 5571.8885622288135, 5643.181738647955, 5664.830055098354, 5667.3482549507335, 5462.071447853795, 5462.617616766788, 5485.588580856372, 5545.973994810973, 5600.215969764155], "unit": "score", "name": "dojo.html", "value": 5301.028559961937}, {"lowerIsBetter": false, "replicates": [546.5518275014489, 549.3819453115245, 571.6627189957924, 571.7512881896378, 608.0848323273473, 31220.687793122062, 38890.22219555611, 39278.85769138469, 39343.21313573731, 39480.0, 42970.355444668356, 43480.56519434805, 43485.34771978423, 43532.129357412836, 43573.564264357345, 8899.199072083511, 9170.862437063415, 9174.357794954356, 9178.908210917873, 9179.357444978854, 9017.143371379709, 9181.95409022954, 9184.448933064004, 9197.080291970817, 9197.540122993856, 9174.220191283748, 9247.306452016091, 9264.119908608705, 9272.629094836233, 9274.536273186313, 9100.817983640323, 9180.954095229548, 9191.816163676689, 9194.126557977015, 9223.861642075375, 9031.954840225757, 9160.0, 9166.450012999241, 9171.0, 9179.265658747285, 42879.35680964788, 43087.78456107731, 43304.3504347435, 43325.0, 43364.78317608423, 3661.9633803662155, 3705.5368078990127, 3834.884953451401, 4124.546299907008, 4433.9556604433865, 32926.0, 33373.66626333754, 33563.6643633563, 33611.15972100692, 33627.495587566205, 4124.896877578055, 4171.812268447927, 4237.957620423787, 4246.851360202378, 4250.213710463574, 263754.0, 264408.6779566109, 264846.0, 265434.0, 265770.0, 60854.39145608487, 61474.0778888317, 61577.0, 61624.69187654123, 61694.38305616976, 71080.28919710737, 71814.64092679607, 71824.64087679528, 71886.64056679788, 71903.4048297583, 13073.869261307455, 13115.737685246242, 13120.934395328153, 13156.40796164175, 13191.0, 10590.735231619272, 10606.098481629147, 10622.94688526568, 10623.893761062445, 10625.309354891908, 22004.449888752588, 22499.775002249768, 22627.547449050926, 22631.094756209895, 22669.319920402413, 8443.00273402933, 8698.260647845003, 8740.825183496294, 8771.89474526122, 8856.512891791013, 1031.3244824639874, 1061.3578784835217, 1106.9169812264113, 1161.3786624156037, 1178.4932479033926, 30431.0, 30498.085057448312, 30525.084247472612, 30544.69455305463, 30589.541156882664, 1123.500042481088, 1132.6092498088146, 1145.2841973766397, 1175.894169524747, 1191.4817054581113, 15674.451394201149, 15962.680746385007, 16166.110863902599, 16223.756643650355, 16471.505854824376, 348586.0, 352737.0, 353590.0, 353888.0, 354816.0, 56676.716616416656, 57142.42857571371, 57200.42799572034, 57268.42731572631, 57313.713431432574, 1271.8728127187208, 1357.280641260114, 1436.9281535923162, 1478.4160256698544, 1483.347327176039, 27441.0, 27594.0, 27992.160235192976, 28208.85895570509, 28365.858170709016, 41929.58070419379, 42309.57690423179, 42525.36211956885, 42758.14483710246, 42919.57080429156, 185253.0, 186367.0, 186641.0, 186926.0, 186989.0, 29912.25219369533, 49425.0, 49897.75051124866, 50244.49755502398, 51819.48180518297, 47309.290360645304, 47346.0, 48021.0, 48679.0, 48854.0, 38457.23085538329, 38748.80625596854, 39076.2184756309, 39099.80450097731, 40071.39892901667, 38249.426258605585, 40799.18401631891, 42009.57990420057, 42240.36639450472, 43532.564674354115, 42079.36880946727, 42930.57069429391, 42998.35502467403, 43583.78208109066, 44086.0, 37257.0, 37682.43476347912, 37766.324053820106, 38119.61880381161, 38139.42790858084, 28722.425551488435, 29181.124566263046, 29211.0, 29388.0, 29405.85297073501], "unit": "score", "name": "ext.html", "value": 19564.203899036496}, {"lowerIsBetter": false, "replicates": [378.9503076478154, 383.48421373252984, 388.5686887554814, 389.2642904909721, 390.5547675649758, 17401.564960875967, 18711.4386568403, 19069.332573359934, 19242.326518571834, 19271.229150833984, 18287.268509259648, 18295.817041829578, 18860.905695471505, 19079.14143863526, 19084.522886927833, 5611.887762244763, 5763.366029736726, 5763.971180144114, 5802.941970580282, 5804.158397032428, 4256.765877876715, 6055.7274922628585, 6295.213098362705, 6320.0, 6320.462760665326, 5981.192539007244, 6249.843753906143, 6256.155419018442, 6270.905936410957, 6277.748890044393, 6124.387561243884, 6146.969265153691, 6147.877042459126, 6153.815385538441, 6157.692115394235, 3841.635044670752, 5285.233641122016, 5510.889782204374, 5520.613557051008, 5525.254090697745, 20523.897380513, 20691.172353105856, 21059.157633694635, 22225.666615000788, 22248.88875555628, 2391.4380120671626, 2414.1912459326145, 2440.987795061031, 2573.1508602161243, 2635.7232490588594, 13558.254296013716, 13954.58136255914, 14125.71748565023, 14391.928040359731, 14516.709665806624, 1894.6494898443832, 1938.6122775444967, 1946.27987644571, 1993.6511110555596, 1997.131247907165, 67796.0, 68046.0, 68498.31501685019, 68910.65544672245, 69676.0, 10591.0, 10926.617568385227, 11295.887041129648, 11355.318680879174, 11472.139589530652, 14794.334254958552, 14824.110553366832, 14911.328990195467, 14999.775003374958, 15026.849731502545, 7670.654820532978, 7676.347510461562, 7692.9615351924, 7709.575973321521, 7843.843123137505, 7173.282671732889, 7293.088363954505, 7332.120145582564, 7439.479236453504, 7456.105267367951, 4384.079343337935, 4542.046170304273, 4726.976365118152, 4860.708357498562, 4923.89212427201, 1840.530664680498, 1901.8953957532192, 1974.8222659960672, 1985.2356842615698, 1985.3448362040492, 898.820235952799, 963.9036096390305, 1038.1798379280435, 1054.5992522841425, 1055.0346433013706, 16307.429239976544, 16744.665106697797, 16762.58093547671, 16927.0690112045, 17347.21937512815, 970.1850445625709, 998.9150922171697, 1018.7656838927088, 1048.7168464514539, 1086.6305456144948, 9984.750381240527, 10077.193824494023, 10121.746956326151, 10133.240006999356, 10336.224783141295, 412975.0, 418490.0, 418764.0, 419609.0, 423647.0, 74098.6295068532, 75926.62036689892, 76759.23240767521, 77084.0, 77230.0, 1011.1607365886297, 1026.1329176845554, 1038.6105210545895, 1040.3185913226848, 1041.0110395124511, 15633.843661563238, 15764.448244311394, 15783.68432631364, 15811.288492017657, 16062.839371606135, 32076.0, 32857.50713739248, 33293.50059749154, 33966.66033339635, 34854.30291394208, 40381.596184037786, 42026.0, 44069.33895991499, 44754.55245447634, 45820.0, 8173.18268173178, 8276.83446331082, 8290.544020078954, 8492.447990880573, 8500.277476414327, 9560.043995600385, 11479.36863472517, 12395.876041239833, 12584.30786306762, 13010.674733131369, 10623.150147988297, 10913.508892100033, 10945.781084378426, 11062.668119956414, 11165.944170279097, 11211.271267367594, 11347.489362978851, 11524.481398336928, 11757.588484403004, 11760.411979400824, 10776.191785616267, 10780.622678206226, 10917.508712108132, 10967.670969870916, 10989.450527473755, 8244.793880153044, 8263.338932885476, 8297.211764882326, 8469.957650211709, 8502.979642442699, 10358.896411035794, 10631.94684026575, 10853.0, 10908.509117089909, 11177.944110279397], "unit": "score", "name": "jquery.html", "value": 8852.084374869795}, {"lowerIsBetter": false, "replicates": [1436.5762100180445, 1542.652903096803, 1550.341105030362, 1786.571222906502, 1923.451816232374, 7905.13043565208, 8099.757007289776, 8117.512949223051, 8124.878126828103, 8136.755897323075, 7762.611869406535, 7907.288344049049, 7924.564148971805, 7929.286364227219, 7934.206579342077, 2022.3427386099577, 2052.445839623301, 2314.143766806284, 2334.8015418689433, 2368.857868527894, 2384.1178763857397, 2405.0139442828445, 2430.5503481855826, 2431.051889762994, 2434.172381390327, 2343.379004563792, 2390.5696974544576, 2396.412878844677, 2400.12395475651, 2408.89159987801, 2434.379233295516, 2446.1683027770555, 2468.8518688878635, 2469.394998225446, 2473.2209354053416, 2387.2599494156866, 2409.3133456964856, 2424.260600516842, 2430.6961629796274, 2436.878156092206, 8132.105468398471, 8188.836223275501, 8198.672053117869, 8202.056763472217, 8205.425620206588, 4868.513148685139, 4974.4776798436005, 5005.6496045276845, 5014.2979982802435, 5030.119729047402, 3210.181403742054, 3311.867525298985, 3355.429576971896, 3377.4764911438765, 3416.282580658065, 1024.354656566366, 1031.0411317474745, 1043.57213542448, 1043.9686809395655, 1078.8489611454402, 11631.418429078512, 11780.941095294555, 11788.410579471078, 11790.823137652942, 11794.469248883735, 4551.385562949025, 4651.790669419885, 4661.930071048937, 4692.085043416501, 4710.717356958594, 9441.103095205945, 9460.716178514656, 9531.0, 9535.952320238355, 9545.188658964065, 5091.439941606421, 5091.694498330112, 5136.101182293084, 5137.741253392943, 5140.691558506502, 4074.124063326399, 4431.0, 4550.954490455053, 4556.066006468666, 4556.088782243564, 3775.716821238418, 3868.5551161616213, 3881.514810648669, 3907.3552863777727, 3974.741641793332, 1110.9777804444027, 1138.2243699828293, 1138.5957984915235, 1149.6665966869682, 1153.798085335063, 1403.333416627094, 1409.6194027612487, 1425.4868247430916, 1441.1929319581068, 1455.3596417576232, 7812.1797211292, 7900.209979002168, 7903.486273392326, 8069.596520173968, 8076.8384632307025, 445.5588966922723, 449.79309517621607, 451.35456297494244, 452.1431886574922, 470.1255664464094, 6142.477889379364, 6152.354002829638, 6204.906926396107, 6208.81373558794, 6256.343083976117, 9596.76008099803, 10013.799724005625, 10041.899581004243, 10067.395956242648, 10205.438700871524, 9949.154321882575, 10012.749681258027, 10053.396796192252, 10061.245406594535, 10075.748106297402, 7708.03649543807, 7873.25204105609, 7932.0878099019355, 7954.125046244792, 7961.36309095271, 7527.247275272648, 7569.545827250383, 7603.353714934071, 7608.543487390774, 7674.501157424749, 8653.653853845775, 8953.104689530996, 9009.0, 9023.909760902307, 9064.0, 8963.731088067369, 9150.908490915006, 9314.20829229496, 9319.627214911328, 9472.28957828152, 6340.239171299474, 6467.417932386108, 6734.057231987427, 7125.8574828502105, 7258.745943891939, 7954.323882470055, 7957.602119893866, 7988.600569971594, 7993.160718124514, 8006.599670016592, 7161.069061022034, 7348.265173482609, 7369.8157546061775, 7381.8154546136775, 7445.664945077593, 7196.604186769778, 7208.207097219197, 7211.531250468703, 7255.876586727908, 7261.491695581259, 7511.51175173612, 7586.886196707164, 7589.962050189934, 7591.013168288087, 7595.240475952361, 6797.422219111431, 6887.896681549681, 6896.655167241517, 6900.861982760417, 6907.758228461979, 6860.0738900247825, 7099.219085900443, 7104.538205016657, 7112.50212485121, 7128.821779455555], "unit": "score", "name": "mootools.html", "value": 4640.561575067456}, {"lowerIsBetter": false, "replicates": [354.7162270183852, 356.1470278682555, 363.36048554543993, 363.5346756152126, 366.3680151738251, 5979.282486101669, 5984.910226346608, 5994.280686317637, 5997.850053748659, 6014.699265036753, 5863.736131874065, 5874.794382196624, 5882.058870580698, 5896.204012458317, 5900.085486749561, 4425.734455932655, 4538.8865278367975, 4647.23320652092, 4653.255479123341, 4676.7427791471455, 4642.723251105939, 4659.4175728034, 4663.207254766698, 4667.789949452266, 4676.415448068991, 4619.653525985547, 4629.166749985001, 4630.606398456135, 4633.0, 4648.65135114866, 4440.134173836103, 4508.075844451896, 4576.519465456146, 4579.473360563545, 4595.333676616872, 4092.774897380643, 4117.402976568381, 4118.938215926763, 4127.690423218271, 4128.318827393507, 5623.0, 5631.915521267184, 5633.436656334375, 5634.971825140848, 5638.689872057035, 5394.190871369326, 5400.054990376708, 5402.054640437907, 5405.351357837046, 5410.97294513525, 6264.3422440643535, 6271.905921411182, 6276.089966954766, 6276.780312689079, 6277.246730392337, 4487.439070116236, 4572.634189264851, 4581.198290299184, 4596.195665758479, 4597.540245975376, 6479.64361960092, 6509.121268628722, 6517.4786017118995, 6517.934820651828, 6537.378948999837, 4666.789994450258, 4698.2952557116705, 4703.459102203222, 4709.293605959065, 4713.505081966413, 4753.809847606126, 4761.761911904391, 4762.52374762521, 4777.307290442866, 4777.641676874179, 5383.865403364947, 5385.623006389594, 5387.138057910715, 5397.433269506645, 5402.405735369185, 6085.847853803691, 6288.056791481315, 6301.464375528131, 6302.180716506825, 6307.864584374856, 1617.6360318928148, 1630.6331075507917, 1637.7297745871797, 1639.8442147995922, 1641.4911377473022, 1038.3199004651965, 1059.232056758859, 1069.2301542889106, 1124.4153040418937, 1124.7356871135278, 6057.969710151421, 6077.696115194222, 6106.603070800384, 6114.602550834182, 6114.633122012693, 6488.0916671666, 6488.967555162194, 6490.935090649033, 6506.674666266667, 6506.837329066811, 5981.551383646244, 6112.2970858351855, 6120.724567394478, 6123.724432400552, 6125.846853828601, 3478.826058697055, 3513.0, 3515.8593656253975, 3517.2262102337445, 3519.630438803889, 6550.70521826519, 6603.438707709898, 6679.0, 6681.365270299314, 6824.4199243064895, 6745.966270168716, 6758.695858686371, 6770.390664840188, 6770.763023294259, 6771.593704377753, 6793.320667933266, 6794.150731158606, 6797.116374871235, 6797.966010169917, 6803.047573339637, 6614.966925165343, 6617.205935287796, 6620.2386725527185, 6623.370779775913, 6630.436412904957, 6299.338569450142, 6467.450266727381, 6493.123428337114, 6534.803955881332, 6575.671216439063, 6748.358905903931, 6791.592504449749, 6795.7961261162245, 6796.2524122347495, 6796.422304104207, 4518.9322160168285, 4538.2511885538515, 4557.931631025604, 4567.794449249725, 4568.0, 6597.703103360264, 6607.273199948098, 6609.074729537965, 6609.239937407259, 6616.569922954993, 6207.099970504161, 6390.297067322684, 6402.87194256103, 6413.807585772434, 6414.390632889868, 6335.683215839282, 6353.697492014218, 6413.262474815456, 6430.324815894263, 6431.0, 6304.306526282197, 6321.241451025722, 6325.96837015812, 6327.493800496041, 6336.0, 6367.61794292344, 6403.711832967434, 6422.710978005907, 6428.453581445629, 6429.7428102875365, 5941.673207973603, 6019.879602408015, 6145.754169833157, 6265.8120256392385, 6280.214973128359], "unit": "score", "name": "prototype.html", "value": 4827.624131529956}, {"lowerIsBetter": false, "replicates": [748.5059860492076, 835.5321020228669, 902.318749344245, 903.7830920579061, 909.1226965977833, 14490.130592164463, 14719.264036798171, 14769.187694676802, 14778.408863645454, 14782.556523304318, 13850.445982160727, 13921.58235252944, 15183.240837958114, 15485.845141548582, 15496.14771187587, 755.022246191181, 896.7937374403891, 914.1772404835634, 928.1785619726556, 931.65063101337, 912.5893347993397, 925.4539821505344, 926.272875792503, 926.6617684545126, 927.4388994658236, 945.8723072385243, 953.7806304549957, 953.9761505962372, 954.1126752120525, 955.1451450951395, 779.5400713579015, 793.6428607126787, 937.0863408177041, 943.2312665177858, 957.9760505987337, 693.5214701855704, 700.0199720391441, 702.4310308649959, 702.5433468245631, 702.662721893494, 15180.240987950669, 15214.16322102284, 15237.314320855588, 15270.31283592241, 15275.923620381938, 13666.658333541742, 13701.725965480635, 13948.16311021342, 13979.930100349535, 14120.293985300797, 11299.717507062389, 11307.830382544269, 11318.547258109666, 11347.716307092307, 11582.131340149523, 3927.175293188434, 3943.960560394388, 3954.960450395488, 3961.4652021976954, 3962.7820469874146, 53224.0, 53398.0, 53488.465115348736, 53626.73186634081, 53732.731336343066, 10979.670609881716, 11042.447877606248, 11085.27945683528, 11146.721331966766, 11254.94372528132, 19415.61168776645, 19472.513187170433, 19522.0, 19531.316403925794, 19546.804531954782, 8154.306883914934, 8177.345812334999, 8228.30059444942, 8344.582770861432, 8545.102764209792, 4501.437320334958, 4648.953510464919, 4665.4634717007975, 4728.668993170514, 4732.929006064912, 3451.5340429041757, 3457.2048428861503, 3458.8270586470576, 3482.6169121396624, 3489.5114683944266, 592.2510508481303, 605.6124080588427, 612.7457105301339, 625.0904933322006, 649.8732747114267, 631.1857703562354, 668.5053060735021, 681.2574294019543, 697.4594689115976, 718.2135561560083, 8865.335099867536, 8980.236679882153, 9109.407888487227, 9124.908750912406, 9229.861552076723, 564.9548036157098, 609.8810731907236, 682.7303215230056, 705.1326867952345, 719.3813320544327, 3915.6671682906704, 3928.0, 4110.17796440713, 4178.0, 4251.383549385322, 58029.0, 60292.0, 60370.0, 60632.69683651553, 61100.694496527234, 54580.45419545833, 54787.41087332935, 55129.448705513234, 56132.438675613535, 56628.716856415456, 47890.9999999993, 47932.760336198095, 47997.76001120042, 48474.75762621235, 49187.0, 7825.1783562725095, 8411.663533458594, 8428.283595894172, 9007.369484136048, 9336.439813611205, 34337.656623433446, 34908.47637285392, 36175.81912090423, 36918.44622330721, 37946.43080353852, 47344.289835651805, 48942.0, 49593.00813983772, 49785.75107124441, 50198.49801501938, 12105.515779368727, 12360.196587221802, 12468.750624987631, 12518.311492867979, 12572.937135314265, 12422.565210217599, 12511.436985335864, 12511.749765004832, 12685.175463595206, 13233.933830330787, 12060.879391206327, 12091.81862272049, 12515.812262816247, 12558.183718058297, 12759.170653907466, 11238.213325067494, 11479.25384849982, 11923.582674606347, 12192.451339689558, 12238.571649992207, 11963.222390544586, 12122.75754484923, 12422.751544969231, 12480.56318028901, 12542.24746515212, 9920.553575088994, 9973.750656233653, 10094.19246460296, 10212.489375531046, 10389.428581428094, 10180.898191018292, 10418.441518326626, 10637.14902807758, 10697.290086215566, 10706.357618542912], "unit": "score", "name": "yui.html", "value": 6814.186484155105}], "name": "dromaeo_css", "value": 7207.270086216914}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 109.0, 104.0, 109.0, 110.0, 109.0, 100.0, 111.0, 109.0, 110.0], "unit": "score", "name": "ai-astar", "value": 108.0}, {"lowerIsBetter": true, "replicates": [150.0, 123.0, 151.0, 165.0, 125.0, 149.0, 153.0, 130.0, 179.0, 124.0], "unit": "score", "name": "audio-beat-detection", "value": 144.9}, {"lowerIsBetter": true, "replicates": [159.0, 161.0, 160.0, 160.0, 162.0, 160.0, 158.0, 164.0, 162.0, 160.0], "unit": "score", "name": "audio-dft", "value": 160.6}, {"lowerIsBetter": true, "replicates": [73.0, 75.0, 76.0, 157.0, 75.0, 75.0, 74.0, 74.0, 164.0, 77.0], "unit": "score", "name": "audio-fft", "value": 92.0}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 145.0, 92.0, 94.0, 93.0, 143.0, 92.0, 93.0, 146.0], "unit": "score", "name": "audio-oscillator", "value": 108.4}, {"lowerIsBetter": true, "replicates": [116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 116.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 116.4}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 145.0, 146.0, 147.0, 146.0, 145.0, 146.0, 145.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [55.0, 55.0, 53.0, 55.0, 55.0, 53.0, 58.0, 63.0, 53.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 55.3}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 64.0, 64.0, 64.0, 65.0, 64.0, 63.0, 64.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 64.2}, {"lowerIsBetter": true, "replicates": [76.0, 76.0, 77.0, 78.0, 77.0, 79.0, 77.0, 77.0, 78.0, 78.0], "unit": "score", "name": "stanford-crypto-aes", "value": 77.3}, {"lowerIsBetter": true, "replicates": [114.0, 113.0, 110.0, 117.0, 110.0, 111.0, 112.0, 116.0, 114.0, 110.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 112.7}, {"lowerIsBetter": true, "replicates": [145.0, 133.0, 149.0, 145.0, 139.0, 137.0, 150.0, 148.0, 145.0, 158.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 144.9}, {"lowerIsBetter": true, "replicates": [66.0, 70.0, 66.0, 65.0, 65.0, 65.0, 65.0, 67.0, 65.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.1}], "name": "kraken", "value": 1492.0}]} 09:36:28 INFO - Return code: 0 09:36:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 09:36:28 INFO - # TBPL SUCCESS # 09:36:28 INFO - Running post-action listener: _resource_record_post_action 09:36:28 INFO - Running post-run listener: _resource_record_post_run 09:36:29 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 282624; Write bytes: 499453952; Read time: 36; Write time: 524728 09:36:29 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 251080704; Read time: 0; Write time: 379188 09:36:29 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 282624; Write bytes: 248094720; Read time: 36; Write time: 145496 09:36:29 INFO - Running post-run listener: _upload_blobber_files 09:36:29 INFO - Blob upload gear active. 09:36:29 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 09:36:29 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:36:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 09:36:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Mozilla-Aurora -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 09:36:30 INFO - (blobuploader) - INFO - Open directory for files ... 09:36:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:36:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:36:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:30 INFO - (blobuploader) - INFO - Done attempting. 09:36:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 09:36:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:36:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:31 INFO - (blobuploader) - INFO - Done attempting. 09:36:31 INFO - (blobuploader) - INFO - Iteration through files over. 09:36:31 INFO - Return code: 0 09:36:31 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 09:36:31 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 09:36:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c3a2da20633b214c29bb94964cd631df0a19a8d6e632acc5738c9efaa56f01f6b3a3eb81fb1b987a6f5862a73d0647347e0627975a4bb82b48ed4a82a4e599c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81b913a9fd53f466273838beafef482e85d3813742fe4262ddbf5a53abff34593a04eed1f9f7858ab993fc8489d10694048e7f0cf23508d3c0995d3cb6bdba80"} 09:36:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 09:36:31 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 09:36:31 INFO - Contents: 09:36:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c3a2da20633b214c29bb94964cd631df0a19a8d6e632acc5738c9efaa56f01f6b3a3eb81fb1b987a6f5862a73d0647347e0627975a4bb82b48ed4a82a4e599c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81b913a9fd53f466273838beafef482e85d3813742fe4262ddbf5a53abff34593a04eed1f9f7858ab993fc8489d10694048e7f0cf23508d3c0995d3cb6bdba80"} 09:36:31 INFO - Running post-run listener: copy_logs_to_upload_dir 09:36:31 INFO - Copying logs to upload dir... 09:36:31 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 09:36:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.787190 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2016-03-07 09:36:31.569811) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-07 09:36:31.573627) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c3a2da20633b214c29bb94964cd631df0a19a8d6e632acc5738c9efaa56f01f6b3a3eb81fb1b987a6f5862a73d0647347e0627975a4bb82b48ed4a82a4e599c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81b913a9fd53f466273838beafef482e85d3813742fe4262ddbf5a53abff34593a04eed1f9f7858ab993fc8489d10694048e7f0cf23508d3c0995d3cb6bdba80"} build_url:https://queue.taskcluster.net/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011323 build_url: 'https://queue.taskcluster.net/v1/task/BLdY6PDVTKWW-ek05wlhrA/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c3a2da20633b214c29bb94964cd631df0a19a8d6e632acc5738c9efaa56f01f6b3a3eb81fb1b987a6f5862a73d0647347e0627975a4bb82b48ed4a82a4e599c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81b913a9fd53f466273838beafef482e85d3813742fe4262ddbf5a53abff34593a04eed1f9f7858ab993fc8489d10694048e7f0cf23508d3c0995d3cb6bdba80"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-07 09:36:31.598367) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-07 09:36:31.598695) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1457370865.918156-856598338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004514 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-07 09:36:31.682232) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-07 09:36:31.682545) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-07 09:36:31.682822) ========= ========= Total master_lag: 0.23 =========