builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-006 starttime: 1451475864.78 results: success (0) buildid: 20151230013009 builduid: f8fa711fb644426bb22b3d81b9481ae6 revision: cb7ddeee6364905a0184e7ceccfab9d1dfea25f9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:24.780484) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:24.780914) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:24.781254) ========= 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=801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006358 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.06 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:24.852492) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:24.852814) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:24.894851) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:24.895189) ========= 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=801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004571 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:24.947705) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:24.948049) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:24.948488) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:24.951730) ========= 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=801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-30 03:44:25-- 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% 43.7M=0s 2015-12-30 03:44:25 (43.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.096156 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:25.063124) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:25.063439) ========= 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=801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020615 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:25.116386) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-30 03:44:25.116750) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev cb7ddeee6364905a0184e7ceccfab9d1dfea25f9 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev cb7ddeee6364905a0184e7ceccfab9d1dfea25f9 --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=801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-30 03:44:25,565 truncating revision to first 12 chars 2015-12-30 03:44:25,565 Setting DEBUG logging. 2015-12-30 03:44:25,565 attempt 1/10 2015-12-30 03:44:25,565 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/cb7ddeee6364?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-30 03:44:26,573 unpacking tar archive at: mozilla-inbound-cb7ddeee6364/testing/mozharness/ program finished with exit code 0 elapsedTime=1.296590 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-30 03:44:26.426642) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:26.426958) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:26.613746) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:26.614128) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-30 03:44:26.614615) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2015-12-30 03:44:26.614897) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:44:27 INFO - MultiFileLogger online at 20151230 03:44:27 in /builds/slave/test-pgo 03:44:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 03:44:27 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 03:44:27 INFO - {'append_to_log': False, 03:44:27 INFO - 'base_work_dir': '/builds/slave/test-pgo', 03:44:27 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 03:44:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 03:44:27 INFO - 'branch': 'Mozilla-Inbound', 03:44:27 INFO - 'buildbot_json_path': 'buildprops.json', 03:44:27 INFO - 'config_files': ('talos/linux_config.py',), 03:44:27 INFO - 'default_actions': ('clobber', 03:44:27 INFO - 'read-buildbot-config', 03:44:27 INFO - 'download-and-extract', 03:44:27 INFO - 'populate-webroot', 03:44:27 INFO - 'create-virtualenv', 03:44:27 INFO - 'install', 03:44:27 INFO - 'run-tests'), 03:44:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:44:27 INFO - 'download_minidump_stackwalk': True, 03:44:27 INFO - 'download_symbols': 'ondemand', 03:44:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:44:27 INFO - 'tooltool.py': '/tools/tooltool.py', 03:44:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:44:27 INFO - '/tools/misc-python/virtualenv.py')}, 03:44:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:44:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:44:27 INFO - 'installer_path': 'installer.exe', 03:44:27 INFO - 'log_level': 'info', 03:44:27 INFO - 'log_name': 'talos', 03:44:27 INFO - 'log_to_console': True, 03:44:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:44:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:44:27 INFO - 'opt_config_files': (), 03:44:27 INFO - 'pip_index': False, 03:44:27 INFO - 'sps_profile': False, 03:44:27 INFO - 'sps_profile_interval': 0, 03:44:27 INFO - 'suite': 'dromaeojs-e10s', 03:44:27 INFO - 'system_bits': '32', 03:44:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:44:27 INFO - 'title': 'talos-linux64-ix-006', 03:44:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:44:27 INFO - 'use_talos_json': True, 03:44:27 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 03:44:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:44:27 INFO - 'work_dir': 'build'} 03:44:27 INFO - ##### 03:44:27 INFO - ##### Running clobber step. 03:44:27 INFO - ##### 03:44:27 INFO - Running pre-action listener: _resource_record_pre_action 03:44:27 INFO - Running main action method: clobber 03:44:27 INFO - rmtree: /builds/slave/test-pgo/build 03:44:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 03:44:27 INFO - Running post-action listener: _resource_record_post_action 03:44:27 INFO - ##### 03:44:27 INFO - ##### Running read-buildbot-config step. 03:44:27 INFO - ##### 03:44:27 INFO - Running pre-action listener: _resource_record_pre_action 03:44:27 INFO - Running main action method: read_buildbot_config 03:44:27 INFO - Using buildbot properties: 03:44:27 INFO - { 03:44:27 INFO - "project": "", 03:44:27 INFO - "product": "firefox", 03:44:27 INFO - "script_repo_revision": "production", 03:44:27 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 03:44:27 INFO - "repository": "", 03:44:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 03:44:27 INFO - "buildid": "20151230013009", 03:44:27 INFO - "pgo_build": "True", 03:44:27 INFO - "basedir": "/builds/slave/test-pgo", 03:44:27 INFO - "buildnumber": 568, 03:44:27 INFO - "slavename": "talos-linux64-ix-006", 03:44:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:44:27 INFO - "platform": "ubuntu64_hw", 03:44:27 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 03:44:27 INFO - "branch": "mozilla-inbound", 03:44:27 INFO - "repo_path": "integration/mozilla-inbound", 03:44:27 INFO - "revision": "cb7ddeee6364905a0184e7ceccfab9d1dfea25f9", 03:44:27 INFO - "stage_platform": "linux64-pgo", 03:44:27 INFO - "builduid": "f8fa711fb644426bb22b3d81b9481ae6", 03:44:27 INFO - "slavebuilddir": "test" 03:44:27 INFO - } 03:44:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 03:44:27 INFO - Running post-action listener: _resource_record_post_action 03:44:27 INFO - ##### 03:44:27 INFO - ##### Running download-and-extract step. 03:44:27 INFO - ##### 03:44:27 INFO - Running pre-action listener: _resource_record_pre_action 03:44:27 INFO - Running main action method: download_and_extract 03:44:27 INFO - mkdir: /builds/slave/test-pgo/build/tests 03:44:27 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')]} 03:44:27 INFO - https://queue.taskcluster.net/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 03:44:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/test_packages.json 03:44:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/test_packages.json 03:44:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 03:44:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 03:44:29 INFO - Downloaded 1302 bytes. 03:44:29 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 03:44:29 INFO - Using the following test package requirements: 03:44:29 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 03:44:29 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 03:44:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 03:44:29 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 03:44:29 INFO - u'jsshell-linux-x86_64.zip'], 03:44:29 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 03:44:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 03:44:29 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 03:44:29 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 03:44:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 03:44:29 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 03:44:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 03:44:29 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 03:44:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 03:44:29 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 03:44:29 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 03:44:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 03:44:29 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:44:29 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')]} 03:44:29 INFO - https://queue.taskcluster.net/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:44:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 03:44:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 03:44:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 03:44:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:44:33 INFO - Downloaded 23199715 bytes. 03:44:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 03:44:33 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 03:44:33 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 03:44:34 INFO - Return code: 0 03:44:34 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:44:34 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')]} 03:44:34 INFO - https://queue.taskcluster.net/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:44:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 03:44:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 03:44:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 03:44:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:44:45 INFO - Downloaded 23199715 bytes. 03:44:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 03:44:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 03:44:45 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 03:44:46 INFO - Return code: 0 03:44:46 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')]} 03:44:46 INFO - https://queue.taskcluster.net/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:44:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 03:44:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 03:44:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 03:44:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:44:48 INFO - Downloaded 11132270 bytes. 03:44:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 03:44:48 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 03:44:48 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 03:44:48 INFO - Return code: 0 03:44:48 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')]} 03:44:48 INFO - https://queue.taskcluster.net/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:44:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 03:44:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 03:44:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 03:44:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:44:51 INFO - Downloaded 54950348 bytes. 03:44:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 03:44:51 INFO - mkdir: /builds/slave/test-pgo/properties 03:44:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 03:44:51 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 03:44:51 INFO - Contents: 03:44:51 INFO - build_url:https://queue.taskcluster.net/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 03:44:51 INFO - Running post-action listener: _resource_record_post_action 03:44:51 INFO - Running post-action listener: set_extra_try_arguments 03:44:51 INFO - ##### 03:44:51 INFO - ##### Running populate-webroot step. 03:44:51 INFO - ##### 03:44:51 INFO - Running pre-action listener: _resource_record_pre_action 03:44:51 INFO - Running main action method: populate_webroot 03:44:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:44:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:44:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:44:51 INFO - '--tppagecycles', 03:44:51 INFO - '7'], 03:44:51 INFO - 'tests': ['tsvgm']}}, 03:44:51 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:44:51 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:44:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:44:51 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:44:51 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:44:51 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:44:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:44:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:44:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:44:51 INFO - 'talos_options': ['--e10s'], 03:44:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:44:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:44:51 INFO - 'tests': ['damp', 'tps']}, 03:44:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:44:51 INFO - 'talos_options': ['--e10s'], 03:44:51 INFO - 'tests': ['damp', 'tps']}, 03:44:51 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:44:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:44:51 INFO - 'other': {'tests': ['a11yr', 03:44:51 INFO - 'ts_paint', 03:44:51 INFO - 'tpaint', 03:44:51 INFO - 'sessionrestore', 03:44:51 INFO - 'sessionrestore_no_auto_restore']}, 03:44:51 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:44:51 INFO - 'tests': ['a11yr', 03:44:51 INFO - 'ts_paint', 03:44:51 INFO - 'tpaint', 03:44:51 INFO - 'sessionrestore', 03:44:51 INFO - 'sessionrestore_no_auto_restore']}, 03:44:51 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:44:51 INFO - 'tests': ['a11yr', 03:44:51 INFO - 'ts_paint', 03:44:51 INFO - 'tpaint', 03:44:51 INFO - 'sessionrestore', 03:44:51 INFO - 'sessionrestore_no_auto_restore']}, 03:44:51 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:44:51 INFO - 'tests': ['a11yr', 03:44:51 INFO - 'ts_paint', 03:44:51 INFO - 'tpaint', 03:44:51 INFO - 'sessionrestore', 03:44:51 INFO - 'sessionrestore_no_auto_restore']}, 03:44:51 INFO - 'other_l64': {'tests': ['a11yr', 03:44:51 INFO - 'ts_paint', 03:44:51 INFO - 'tpaint', 03:44:51 INFO - 'sessionrestore', 03:44:51 INFO - 'sessionrestore_no_auto_restore']}, 03:44:51 INFO - 'other_nol64': {'tests': ['a11yr', 03:44:51 INFO - 'ts_paint', 03:44:51 INFO - 'tpaint', 03:44:51 INFO - 'sessionrestore', 03:44:51 INFO - 'sessionrestore_no_auto_restore']}, 03:44:51 INFO - 'svgr': {'tests': ['tsvgx', 03:44:51 INFO - 'tsvgr_opacity', 03:44:51 INFO - 'tart', 03:44:51 INFO - 'tscrollx', 03:44:51 INFO - 'cart']}, 03:44:51 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:44:51 INFO - 'tests': ['tsvgx', 03:44:51 INFO - 'tsvgr_opacity', 03:44:51 INFO - 'tart', 03:44:51 INFO - 'tscrollx', 03:44:51 INFO - 'cart']}, 03:44:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:44:51 INFO - 'tests': ['tp5o']}, 03:44:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:44:51 INFO - 'talos_options': ['--e10s'], 03:44:51 INFO - 'tests': ['tp5o']}, 03:44:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:44:51 INFO - 'talos_options': ['--xperf_path', 03:44:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:44:51 INFO - 'tests': ['tp5n']}, 03:44:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:44:51 INFO - 'talos_options': ['--e10s', 03:44:51 INFO - '--xperf_path', 03:44:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:44:51 INFO - 'tests': ['tp5n']}}, 03:44:51 INFO - 'talos.zip': {'path': '', 03:44:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:44:51 INFO - Running post-action listener: _resource_record_post_action 03:44:51 INFO - ##### 03:44:51 INFO - ##### Running create-virtualenv step. 03:44:51 INFO - ##### 03:44:51 INFO - Running pre-action listener: _resource_record_pre_action 03:44:51 INFO - Running main action method: create_virtualenv 03:44:51 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 03:44:51 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 03:44:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 03:44:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:44:51 INFO - Using real prefix '/usr' 03:44:51 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 03:44:51 INFO - Installing distribute.............................................................................................................................................................................................done. 03:44:53 INFO - Installing pip.................done. 03:44:53 INFO - Return code: 0 03:44:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 03:44:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7ff763d21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23297f0>, '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 0x22952d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295760>, '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': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', '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 03:44:53 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 03:44:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 03:44:53 INFO - Using env: {'DISPLAY': ':0', 03:44:53 INFO - 'HOME': '/home/cltbld', 03:44:53 INFO - 'LANG': 'en_US.UTF-8', 03:44:53 INFO - 'LANGUAGE': 'en_US:en', 03:44:53 INFO - 'LOGNAME': 'cltbld', 03:44:53 INFO - 'MAIL': '/var/mail/cltbld', 03:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:53 INFO - 'MOZ_NO_REMOTE': '1', 03:44:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:53 INFO - 'NO_EM_RESTART': '1', 03:44:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:44:53 INFO - 'PWD': '/builds/slave/test-pgo', 03:44:53 INFO - 'SHELL': '/bin/bash', 03:44:53 INFO - 'SHLVL': '1', 03:44:53 INFO - 'TERM': 'linux', 03:44:53 INFO - 'TMOUT': '86400', 03:44:53 INFO - 'USER': 'cltbld', 03:44:53 INFO - 'XDG_SESSION_COOKIE': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', 03:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:53 INFO - '_': '/tools/buildbot/bin/python'} 03:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:54 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 03:44:54 INFO - Cleaning up... 03:44:54 INFO - Return code: 0 03:44:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 03:44:54 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')]} 03:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:54 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')]} 03:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7ff763d21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23297f0>, '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 0x22952d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295760>, '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': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', '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 03:44:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 03:44:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 03:44:54 INFO - Using env: {'DISPLAY': ':0', 03:44:54 INFO - 'HOME': '/home/cltbld', 03:44:54 INFO - 'LANG': 'en_US.UTF-8', 03:44:54 INFO - 'LANGUAGE': 'en_US:en', 03:44:54 INFO - 'LOGNAME': 'cltbld', 03:44:54 INFO - 'MAIL': '/var/mail/cltbld', 03:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:54 INFO - 'MOZ_NO_REMOTE': '1', 03:44:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:54 INFO - 'NO_EM_RESTART': '1', 03:44:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:44:54 INFO - 'PWD': '/builds/slave/test-pgo', 03:44:54 INFO - 'SHELL': '/bin/bash', 03:44:54 INFO - 'SHLVL': '1', 03:44:54 INFO - 'TERM': 'linux', 03:44:54 INFO - 'TMOUT': '86400', 03:44:54 INFO - 'USER': 'cltbld', 03:44:54 INFO - 'XDG_SESSION_COOKIE': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', 03:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:54 INFO - '_': '/tools/buildbot/bin/python'} 03:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:54 INFO - Downloading/unpacking psutil>=0.7.1 03:44:54 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 03:44:54 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 03:44:54 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 03:44:54 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 03:44:54 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 03:44:54 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 03:44:55 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 03:44:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 03:44:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 03:44:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:44:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:44:56 INFO - Installing collected packages: psutil 03:44:56 INFO - Running setup.py install for psutil 03:44:56 INFO - building 'psutil._psutil_linux' extension 03:44:56 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 03:44:56 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 03:44:56 INFO - building 'psutil._psutil_posix' extension 03:44:56 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 03:44:56 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 03:44:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:44:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:44:56 INFO - Successfully installed psutil 03:44:56 INFO - Cleaning up... 03:44:56 INFO - Return code: 0 03:44:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 03:44: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')]} 03:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44: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')]} 03:44:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:56 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7ff763d21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23297f0>, '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 0x22952d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295760>, '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': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', '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 03:44:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 03:44:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 03:44:56 INFO - Using env: {'DISPLAY': ':0', 03:44:56 INFO - 'HOME': '/home/cltbld', 03:44:56 INFO - 'LANG': 'en_US.UTF-8', 03:44:56 INFO - 'LANGUAGE': 'en_US:en', 03:44:56 INFO - 'LOGNAME': 'cltbld', 03:44:56 INFO - 'MAIL': '/var/mail/cltbld', 03:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:56 INFO - 'MOZ_NO_REMOTE': '1', 03:44:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:56 INFO - 'NO_EM_RESTART': '1', 03:44:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:44:56 INFO - 'PWD': '/builds/slave/test-pgo', 03:44:56 INFO - 'SHELL': '/bin/bash', 03:44:56 INFO - 'SHLVL': '1', 03:44:56 INFO - 'TERM': 'linux', 03:44:56 INFO - 'TMOUT': '86400', 03:44:56 INFO - 'USER': 'cltbld', 03:44:56 INFO - 'XDG_SESSION_COOKIE': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', 03:44:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:56 INFO - '_': '/tools/buildbot/bin/python'} 03:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:44:56 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 03:44:56 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 03:44:56 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 03:44:56 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 03:44:56 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 03:44:56 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 03:44:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:44:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 03:44:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:44:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:44:58 INFO - Installing collected packages: mozsystemmonitor 03:44:58 INFO - Running setup.py install for mozsystemmonitor 03:44:58 INFO - Successfully installed mozsystemmonitor 03:44:58 INFO - Cleaning up... 03:44:58 INFO - Return code: 0 03:44:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 03:44:58 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')]} 03:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:58 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')]} 03:44:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:44:58 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7ff763d21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23297f0>, '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 0x22952d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295760>, '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': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', '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 03:44:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 03:44:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 03:44:58 INFO - Using env: {'DISPLAY': ':0', 03:44:58 INFO - 'HOME': '/home/cltbld', 03:44:58 INFO - 'LANG': 'en_US.UTF-8', 03:44:58 INFO - 'LANGUAGE': 'en_US:en', 03:44:58 INFO - 'LOGNAME': 'cltbld', 03:44:58 INFO - 'MAIL': '/var/mail/cltbld', 03:44:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:58 INFO - 'MOZ_NO_REMOTE': '1', 03:44:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:58 INFO - 'NO_EM_RESTART': '1', 03:44:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:44:58 INFO - 'PWD': '/builds/slave/test-pgo', 03:44:58 INFO - 'SHELL': '/bin/bash', 03:44:58 INFO - 'SHLVL': '1', 03:44:58 INFO - 'TERM': 'linux', 03:44:58 INFO - 'TMOUT': '86400', 03:44:58 INFO - 'USER': 'cltbld', 03:44:58 INFO - 'XDG_SESSION_COOKIE': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', 03:44:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:58 INFO - '_': '/tools/buildbot/bin/python'} 03:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:59 INFO - Downloading/unpacking blobuploader==1.2.4 03:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:44:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:00 INFO - Downloading blobuploader-1.2.4.tar.gz 03:45:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 03:45:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:45:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:45:00 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 03:45:00 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 03:45:00 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 03:45:00 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 03:45:00 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 03:45:00 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 03:45:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 03:45:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 03:45:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:45:01 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 03:45:01 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 03:45:01 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 03:45:01 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 03:45:01 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 03:45:01 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 03:45:01 INFO - Downloading docopt-0.6.1.tar.gz 03:45:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 03:45:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 03:45:01 INFO - Installing collected packages: blobuploader, requests, docopt 03:45:01 INFO - Running setup.py install for blobuploader 03:45:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:45:01 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 03:45:01 INFO - Running setup.py install for requests 03:45:01 INFO - Running setup.py install for docopt 03:45:02 INFO - Successfully installed blobuploader requests docopt 03:45:02 INFO - Cleaning up... 03:45:02 INFO - Return code: 0 03:45:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7ff763d21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23297f0>, '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 0x22952d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295760>, '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': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', '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 03:45:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 03:45:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --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 03:45:02 INFO - Using env: {'DISPLAY': ':0', 03:45:02 INFO - 'HOME': '/home/cltbld', 03:45:02 INFO - 'LANG': 'en_US.UTF-8', 03:45:02 INFO - 'LANGUAGE': 'en_US:en', 03:45:02 INFO - 'LOGNAME': 'cltbld', 03:45:02 INFO - 'MAIL': '/var/mail/cltbld', 03:45:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:02 INFO - 'MOZ_NO_REMOTE': '1', 03:45:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:02 INFO - 'NO_EM_RESTART': '1', 03:45:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:45:02 INFO - 'PWD': '/builds/slave/test-pgo', 03:45:02 INFO - 'SHELL': '/bin/bash', 03:45:02 INFO - 'SHLVL': '1', 03:45:02 INFO - 'TERM': 'linux', 03:45:02 INFO - 'TMOUT': '86400', 03:45:02 INFO - 'USER': 'cltbld', 03:45:02 INFO - 'XDG_SESSION_COOKIE': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', 03:45:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:02 INFO - '_': '/tools/buildbot/bin/python'} 03:45:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:45:02 INFO - Running setup.py (path:/tmp/pip-qRWmNK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:45:02 INFO - Running setup.py (path:/tmp/pip-70vNy0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:45:02 INFO - Running setup.py (path:/tmp/pip-Iiaycs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:45:02 INFO - Running setup.py (path:/tmp/pip-FMqo9l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 03:45:02 INFO - Running setup.py (path:/tmp/pip-s4627l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 03:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:45:02 INFO - Running setup.py (path:/tmp/pip-Ni87UQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:45:02 INFO - Running setup.py (path:/tmp/pip-Y77MTj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:45:02 INFO - Running setup.py (path:/tmp/pip-j14USP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 03:45:02 INFO - Running setup.py (path:/tmp/pip-kSkquc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 03:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 03:45:02 INFO - Running setup.py (path:/tmp/pip-q2Rfik-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 03:45:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:45:03 INFO - Running setup.py (path:/tmp/pip-Ovow39-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:45:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:45:03 INFO - Running setup.py (path:/tmp/pip-crJwCT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:45:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:45:03 INFO - Running setup.py (path:/tmp/pip-Z5FTWX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:45:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:45:03 INFO - Running setup.py (path:/tmp/pip-nu0LVQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:45:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:45:03 INFO - Running setup.py (path:/tmp/pip-X6M_dR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:45:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 03:45:03 INFO - Running setup.py (path:/tmp/pip-m1ayhT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 03:45:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 03:45:03 INFO - Running setup.py (path:/tmp/pip-EtXHt0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 03:45:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:45:03 INFO - Running setup.py install for manifestparser 03:45:03 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 03:45:03 INFO - Running setup.py install for mozcrash 03:45:03 INFO - Running setup.py install for mozdebug 03:45:03 INFO - Running setup.py install for mozdevice 03:45:03 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 03:45:03 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 03:45:03 INFO - Running setup.py install for mozfile 03:45:04 INFO - Running setup.py install for mozhttpd 03:45:04 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 03:45:04 INFO - Running setup.py install for mozinfo 03:45:04 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 03:45:04 INFO - Running setup.py install for mozInstall 03:45:04 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 03:45:04 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 03:45:04 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 03:45:04 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 03:45:04 INFO - Running setup.py install for mozleak 03:45:04 INFO - Running setup.py install for mozlog 03:45:04 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 03:45:04 INFO - Running setup.py install for moznetwork 03:45:04 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 03:45:04 INFO - Running setup.py install for mozprocess 03:45:04 INFO - Running setup.py install for mozprofile 03:45:04 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 03:45:04 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 03:45:04 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 03:45:04 INFO - Running setup.py install for mozrunner 03:45:05 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 03:45:05 INFO - Running setup.py install for mozscreenshot 03:45:05 INFO - Running setup.py install for moztest 03:45:05 INFO - Running setup.py install for mozversion 03:45:05 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 03:45:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:45:05 INFO - Cleaning up... 03:45:05 INFO - Return code: 0 03:45:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7ff763d21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23297f0>, '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 0x22952d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295760>, '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': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', '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 03:45:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 03:45:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 03:45:05 INFO - Using env: {'DISPLAY': ':0', 03:45:05 INFO - 'HOME': '/home/cltbld', 03:45:05 INFO - 'LANG': 'en_US.UTF-8', 03:45:05 INFO - 'LANGUAGE': 'en_US:en', 03:45:05 INFO - 'LOGNAME': 'cltbld', 03:45:05 INFO - 'MAIL': '/var/mail/cltbld', 03:45:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:05 INFO - 'MOZ_NO_REMOTE': '1', 03:45:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:05 INFO - 'NO_EM_RESTART': '1', 03:45:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:45:05 INFO - 'PWD': '/builds/slave/test-pgo', 03:45:05 INFO - 'SHELL': '/bin/bash', 03:45:05 INFO - 'SHLVL': '1', 03:45:05 INFO - 'TERM': 'linux', 03:45:05 INFO - 'TMOUT': '86400', 03:45:05 INFO - 'USER': 'cltbld', 03:45:05 INFO - 'XDG_SESSION_COOKIE': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', 03:45:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:05 INFO - '_': '/tools/buildbot/bin/python'} 03:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:45:05 INFO - Running setup.py (path:/tmp/pip-oII7jy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:45:05 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)) 03:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:45:05 INFO - Running setup.py (path:/tmp/pip-7VIL_y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 03:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:45:05 INFO - Running setup.py (path:/tmp/pip-IX_UON-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:45:05 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)) 03:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:45:05 INFO - Running setup.py (path:/tmp/pip-s7afqn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 03:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 03:45:05 INFO - Running setup.py (path:/tmp/pip-wOGRQT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 03:45:05 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)) 03:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:45:05 INFO - Running setup.py (path:/tmp/pip-or45FK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:45:06 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)) 03:45:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:45:06 INFO - Running setup.py (path:/tmp/pip-XZM0Kp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:45:06 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)) 03:45:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:45:06 INFO - Running setup.py (path:/tmp/pip-yggEce-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:45:06 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)) 03:45:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 03:45:06 INFO - Running setup.py (path:/tmp/pip-VhQv68-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 03:45:06 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)) 03:45:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 03:45:06 INFO - Running setup.py (path:/tmp/pip-9oLrIS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 03:45:06 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)) 03:45:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:45:06 INFO - Running setup.py (path:/tmp/pip-6rSxtH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:45:06 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)) 03:45:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:45:06 INFO - Running setup.py (path:/tmp/pip-8UowKN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:45:06 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)) 03:45:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:45:06 INFO - Running setup.py (path:/tmp/pip-c9R6Q6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:45:06 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)) 03:45:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:45:06 INFO - Running setup.py (path:/tmp/pip-21RPZW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:45:06 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)) 03:45:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:45:06 INFO - Running setup.py (path:/tmp/pip-9VntYc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:45:06 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)) 03:45:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 03:45:06 INFO - Running setup.py (path:/tmp/pip-YkIMN6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 03:45:06 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)) 03:45:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 03:45:06 INFO - Running setup.py (path:/tmp/pip-yM2ZCD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 03:45:06 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)) 03:45:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 03:45:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 03:45:06 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)) 03:45:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 03:45:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 03:45:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 03:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:08 INFO - Downloading blessings-1.5.1.tar.gz 03:45:08 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 03:45:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 03:45:08 INFO - Installing collected packages: blessings 03:45:08 INFO - Running setup.py install for blessings 03:45:08 INFO - Successfully installed blessings 03:45:08 INFO - Cleaning up... 03:45:08 INFO - Return code: 0 03:45:08 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 03:45:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 03:45:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 03:45:08 INFO - Reading from file tmpfile_stdout 03:45:08 INFO - Current package versions: 03:45:08 INFO - argparse == 1.2.1 03:45:08 INFO - blessings == 1.5.1 03:45:08 INFO - blobuploader == 1.2.4 03:45:08 INFO - docopt == 0.6.1 03:45:08 INFO - manifestparser == 1.1 03:45:08 INFO - mozInstall == 1.12 03:45:08 INFO - mozcrash == 0.16 03:45:08 INFO - mozdebug == 0.1 03:45:08 INFO - mozdevice == 0.47 03:45:08 INFO - mozfile == 1.2 03:45:08 INFO - mozhttpd == 0.7 03:45:08 INFO - mozinfo == 0.9 03:45:08 INFO - mozleak == 0.1 03:45:08 INFO - mozlog == 3.1 03:45:08 INFO - moznetwork == 0.27 03:45:08 INFO - mozprocess == 0.22 03:45:08 INFO - mozprofile == 0.28 03:45:08 INFO - mozrunner == 6.11 03:45:08 INFO - mozscreenshot == 0.1 03:45:08 INFO - mozsystemmonitor == 0.0 03:45:08 INFO - moztest == 0.7 03:45:08 INFO - mozversion == 1.4 03:45:08 INFO - psutil == 3.1.1 03:45:08 INFO - requests == 1.2.3 03:45:08 INFO - wsgiref == 0.1.2 03:45:08 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:45:08 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')]} 03:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:08 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')]} 03:45:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:08 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7ff763d21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23297f0>, '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 0x22952d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295760>, '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': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', '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 03:45:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 03:45:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 03:45:08 INFO - Using env: {'DISPLAY': ':0', 03:45:08 INFO - 'HOME': '/home/cltbld', 03:45:08 INFO - 'LANG': 'en_US.UTF-8', 03:45:08 INFO - 'LANGUAGE': 'en_US:en', 03:45:08 INFO - 'LOGNAME': 'cltbld', 03:45:08 INFO - 'MAIL': '/var/mail/cltbld', 03:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:08 INFO - 'MOZ_NO_REMOTE': '1', 03:45:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:08 INFO - 'NO_EM_RESTART': '1', 03:45:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:45:08 INFO - 'PWD': '/builds/slave/test-pgo', 03:45:08 INFO - 'SHELL': '/bin/bash', 03:45:08 INFO - 'SHLVL': '1', 03:45:08 INFO - 'TERM': 'linux', 03:45:08 INFO - 'TMOUT': '86400', 03:45:08 INFO - 'USER': 'cltbld', 03:45:08 INFO - 'XDG_SESSION_COOKIE': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', 03:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:08 INFO - '_': '/tools/buildbot/bin/python'} 03:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:09 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)) 03:45:09 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)) 03:45:09 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)) 03:45:09 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)) 03:45:09 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)) 03:45:09 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)) 03:45:09 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)) 03:45:09 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)) 03:45:09 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)) 03:45:09 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)) 03:45:09 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)) 03:45:09 INFO - Cleaning up... 03:45:09 INFO - Return code: 0 03:45:09 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 03:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7ff763d21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23297f0>, '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 0x22952d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295760>, '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': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', '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 03:45:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 03:45:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 03:45:09 INFO - Using env: {'DISPLAY': ':0', 03:45:09 INFO - 'HOME': '/home/cltbld', 03:45:09 INFO - 'LANG': 'en_US.UTF-8', 03:45:09 INFO - 'LANGUAGE': 'en_US:en', 03:45:09 INFO - 'LOGNAME': 'cltbld', 03:45:09 INFO - 'MAIL': '/var/mail/cltbld', 03:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:09 INFO - 'MOZ_NO_REMOTE': '1', 03:45:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:09 INFO - 'NO_EM_RESTART': '1', 03:45:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:45:09 INFO - 'PWD': '/builds/slave/test-pgo', 03:45:09 INFO - 'SHELL': '/bin/bash', 03:45:09 INFO - 'SHLVL': '1', 03:45:09 INFO - 'TERM': 'linux', 03:45:09 INFO - 'TMOUT': '86400', 03:45:09 INFO - 'USER': 'cltbld', 03:45:09 INFO - 'XDG_SESSION_COOKIE': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', 03:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:09 INFO - '_': '/tools/buildbot/bin/python'} 03:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:09 INFO - Downloading/unpacking jsonschema 03:45:09 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 03:45:09 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 03:45:09 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 03:45:09 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 03:45:09 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 03:45:09 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 03:45:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:45:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 03:45:11 INFO - Downloading/unpacking functools32 (from jsonschema) 03:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:11 INFO - Downloading functools32-3.2.3-2.tar.gz 03:45:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 03:45:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 03:45:11 INFO - warning: no files found matching '*.txt' 03:45:11 INFO - no previously-included directories found matching 'build' 03:45:11 INFO - no previously-included directories found matching 'dist' 03:45:11 INFO - no previously-included directories found matching '.git*' 03:45:11 INFO - Installing collected packages: jsonschema, functools32 03:45:11 INFO - Running setup.py install for functools32 03:45:11 INFO - warning: no files found matching '*.txt' 03:45:11 INFO - no previously-included directories found matching 'build' 03:45:11 INFO - no previously-included directories found matching 'dist' 03:45:11 INFO - no previously-included directories found matching '.git*' 03:45:11 INFO - Successfully installed jsonschema functools32 03:45:11 INFO - Cleaning up... 03:45:11 INFO - Return code: 0 03:45:11 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 03:45: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')]} 03:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45: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')]} 03:45:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:11 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7ff763d21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23297f0>, '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 0x22952d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295760>, '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': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', '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 03:45:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 03:45:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 03:45:11 INFO - Using env: {'DISPLAY': ':0', 03:45:11 INFO - 'HOME': '/home/cltbld', 03:45:11 INFO - 'LANG': 'en_US.UTF-8', 03:45:11 INFO - 'LANGUAGE': 'en_US:en', 03:45:11 INFO - 'LOGNAME': 'cltbld', 03:45:11 INFO - 'MAIL': '/var/mail/cltbld', 03:45:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:11 INFO - 'MOZ_NO_REMOTE': '1', 03:45:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:11 INFO - 'NO_EM_RESTART': '1', 03:45:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:45:11 INFO - 'PWD': '/builds/slave/test-pgo', 03:45:11 INFO - 'SHELL': '/bin/bash', 03:45:11 INFO - 'SHLVL': '1', 03:45:11 INFO - 'TERM': 'linux', 03:45:11 INFO - 'TMOUT': '86400', 03:45:11 INFO - 'USER': 'cltbld', 03:45:11 INFO - 'XDG_SESSION_COOKIE': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', 03:45:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:11 INFO - '_': '/tools/buildbot/bin/python'} 03:45:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:11 INFO - Downloading/unpacking flake8 03:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:45:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 03:45:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:45:13 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 03:45:13 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 03:45:13 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 03:45:13 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 03:45:13 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 03:45:13 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 03:45:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:45:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 03:45:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:45:13 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 03:45:13 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 03:45:13 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 03:45:13 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 03:45:13 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 03:45:13 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 03:45:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 03:45:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:45:13 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 03:45:13 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 03:45:13 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 03:45:13 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 03:45:13 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 03:45:13 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 03:45:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 03:45:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 03:45:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:45:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:45:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:45:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:45:14 INFO - no previously-included directories found matching 'docs/_build' 03:45:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:45:14 INFO - Running setup.py install for pep8 03:45:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:45:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:45:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:45:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:45:14 INFO - no previously-included directories found matching 'docs/_build' 03:45:14 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 03:45:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:45:14 INFO - Cleaning up... 03:45:14 INFO - Return code: 0 03:45:14 INFO - Running post-action listener: _resource_record_post_action 03:45:14 INFO - Running post-action listener: _start_resource_monitoring 03:45:14 INFO - Starting resource monitoring. 03:45:14 INFO - ##### 03:45:14 INFO - ##### Running install step. 03:45:14 INFO - ##### 03:45:14 INFO - Running pre-action listener: _resource_record_pre_action 03:45:14 INFO - Running main action method: install 03:45:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 03:45:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 03:45:14 INFO - Reading from file tmpfile_stdout 03:45:14 INFO - Detecting whether we're running mozinstall >=1.0... 03:45:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 03:45:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 03:45:14 INFO - Reading from file tmpfile_stdout 03:45:14 INFO - Output received: 03:45:14 INFO - Usage: mozinstall [options] installer 03:45:14 INFO - Options: 03:45:14 INFO - -h, --help show this help message and exit 03:45:14 INFO - -d DEST, --destination=DEST 03:45:14 INFO - Directory to install application into. [default: 03:45:14 INFO - "/builds/slave/test-pgo"] 03:45:14 INFO - --app=APP Application being installed. [default: firefox] 03:45:14 INFO - mkdir: /builds/slave/test-pgo/build/application 03:45:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 03:45:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 03:45:30 INFO - Reading from file tmpfile_stdout 03:45:30 INFO - Output received: 03:45:30 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 03:45:30 INFO - Running post-action listener: _resource_record_post_action 03:45:30 INFO - ##### 03:45:30 INFO - ##### Running run-tests step. 03:45:30 INFO - ##### 03:45:30 INFO - Running pre-action listener: _resource_record_pre_action 03:45:30 INFO - Running main action method: run_tests 03:45:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 03:45:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 03:45:30 INFO - Python 2.7.3 03:45:30 INFO - Return code: 0 03:45:30 INFO - grabbing minidump binary from tooltool 03:45:30 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')]} 03:45:30 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 0x23297f0>, '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 0x22952d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295760>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 03:45:30 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 03:45:30 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 03:45:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:45:30 INFO - Return code: 0 03:45:30 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 03:45:30 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 03:45:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 03:45:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 03:45:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 03:45:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 03:45:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 03:45:31 INFO - Return code: 0 03:45:31 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.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-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test-pgo/build 03:45:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.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-e10s_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:45:31 INFO - Using env: {'DISPLAY': ':0', 03:45:31 INFO - 'HOME': '/home/cltbld', 03:45:31 INFO - 'LANG': 'en_US.UTF-8', 03:45:31 INFO - 'LANGUAGE': 'en_US:en', 03:45:31 INFO - 'LOGNAME': 'cltbld', 03:45:31 INFO - 'MAIL': '/var/mail/cltbld', 03:45:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 03:45:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 03:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:31 INFO - 'MOZ_NO_REMOTE': '1', 03:45:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 03:45:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:31 INFO - 'NO_EM_RESTART': '1', 03:45:31 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:45:31 INFO - 'PWD': '/builds/slave/test-pgo', 03:45:31 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 03:45:31 INFO - 'SHELL': '/bin/bash', 03:45:31 INFO - 'SHLVL': '1', 03:45:31 INFO - 'TERM': 'linux', 03:45:31 INFO - 'TMOUT': '86400', 03:45:31 INFO - 'USER': 'cltbld', 03:45:31 INFO - 'XDG_SESSION_COOKIE': '801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170', 03:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:31 INFO - '_': '/tools/buildbot/bin/python'} 03:45:31 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.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-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:45:31 INFO - mozversion application_buildid: 20151230013009 03:45:31 INFO - mozversion application_changeset: cb7ddeee6364905a0184e7ceccfab9d1dfea25f9 03:45:31 INFO - mozversion application_display_name: Nightly 03:45:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:45:31 INFO - mozversion application_name: Firefox 03:45:31 INFO - mozversion application_remotingname: firefox 03:45:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 03:45:31 INFO - mozversion application_vendor: Mozilla 03:45:31 INFO - mozversion application_version: 46.0a1 03:45:31 INFO - mozversion platform_buildid: 20151230013009 03:45:31 INFO - mozversion platform_changeset: cb7ddeee6364905a0184e7ceccfab9d1dfea25f9 03:45:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 03:45:31 INFO - mozversion platform_version: 46.0a1 03:45:31 INFO - using testdate: 1451475931 03:45:31 INFO - actual date: 1451475931 03:45:31 INFO - starting webserver on 'localhost:47110' 03:45:31 INFO - SUITE-START | Running 3 tests 03:45:31 INFO - TEST-START | dromaeo_css 03:45:31 INFO - operating with platform_type : linux_ 03:45:31 INFO - Initialising browser for dromaeo_css test... 03:45:31 INFO - TEST-INFO | started process 29101 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpm2bpI3/profile http://localhost:47110/getInfo.html) 03:45:36 INFO - PROCESS | 29101 | __metrics Screen width/height:1600/1200 03:45:36 INFO - PROCESS | 29101 | colorDepth:24 03:45:36 INFO - PROCESS | 29101 | Browser inner width/height: 1024/697 03:45:36 INFO - PROCESS | 29101 | __metrics 03:45:36 INFO - PROCESS | 29101 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 03:45:36 INFO - TEST-INFO | 29101: exit 0 03:45:36 INFO - Browser initialized. 03:45:36 INFO - Running cycle 1/1 for dromaeo_css test... 03:45:36 INFO - TEST-INFO | started process 29229 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpm2bpI3/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:45:48 INFO - PROCESS | 29229 | RSS: Main: 149889024 03:45:48 INFO - PROCESS | 29229 | 03:46:38 INFO - PROCESS | 29229 | 1451475998344 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] 03:48:47 INFO - PROCESS | 29229 | Cycle 1(1): loaded http://localhost:47110/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47110/tests/dromaeo/cssquery-ext.html) 03:48:48 INFO - PROCESS | 29229 | RSS: Main: 155291648 03:48:48 INFO - PROCESS | 29229 | 03:51:50 INFO - PROCESS | 29229 | Cycle 1(1): loaded http://localhost:47110/tests/dromaeo/cssquery-ext.html (next: http://localhost:47110/tests/dromaeo/cssquery-jquery.html) 03:51:51 INFO - PROCESS | 29229 | RSS: Main: 152711168 03:51:51 INFO - PROCESS | 29229 | 03:54:53 INFO - PROCESS | 29229 | Cycle 1(1): loaded http://localhost:47110/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47110/tests/dromaeo/cssquery-mootools.html) 03:54:53 INFO - PROCESS | 29229 | RSS: Main: 153542656 03:54:53 INFO - PROCESS | 29229 | 03:57:55 INFO - PROCESS | 29229 | Cycle 1(1): loaded http://localhost:47110/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47110/tests/dromaeo/cssquery-prototype.html) 03:57:55 INFO - PROCESS | 29229 | RSS: Main: 152186880 03:57:55 INFO - PROCESS | 29229 | 04:00:58 INFO - PROCESS | 29229 | Cycle 1(1): loaded http://localhost:47110/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47110/tests/dromaeo/cssquery-yui.html) 04:00:58 INFO - PROCESS | 29229 | RSS: Main: 152670208 04:00:58 INFO - PROCESS | 29229 | 04:04:01 INFO - PROCESS | 29229 | Cycle 1(1): loaded http://localhost:47110/tests/dromaeo/cssquery-yui.html (next: http://localhost:47110/tests/dromaeo/cssquery-dojo.html) 04:04:01 INFO - PROCESS | 29229 | RSS: Main: 154628096 04:04:01 INFO - PROCESS | 29229 | 04:04:01 INFO - PROCESS | 29229 | __start_tp_report 04:04:01 INFO - PROCESS | 29229 | _x_x_mozilla_page_load 04:04:01 INFO - PROCESS | 29229 | _x_x_mozilla_page_load_details 04:04:01 INFO - PROCESS | 29229 | |i|pagename|runs| 04:04:01 INFO - PROCESS | 29229 | |0;dojo.html;3169.5404166395874;3209.6790320967893;3252.788568743033;3262.657420970798;3272.1001724525754;5284.656497327671;5373.220882971967;5392.56859451244;5437.809676661318;5442.12925931851;5348.411674715778;5352.625316227856;5388.515033646971;5438.945610543893;5455.345358556969;4665.650076244278;4932.605391568666;5080.593552515808;5087.898242035157;5097.5667068299235;4809.470958194595;4970.254461830718;4983.6013118950395;5036.84889453317;5048.6970781753025;4877.122118018738;4922.729249891255;4938.925916111261;4964.826231081895;5000.024995125952;4951.925721114186;4952.959878425535;4983.651144419892;4987.975060124712;4996.000799840047;4843.443004054544;4914.11545921734;4936.827211047596;4957.652964292501;5014.971930754223;5246.501582349671;5269.235960785703;5299.6025298102795;5317.202419637047;5371.221172929903;5705.971470142665;5736.541076713853;5747.942520574783;5783.132530120473;5789.507891829199;5729.512991395736;5835.328937172195;5838.766449342022;5888.38171991939;5900.409959004065;5438.9394068156325;5440.455954404607;5444.809431669913;5462.453754624546;5472.315960504937;5875.4124587541755;5944;5961.314448838352;5965.8366618509845;5972.4326189012845;5672.14917762339;5673.120666296731;5723.627964182315;5798.478136967693;5851.034579294454;5810.738516766671;5863.472287494146;5879.882402352015;5881.264841894763;5882.588218824641;5588.580856435784;5780.739866706008;5836.153757705109;5849.327327357323;5852.824415267549;5669.574781891375;5712.314522257273;5795.449432303924;5827.184194212814;5856.238688970492;4519.593236608721;4522.638188944877;4527.660425468103;4532.138893610204;4541.046380260116;3871.7483363581277;3998.0404702871683;4004.3993400989502;4009.67922566194;4041.636252737209;5411.783528658888;5437.809676661298;5475.616706830484;5502.614816962774;5521.5306698930235;5829.300483941955;5850.297964244232;5852.385499522574;5856.472917437451;5858.941410585925;5278.366596008426;5412.161115027177;5412.512873841373;5438.15708565173;5441.5102640762525;4984.053029924303;5027.79888804451;5028.371453568304;5075.974620126875;5103.055934652101;5884.676342801102;5899.587028908021;5903.498202652737;5907.05487122067;5907.881842363128;5704.80033198836;5868.823935281948;5883.499902508163;5884;5893.469587737124;5751.281089863767;5755.079187330007;5824.417558244311;5824.767009319749;5958.225430693982;5683.914741278802;5686.459786320293;5691.772329106955;5702.059160238514;5719.370869204467;5749.482546570829;5792.94207057924;5803.39064398249;5811.041178205549;5819.447152520673;5533.253010843485;5639.6334238274385;5706.914396284142;5780;5820.214271073351;5687.77248910039;5720.542356611378;5723.656580605177;5724.713764311851;5736.311642602915;5452.263944367618;5649.576281778801;5666.03677374839;5668.574856885833;5668.688222147822;5670.858228544319;5751.568632352674;5810.477057064884;5813.505852002458;5858.7070646466655;5560.471755183251;5576.330840299028;5604.579656525695;5618.494335509824;5618.943810562006 04:04:01 INFO - PROCESS | 29229 | |1;ext.html;534.4361698408179;548.9039418101831;549.5603517186253;558.3523113188701;601.2454369765943;28238.57642135367;28873.278168045785;35449.82275088621;35880.820595896985;36238.45642315367;38432.423513647314;38726.8063659682;38759;38943.805280973625;39015.41476877849;8531.232189102979;8648.43785153963;8730.519821409851;8754.956225218897;8756.781080472976;8458.3656225783;8531.402801803877;8598.527081010541;8604.139586041378;8611.870821937644;8595.656173753043;8673.609687564043;8678.826423471559;8689.131086891324;8692.956535217316;8529.488230706147;8587;8617.741467755977;8623.482591044494;8625.051244363114;8445.1977062179;8525.488470691716;8585.484870907765;8585.871211931826;8590.613422396007;38892.41661375053;39109.608903910885;39332.21335573301;39472;39632.20735585272;3274.4433446314183;3352.1619595101224;3369.073504786179;3538.115471132217;3817.675497582709;29434;29546;29881.402371952656;29951;30034.24914377136;3742.8315725792404;3775.6979441644603;3813.3707938190164;3848.807559622008;3874.418837174418;254515;254552;255764;256298;257635;50802.23796643053;52159.478405215465;55958.160627590616;56171.43828561744;56177;61667.69166154141;63038.36961630417;63225.683871580346;63289.68355158195;63351.683241583494;15615.843841561666;15701.921490392704;15716.29276682552;15809.209539522977;15854.524364269091;11899.167058305835;11970.341631210367;12002.459889305002;12009.159358844798;12023.338716370683;18346.449606511826;18449.815501845078;18645.81354186441;18688.34590789316;19108;7160.140783106061;7272.8181795454475;7350.649350649351;7354.852902941911;7429.442791790637;915.0666320353336;1010.4947526236881;1031.4430207687765;1062.1184416933927;1090.4166271045112;28548.596047987827;28607.713922860923;29010.564841527394;29176.27059323534;29376.26559336034;1090.4711215060688;1113.0427832064418;1157.2015309436463;1168.3457263932055;1169.8713141554422;15045.849541504445;15092.849071509365;15095.849041509444;15099;15386.307616157299;308087;309785;310976;313470;313513;50404;50858;51109.48890511048;51112.233316499536;51209.48790512122;1331.5672406467936;1340.1824886819034;1342;1350.1426594112809;1360.3946243921557;27725.86137069302;28473.572896406156;28549.42901142007;28657.42685146244;29110.41779164447;45532.54467455283;45902.770486147354;46024.079518410115;46047;46053.30920036269;241302;241438;242682.786586073;243162;243245;31763;31864.840675796473;31914.840425797724;32533.186670332485;32591.67408325981;39129.80435097806;41921.16157676891;42587.36118958156;43164.568354317315;43449.782751086044;32756;32990.5051424224;33024.834875825465;33489.162770930925;33625.66374336225;32915;35311.29377412386;36805.44791828178;37003.44494832634;37504.437433437975;34893.47659785156;34897;35161;35171.47242791309;35863;29753;31476;32685;32722.672773271963;32768.67231327656;24295.392615184053;24998.875005624854;26187.476250475265;26299.868500657376;26448.8677556611 04:04:01 INFO - PROCESS | 29229 | |2;jquery.html;361.9493270942067;363.7526481992246;364.5370379617885;367.34795737565815;367.7095094875049;16469;17428.215730292137;17654.82345176548;18124.81875181248;18391;12937.611871643841;12977.61067167984;13402.396892139852;13701.3834377453;17048.914755426238;5007.474215207405;5107.7701503432245;5126.641135120524;5127.051495473349;5128.128218202895;4589.0363023764885;5080;5104.183330667094;5107.616928730342;5110.080185566597;4715.504871988426;5029.497050294959;5088.1095808233595;5135.871603209912;5219.817306394277;5120.206368012964;5132.384113906336;5152.536271735543;5153.845384638467;5170.689758614495;5076.18780995039;5233.764480598345;5260.92108618371;5264.341957255343;5267.710275934822;13248.20510769357;13290.667733306647;13298.069135160542;16982.575435614086;19748.40754777359;2022.6258142243732;2096.6330892093824;2118.7881211878844;2162.675598660198;2205.911763529457;9476.905230947672;9717.514124293757;9821.80356392868;12259.774022597758;12376.876231237662;1771.1852547828014;1773.8492228160621;1797.9460616181536;1828.9176987035614;1834.9082545872652;61548;64692.67653661749;65729.67135164341;66023;66145.3385466144;10136.898631013742;10321.535530901128;10353.534090965924;10370.481475926174;10387.792244155075;13488;13522.594322170155;13542.255175965416;13618.727625447635;13650.726985460435;7566.886496702554;7656.808579785438;7707.190744971809;7839.608019598997;7937.920620793833;7432.702691892372;7440.069991251094;7458.5151965122095;7480.438967077491;7618.047744031996;4414.690971631955;4426;4426.75652839097;4439.778011099432;4490.50604433512;1824.5529845187748;1850.2506484873647;1854.8887066776037;1855.5116381203252;1855.8608104392224;437.65862627150875;457.2455448509985;458.5712358944398;460.716659254556;468.861685802689;10665.453509241117;11231.157663175294;11277.379744113989;11300.94349528247;11414.258073225214;451.50560136650313;457.38938517079407;459.36836849332167;465.8113464047066;470.9576138147583;6108.511319094462;6228.22147231596;6267.71795269214;6281.277653069864;6328.557001009885;180786;500616;501607;507795;541039;80290;81321;82292.58853705693;83238.58380708058;83958.58020709857;505.7547089661511;507.76642744338017;509.5006893244567;509.8750806052541;511.71599762132377;10247.897521024694;10500.579976800842;10555.261131721012;10669.41318227505;10776.245662803529;35311;35753.46369804403;36332.27335453329;36495;36536.81731591325;47793.28310075282;48151;48795;49077.01845963132;49506.75246623744;9523.380980236263;9681.564329605044;9713.514324283617;10103.696889093339;10147.797044059225;11873.82189267179;12017.4592143352;13308.600741977756;18036.18837152305;18067.367642132464;10959.835602466128;14287.57137285883;14489.347979341166;14864.702705946036;15000.399984000518;14014.229217393142;14116.01187916836;14177.43290268419;14488.763430707044;14636.780448293071;14509.347079381665;14742.926285368505;15586.207586127286;15651.843481565495;15686.843131568538;9596;9723.416595004323;9740.512974351112;9835.655752048644;9839.5080245986;10499.895001049892;11565;14605.488807891672;14966;15103.773443398139 04:04:01 INFO - PROCESS | 29229 | |3;mootools.html;1424.3234463629776;1445.9927700361498;1608.4933246027501;1774.3789673614244;1812.5378028602713;7217.422606191505;7325.5238409503345;7436.442266829989;7572.772816815497;7619.276168763959;7444.590547519884;7446.218147094558;7503.924960750391;7527.698892044312;7558.697652093923;2297.4715815362433;2313.2250696016854;2322.7212734471887;2323.152049501928;2339.076064954342;2289.713785776778;2334.054707843326;2340.1341503643675;2341.3210169050953;2341.8711970841596;2210.469487323047;2277.2257432472957;2278.453171238899;2298.9310320690406;2318.4435735423544;2282.942926426836;2306.4003359126664;2343.109618345035;2344.2146880794867;2347.8826058696995;2308.7806658367426;2328.2898715891647;2343.9882800585888;2356.8468049576722;2360.44529535559;7508.211637778064;7607.923920760777;7628.237176282383;7672.731454399125;7673.50122242058;4643.16423043852;4686.929696054562;4696.7651617419;4699.976500117512;4700.318453824177;2977;3015.834129122897;3095.8761649534217;3099.1012606344134;3137.30979184579;952.8999455057258;955.603424578806;957.2055194188806;965.7826988927505;977.6480467031934;10462.686119416354;10483.161347092215;10515.369077855354;10545.472726363729;10546.419946902917;4134.793260336971;4217.873463796091;4241.024564350217;4263.531011588785;4265.1043280910735;8470.195331443503;8508.957455212685;8511.404201705946;8513.233808957224;8513.914860851437;4619.745913974696;4658.813647454132;4671.766411679402;4677.368555245066;4698.859034228979;4243.511996120424;4259.403683484315;4304.418903448057;4305.655547556251;4316.719413238129;3338.382399256097;3344.080377896098;3368.865245390206;3382.644822293673;3388.8136152511356;1029.8815636201932;1049.6063976008998;1067.791780602775;1068.636663534402;1090.0897750378522;1055.2560444886337;1088.0697004061572;1095.9616413425492;1263.677762170659;1361.1764882246296;7044.436445084381;7064.32888875556;7107.466939979522;7120.786376408716;7141.89287160693;1117.536222467667;1121.7644294698205;1124.4883577972003;1133.7562424078862;1141.9543218271176;5659.943400566024;5693.231413759172;5731;5750.539956803445;5754.280714910637;8879.200871921557;8917.331200160013;9143.268538516902;9190.172884440433;9193.494357810385;8971.372003959661;8981.05698901619;8997.190252877272;9008.549572521348;9109.635614575343;6869.690863911136;7097.89353159703;7116.181639111568;7120.2167761547225;7196.136463624399;7150.42796576283;7194.4244460442;7218.927810721825;7229.349358557755;7236.855262894818;7842.568658723825;8102.270795628186;8114.715984940499;8123.18768123183;8232.83534329322;8055.597220139087;8274.503529788231;8284;8386.664533418594;8433.746987590383;6530.706118224596;6567.9014814776865;6569.408753212234;6635.402813746786;6651.567648102858;7318.707251709872;7321.670524826289;7324.08448943882;7332.926670733438;7352.485326027127;6315.747370105144;6867.725290988305;6985.1617805863625;7082.2917708230825;7099.290070992859;6568.14614100183;6595.67021648906;6628.171478565179;6698.631575263407;6703.798886033427;6486.902696459651;6546.869062618625;6563.3764792344655;6574.211094668671;6601.636909969998;6170.167027451416;6171.5988460751705;6177.4749146321265;6204.255489341127;6223.284322302993;6109.144719739151;6161.630302181883;6195.721192546256;6205.03821907605;6262.185915830913 04:04:01 INFO - PROCESS | 29229 | |4;prototype.html;342.2692551402755;343.3236524047625;348.794211415265;350.8070561191344;351.174739362498;6268.529860260482;6282.52881033922;6286.465650419714;6305.747770089202;6402.615843049421;6182.443580077792;6226.657533835637;6258.718357673904;6280.811575652738;6328.177336946191;4807.110684523357;4810.542998415162;4819.686720363183;4838.056578967103;4841.3222148899185;4820.060088282787;4839.951600483985;4853.757312134379;4854.660173787836;4855.441624213207;4689.062187562484;4796.808127674889;4807.1587472192405;4807.278908163768;4816;4779.402574678165;4783.067301876135;4794.784234709429;4798.880027999328;4800.519948005206;4150.128473020669;4163.500379954395;4166;4172.165566886635;4173.979130104361;6063.454289114001;6104.542159338067;6108.389161083901;6115.724792384353;6127.662978536223;5415.539679127279;5543.251661025791;5549.639273447254;5551.77792888284;5602.1036634138345;6760.357766012221;6771.153605799275;6772.458203343721;6773.221079575863;6773.72905083796;4588.311753237008;4592.816287348502;4598.0803839232285;4603.286490593963;4604.746738929358;6734.8989765153565;6904.240533541307;6917.550359226634;6921.4808889333;6986.161660600709;4522.819087236539;4536.705114167568;4538.795754191069;4540.841070562515;4546.318052292183;4594.816207351735;4596.287575425815;4607.262837945913;4618.97690511552;4624.583787459145;5429.891402171935;5439.782408703687;5445.754941027663;5455.427180146107;5459.508644222039;6616.239132499728;6737.764178253738;6741.325867413219;6748.325167483213;6749.358810912955;1593.9920300398423;1594.8484893935058;1597.9600509987342;1598;1604.8635865951292;1057.376148072641;1069.8609180806445;1070.325694812279;1081.6106201767357;1081.7836432713489;6209.65846878417;6248.875022499525;6248.9062664060075;6265.498760099181;6267.9373206268265;6824.624645644438;6859.725610975505;6883.827904302432;6886;6902.551334163263;6144.508439324933;6244.219472565929;6284.937150628527;6290.433860952537;6293.874122517524;3737.439384092408;3746.550413950289;3759.0414444316525;3760.793156376371;3761.4169803680475;6935.6878940447805;6947.444204463631;6949.89575156373;6952.409045231212;6978.546394484342;6950.0964874567;6995.720171193096;7008.614526201006;7023.543469674557;7042.647867606702;7043.823904402431;7068.787936361918;7079.327463890922;7081.433485321162;7098.716051357991;7000.334968178015;7005.824854378681;7013.684384202621;7014.807482728051;7028.156621205488;6807.251202367636;6847.1098757163245;6927.792166235021;6942.583444993316;6951.304869513008;7043.436525078084;7053.964730176316;7063.328983746535;7065.0815393998455;7065.894011589933;4664.3469914211355;4678.789454474489;4691.390119284471;4694.2723877799;4704.341392205162;6878.140232470942;7013.544119632412;7018.403435708022;7036.472264580075;7077.433805295461;6752.054712340375;6772.5936443813525;6793.762218322335;6796.660166991532;6804.4216241620015;6759.763408280686;6765.42493888025;6776.728930842908;6778.356056174655;6781;6579.769708060195;6635.4359879410795;6680.465562754871;6683.899741503784;6701.497387695845;6785.864282714417;6818.352256535622;6826.965865170642;6834.589924604539;6849;6649.933500664932;6659.500537459807;6661.16735408074;6670.599764014175;6688.130543029375 04:04:01 INFO - PROCESS | 29229 | |5;yui.html;749.4978364495787;806.8386322735454;833.7540425574455;859.1709000814213;873.3406278259913;13582.25297608631;13822.861771382295;13844.169349839016;13880.236586987725;13922.095063820845;13242.139260948032;13334.266615336153;13336.933315333434;13342.332883355843;13356.198628082322;811.0267678785466;826.2481142160635;830.995845020774;843.5149788871393;848.0840692052568;845.2899564365931;849.1932663969224;853.0616322045731;853.0999795216045;853.8975322961253;884.880541126946;893.4639216470099;895.3777124898198;895.4492986813095;896.2426749396749;882.5190271302156;882.7131182365757;883.3021912689001;883.9116088391141;884.1423818895661;650.1645385679426;653.630698655263;653.8626888353451;653.9574927629689;655.1515787055727;13699.589012329647;13707.931460342734;13818.792718109236;13853.792193117111;13880.305984700826;12128.696782580506;12165.20926139807;12421.254724716455;12522.686932826753;12936.223826570436;10534.736631584195;10611.31026483276;10616.628418005334;10698.411587362692;10756.247062705614;3610.494530765695;3620.584986216308;3654.2326111516613;3669.2294618130227;3680.3743363627977;45236.54763452389;45859;45893.31160032569;45911;46002.53997460016;9125.680601178927;9611.711648650551;10026.248031397674;10104.545295461721;10284.588616455258;17259.482215533553;17899.37352192667;18026.2789488419;18055.09724513769;18097.728534072;7501.549907005598;7641.312281894656;7711.961440192876;7735.381169506426;7887.369010479148;4567.1550763108935;4605.148047611203;4607.262837945913;4625.167469855391;4635.3742244797195;3134.717875391226;3142.9371412571622;3159.526071089309;3161.826099564546;3165.6201255849446;560.4983539731917;573.191799562623;579.3077272659216;582.7232064769286;589.0251633546487;563.8252141836044;649.6264647827423;690.8203866994517;692.2143367278176;705.8180281830136;8561.871571926426;8609.956950215334;8677.1756683115;8709.172628600272;8758.080401557872;472.7021976155069;521.6374619639314;546.5327145290798;553.2531083037948;666.2005593288073;4022.235775202702;4094.201630682048;4513.796879140446;4541.772911354419;4565.589096981287;56017.719911400185;57458.13812792811;57682;57891.131633025536;57994.55013624693;48110.51889481131;48513;48862.26706599404;48924.75537622289;48945.26582101271;42615.57384426178;44205;44383.55616443859;44593.33110003352;44747.77626111849;8413.789655258668;8452;8617.82764344722;8797.780055498664;8891.377603567687;34583.308333832676;34670.30659386849;35301.1174720634;35476;35509.64490355063;40585.391219132325;41217;44535.55464445314;44580.77709611431;44967.55032449633;11412.31526108436;11469.139814514112;11573.652790416301;11583.247088939192;11762;11748.88251117478;11760.470778815145;12017.399130043288;12581;12867.099303048697;11675.241109327866;11961.880381196077;11970.581029664268;12058.457369418222;12336.383180841101;11452.656420307405;11844.703882402664;11864.76270474603;11981.460834262654;12383.19509231897;10964.780704385708;11577.594784182513;11598.88401115978;11981.880181198076;12006.579769708018;10157.593696252363;10253.231007674603;10347.27569070158;10425.895741042492;10893.128549715846;9332.860007099764;9584.468327472747;9736.264063189625;10134.290599657954;10222 04:04:01 INFO - PROCESS | 29229 | __end_tp_report 04:04:01 INFO - PROCESS | 29229 | __start_cc_report 04:04:01 INFO - PROCESS | 29229 | _x_x_mozilla_cycle_collect,307 04:04:01 INFO - PROCESS | 29229 | __end_cc_report 04:04:01 INFO - PROCESS | 29229 | __startTimestamp1451477041627__endTimestamp 04:04:01 INFO - PROCESS | 29229 | 04:04:01 INFO - PROCESS | 29229 | ------- Summary: start ------- 04:04:01 INFO - PROCESS | 29229 | Number of tests: 6 04:04:01 INFO - PROCESS | 29229 | 04:04:01 INFO - PROCESS | 29229 | [#0] dojo.html Cycles:165 Average:5394.06 Median:5618.94 stddev:590.11 (10.5%) stddev-sans-first:565.53 04:04:01 INFO - PROCESS | 29229 | Values: 3169.5 3209.7 3252.8 3262.7 3272.1 5284.7 5373.2 5392.6 5437.8 5442.1 5348.4 5352.6 5388.5 5438.9 5455.3 4665.7 4932.6 5080.6 5087.9 5097.6 4809.5 4970.3 4983.6 5036.8 5048.7 4877.1 4922.7 4938.9 4964.8 5000.0 4951.9 4953.0 4983.7 4988.0 4996.0 4843.4 4914.1 4936.8 4957.7 5015.0 5246.5 5269.2 5299.6 5317.2 5371.2 5706.0 5736.5 5747.9 5783.1 5789.5 5729.5 5835.3 5838.8 5888.4 5900.4 5438.9 5440.5 5444.8 5462.5 5472.3 5875.4 5944.0 5961.3 5965.8 5972.4 5672.1 5673.1 5723.6 5798.5 5851.0 5810.7 5863.5 5879.9 5881.3 5882.6 5588.6 5780.7 5836.2 5849.3 5852.8 5669.6 5712.3 5795.4 5827.2 5856.2 4519.6 4522.6 4527.7 4532.1 4541.0 3871.7 3998.0 4004.4 4009.7 4041.6 5411.8 5437.8 5475.6 5502.6 5521.5 5829.3 5850.3 5852.4 5856.5 5858.9 5278.4 5412.2 5412.5 5438.2 5441.5 4984.1 5027.8 5028.4 5076.0 5103.1 5884.7 5899.6 5903.5 5907.1 5907.9 5704.8 5868.8 5883.5 5884.0 5893.5 5751.3 5755.1 5824.4 5824.8 5958.2 5683.9 5686.5 5691.8 5702.1 5719.4 5749.5 5792.9 5803.4 5811.0 5819.4 5533.3 5639.6 5706.9 5780.0 5820.2 5687.8 5720.5 5723.7 5724.7 5736.3 5452.3 5649.6 5666.0 5668.6 5668.7 5670.9 5751.6 5810.5 5813.5 5858.7 5560.5 5576.3 5604.6 5618.5 5618.9 04:04:01 INFO - PROCESS | 29229 | 04:04:01 INFO - PROCESS | 29229 | [#1] ext.html Cycles:180 Average:43909.44 Median:34895.24 stddev:70789.52 (202.9%) stddev-sans-first:70913.19 04:04:01 INFO - PROCESS | 29229 | Values: 534.4 548.9 549.6 558.4 601.2 28238.6 28873.3 35449.8 35880.8 36238.5 38432.4 38726.8 38759.0 38943.8 39015.4 8531.2 8648.4 8730.5 8755.0 8756.8 8458.4 8531.4 8598.5 8604.1 8611.9 8595.7 8673.6 8678.8 8689.1 8693.0 8529.5 8587.0 8617.7 8623.5 8625.1 8445.2 8525.5 8585.5 8585.9 8590.6 38892.4 39109.6 39332.2 39472.0 39632.2 3274.4 3352.2 3369.1 3538.1 3817.7 29434.0 29546.0 29881.4 29951.0 30034.2 3742.8 3775.7 3813.4 3848.8 3874.4 254515.0 254552.0 255764.0 256298.0 257635.0 50802.2 52159.5 55958.2 56171.4 56177.0 61667.7 63038.4 63225.7 63289.7 63351.7 15615.8 15701.9 15716.3 15809.2 15854.5 11899.2 11970.3 12002.5 12009.2 12023.3 18346.4 18449.8 18645.8 18688.3 19108.0 7160.1 7272.8 7350.6 7354.9 7429.4 915.1 1010.5 1031.4 1062.1 1090.4 28548.6 28607.7 29010.6 29176.3 29376.3 1090.5 1113.0 1157.2 1168.3 1169.9 15045.8 15092.8 15095.8 15099.0 15386.3 308087.0 309785.0 310976.0 313470.0 313513.0 50404.0 50858.0 51109.5 51112.2 51209.5 1331.6 1340.2 1342.0 1350.1 1360.4 27725.9 28473.6 28549.4 28657.4 29110.4 45532.5 45902.8 46024.1 46047.0 46053.3 241302.0 241438.0 242682.8 243162.0 243245.0 31763.0 31864.8 31914.8 32533.2 32591.7 39129.8 41921.2 42587.4 43164.6 43449.8 32756.0 32990.5 33024.8 33489.2 33625.7 32915.0 35311.3 36805.4 37003.4 37504.4 34893.5 34897.0 35161.0 35171.5 35863.0 29753.0 31476.0 32685.0 32722.7 32768.7 24295.4 24998.9 26187.5 26299.9 26448.9 04:04:01 INFO - PROCESS | 29229 | 04:04:01 INFO - PROCESS | 29229 | [#2] jquery.html Cycles:180 Average:25873.64 Median:2426.17 stddev:76694.38 (3161.1%) stddev-sans-first:76885.60 04:04:01 INFO - PROCESS | 29229 | Values: 361.9 363.8 364.5 367.3 367.7 16469.0 17428.2 17654.8 18124.8 18391.0 12937.6 12977.6 13402.4 13701.4 17048.9 5007.5 5107.8 5126.6 5127.1 5128.1 4589.0 5080.0 5104.2 5107.6 5110.1 4715.5 5029.5 5088.1 5135.9 5219.8 5120.2 5132.4 5152.5 5153.8 5170.7 5076.2 5233.8 5260.9 5264.3 5267.7 13248.2 13290.7 13298.1 16982.6 19748.4 2022.6 2096.6 2118.8 2162.7 2205.9 9476.9 9717.5 9821.8 12259.8 12376.9 1771.2 1773.8 1797.9 1828.9 1834.9 61548.0 64692.7 65729.7 66023.0 66145.3 10136.9 10321.5 10353.5 10370.5 10387.8 13488.0 13522.6 13542.3 13618.7 13650.7 7566.9 7656.8 7707.2 7839.6 7937.9 7432.7 7440.1 7458.5 7480.4 7618.0 4414.7 4426.0 4426.8 4439.8 4490.5 1824.6 1850.3 1854.9 1855.5 1855.9 437.7 457.2 458.6 460.7 468.9 10665.5 11231.2 11277.4 11300.9 11414.3 451.5 457.4 459.4 465.8 471.0 6108.5 6228.2 6267.7 6281.3 6328.6 180786.0 500616.0 501607.0 507795.0 541039.0 80290.0 81321.0 82292.6 83238.6 83958.6 505.8 507.8 509.5 509.9 511.7 10247.9 10500.6 10555.3 10669.4 10776.2 35311.0 35753.5 36332.3 36495.0 36536.8 47793.3 48151.0 48795.0 49077.0 49506.8 9523.4 9681.6 9713.5 10103.7 10147.8 11873.8 12017.5 13308.6 18036.2 18067.4 10959.8 14287.6 14489.3 14864.7 15000.4 14014.2 14116.0 14177.4 14488.8 14636.8 14509.3 14742.9 15586.2 15651.8 15686.8 9596.0 9723.4 9740.5 9835.7 9839.5 10499.9 11565.0 14605.5 14966.0 15103.8 04:04:01 INFO - PROCESS | 29229 | 04:04:01 INFO - PROCESS | 29229 | [#3] mootools.html Cycles:180 Average:5313.58 Median:6199.99 stddev:2702.28 (43.6%) stddev-sans-first:2694.05 04:04:01 INFO - PROCESS | 29229 | Values: 1424.3 1446.0 1608.5 1774.4 1812.5 7217.4 7325.5 7436.4 7572.8 7619.3 7444.6 7446.2 7503.9 7527.7 7558.7 2297.5 2313.2 2322.7 2323.2 2339.1 2289.7 2334.1 2340.1 2341.3 2341.9 2210.5 2277.2 2278.5 2298.9 2318.4 2282.9 2306.4 2343.1 2344.2 2347.9 2308.8 2328.3 2344.0 2356.8 2360.4 7508.2 7607.9 7628.2 7672.7 7673.5 4643.2 4686.9 4696.8 4700.0 4700.3 2977.0 3015.8 3095.9 3099.1 3137.3 952.9 955.6 957.2 965.8 977.6 10462.7 10483.2 10515.4 10545.5 10546.4 4134.8 4217.9 4241.0 4263.5 4265.1 8470.2 8509.0 8511.4 8513.2 8513.9 4619.7 4658.8 4671.8 4677.4 4698.9 4243.5 4259.4 4304.4 4305.7 4316.7 3338.4 3344.1 3368.9 3382.6 3388.8 1029.9 1049.6 1067.8 1068.6 1090.1 1055.3 1088.1 1096.0 1263.7 1361.2 7044.4 7064.3 7107.5 7120.8 7141.9 1117.5 1121.8 1124.5 1133.8 1142.0 5659.9 5693.2 5731.0 5750.5 5754.3 8879.2 8917.3 9143.3 9190.2 9193.5 8971.4 8981.1 8997.2 9008.5 9109.6 6869.7 7097.9 7116.2 7120.2 7196.1 7150.4 7194.4 7218.9 7229.3 7236.9 7842.6 8102.3 8114.7 8123.2 8232.8 8055.6 8274.5 8284.0 8386.7 8433.7 6530.7 6567.9 6569.4 6635.4 6651.6 7318.7 7321.7 7324.1 7332.9 7352.5 6315.7 6867.7 6985.2 7082.3 7099.3 6568.1 6595.7 6628.2 6698.6 6703.8 6486.9 6546.9 6563.4 6574.2 6601.6 6170.2 6171.6 6177.5 6204.3 6223.3 6109.1 6161.6 6195.7 6205.0 6262.2 04:04:01 INFO - PROCESS | 29229 | 04:04:01 INFO - PROCESS | 29229 | [#4] prototype.html Cycles:180 Average:5575.31 Median:6274.67 stddev:1706.73 (27.2%) stddev-sans-first:1665.71 04:04:01 INFO - PROCESS | 29229 | Values: 342.3 343.3 348.8 350.8 351.2 6268.5 6282.5 6286.5 6305.7 6402.6 6182.4 6226.7 6258.7 6280.8 6328.2 4807.1 4810.5 4819.7 4838.1 4841.3 4820.1 4840.0 4853.8 4854.7 4855.4 4689.1 4796.8 4807.2 4807.3 4816.0 4779.4 4783.1 4794.8 4798.9 4800.5 4150.1 4163.5 4166.0 4172.2 4174.0 6063.5 6104.5 6108.4 6115.7 6127.7 5415.5 5543.3 5549.6 5551.8 5602.1 6760.4 6771.2 6772.5 6773.2 6773.7 4588.3 4592.8 4598.1 4603.3 4604.7 6734.9 6904.2 6917.6 6921.5 6986.2 4522.8 4536.7 4538.8 4540.8 4546.3 4594.8 4596.3 4607.3 4619.0 4624.6 5429.9 5439.8 5445.8 5455.4 5459.5 6616.2 6737.8 6741.3 6748.3 6749.4 1594.0 1594.8 1598.0 1598.0 1604.9 1057.4 1069.9 1070.3 1081.6 1081.8 6209.7 6248.9 6248.9 6265.5 6267.9 6824.6 6859.7 6883.8 6886.0 6902.6 6144.5 6244.2 6284.9 6290.4 6293.9 3737.4 3746.6 3759.0 3760.8 3761.4 6935.7 6947.4 6949.9 6952.4 6978.5 6950.1 6995.7 7008.6 7023.5 7042.6 7043.8 7068.8 7079.3 7081.4 7098.7 7000.3 7005.8 7013.7 7014.8 7028.2 6807.3 6847.1 6927.8 6942.6 6951.3 7043.4 7054.0 7063.3 7065.1 7065.9 4664.3 4678.8 4691.4 4694.3 4704.3 6878.1 7013.5 7018.4 7036.5 7077.4 6752.1 6772.6 6793.8 6796.7 6804.4 6759.8 6765.4 6776.7 6778.4 6781.0 6579.8 6635.4 6680.5 6683.9 6701.5 6785.9 6818.4 6827.0 6834.6 6849.0 6649.9 6659.5 6661.2 6670.6 6688.1 04:04:01 INFO - PROCESS | 29229 | 04:04:01 INFO - PROCESS | 29229 | [#5] yui.html Cycles:180 Average:13692.66 Median:44857.66 stddev:15396.92 (34.3%) stddev-sans-first:15409.43 04:04:01 INFO - PROCESS | 29229 | Values: 749.5 806.8 833.8 859.2 873.3 13582.3 13822.9 13844.2 13880.2 13922.1 13242.1 13334.3 13336.9 13342.3 13356.2 811.0 826.2 831.0 843.5 848.1 845.3 849.2 853.1 853.1 853.9 884.9 893.5 895.4 895.4 896.2 882.5 882.7 883.3 883.9 884.1 650.2 653.6 653.9 654.0 655.2 13699.6 13707.9 13818.8 13853.8 13880.3 12128.7 12165.2 12421.3 12522.7 12936.2 10534.7 10611.3 10616.6 10698.4 10756.2 3610.5 3620.6 3654.2 3669.2 3680.4 45236.5 45859.0 45893.3 45911.0 46002.5 9125.7 9611.7 10026.2 10104.5 10284.6 17259.5 17899.4 18026.3 18055.1 18097.7 7501.5 7641.3 7712.0 7735.4 7887.4 4567.2 4605.1 4607.3 4625.2 4635.4 3134.7 3142.9 3159.5 3161.8 3165.6 560.5 573.2 579.3 582.7 589.0 563.8 649.6 690.8 692.2 705.8 8561.9 8610.0 8677.2 8709.2 8758.1 472.7 521.6 546.5 553.3 666.2 4022.2 4094.2 4513.8 4541.8 4565.6 56017.7 57458.1 57682.0 57891.1 57994.6 48110.5 48513.0 48862.3 48924.8 48945.3 42615.6 44205.0 44383.6 44593.3 44747.8 8413.8 8452.0 8617.8 8797.8 8891.4 34583.3 34670.3 35301.1 35476.0 35509.6 40585.4 41217.0 44535.6 44580.8 44967.6 11412.3 11469.1 11573.7 11583.2 11762.0 11748.9 11760.5 12017.4 12581.0 12867.1 11675.2 11961.9 11970.6 12058.5 12336.4 11452.7 11844.7 11864.8 11981.5 12383.2 10964.8 11577.6 11598.9 11981.9 12006.6 10157.6 10253.2 10347.3 10425.9 10893.1 9332.9 9584.5 9736.3 10134.3 10222.0 04:04:01 INFO - PROCESS | 29229 | -------- Summary: end -------- 04:04:01 INFO - PROCESS | 29229 | 04:04:02 INFO - TEST-INFO | 29229: exit 0 04:04:02 INFO - TEST-OK | dromaeo_css | took 1110611ms 04:04:02 INFO - TEST-START | kraken 04:04:02 INFO - operating with platform_type : linux_ 04:04:02 INFO - Initialising browser for kraken test... 04:04:02 INFO - TEST-INFO | started process 29472 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpNc2iJG/profile http://localhost:47110/getInfo.html) 04:04:06 INFO - PROCESS | 29472 | __metrics Screen width/height:1600/1200 04:04:06 INFO - PROCESS | 29472 | colorDepth:24 04:04:06 INFO - PROCESS | 29472 | Browser inner width/height: 1024/697 04:04:06 INFO - PROCESS | 29472 | __metrics 04:04:06 INFO - PROCESS | 29472 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 04:04:07 INFO - TEST-INFO | 29472: exit 0 04:04:07 INFO - Browser initialized. 04:04:07 INFO - Running cycle 1/1 for kraken test... 04:04:07 INFO - TEST-INFO | started process 29592 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpNc2iJG/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:04:19 INFO - PROCESS | 29592 | RSS: Main: 150331392 04:04:19 INFO - PROCESS | 29592 | 04:04:21 INFO - PROCESS | 29592 | Cycle 1(1): loaded http://localhost:47110/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47110/tests/kraken/driver.html?testName=audio-beat-detection) 04:04:22 INFO - PROCESS | 29592 | RSS: Main: 152268800 04:04:22 INFO - PROCESS | 29592 | 04:04:25 INFO - PROCESS | 29592 | Cycle 1(1): loaded http://localhost:47110/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47110/tests/kraken/driver.html?testName=audio-dft) 04:04:25 INFO - PROCESS | 29592 | RSS: Main: 150548480 04:04:25 INFO - PROCESS | 29592 | 04:04:28 INFO - PROCESS | 29592 | Cycle 1(1): loaded http://localhost:47110/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47110/tests/kraken/driver.html?testName=audio-fft) 04:04:29 INFO - PROCESS | 29592 | RSS: Main: 146685952 04:04:29 INFO - PROCESS | 29592 | 04:04:31 INFO - PROCESS | 29592 | Cycle 1(1): loaded http://localhost:47110/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47110/tests/kraken/driver.html?testName=audio-oscillator) 04:04:31 INFO - PROCESS | 29592 | RSS: Main: 146640896 04:04:31 INFO - PROCESS | 29592 | 04:04:33 INFO - PROCESS | 29592 | Cycle 1(1): loaded http://localhost:47110/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47110/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:04:34 INFO - PROCESS | 29592 | RSS: Main: 147390464 04:04:34 INFO - PROCESS | 29592 | 04:04:37 INFO - PROCESS | 29592 | Cycle 1(1): loaded http://localhost:47110/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47110/tests/kraken/driver.html?testName=imaging-darkroom) 04:04:38 INFO - PROCESS | 29592 | RSS: Main: 147075072 04:04:38 INFO - PROCESS | 29592 | 04:04:41 INFO - PROCESS | 29592 | Cycle 1(1): loaded http://localhost:47110/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47110/tests/kraken/driver.html?testName=imaging-desaturate) 04:04:42 INFO - PROCESS | 29592 | RSS: Main: 148344832 04:04:42 INFO - PROCESS | 29592 | 04:04:44 INFO - PROCESS | 29592 | Cycle 1(1): loaded http://localhost:47110/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47110/tests/kraken/driver.html?testName=json-parse-financial) 04:04:45 INFO - PROCESS | 29592 | RSS: Main: 148684800 04:04:45 INFO - PROCESS | 29592 | 04:04:47 INFO - PROCESS | 29592 | Cycle 1(1): loaded http://localhost:47110/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47110/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:04:47 INFO - PROCESS | 29592 | RSS: Main: 149344256 04:04:47 INFO - PROCESS | 29592 | 04:04:49 INFO - PROCESS | 29592 | Cycle 1(1): loaded http://localhost:47110/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47110/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:04:50 INFO - PROCESS | 29592 | RSS: Main: 148590592 04:04:50 INFO - PROCESS | 29592 | 04:04:52 INFO - PROCESS | 29592 | Cycle 1(1): loaded http://localhost:47110/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47110/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:04:52 INFO - PROCESS | 29592 | RSS: Main: 149405696 04:04:52 INFO - PROCESS | 29592 | 04:04:55 INFO - PROCESS | 29592 | Cycle 1(1): loaded http://localhost:47110/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47110/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:04:55 INFO - PROCESS | 29592 | RSS: Main: 149315584 04:04:55 INFO - PROCESS | 29592 | 04:04:58 INFO - PROCESS | 29592 | Cycle 1(1): loaded http://localhost:47110/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47110/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:04:59 INFO - PROCESS | 29592 | RSS: Main: 148217856 04:04:59 INFO - PROCESS | 29592 | 04:05:00 INFO - PROCESS | 29592 | Cycle 1(1): loaded http://localhost:47110/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47110/tests/kraken/driver.html?testName=ai-astar) 04:05:01 INFO - PROCESS | 29592 | RSS: Main: 148307968 04:05:01 INFO - PROCESS | 29592 | 04:05:01 INFO - PROCESS | 29592 | __start_tp_report 04:05:01 INFO - PROCESS | 29592 | _x_x_mozilla_page_load 04:05:01 INFO - PROCESS | 29592 | _x_x_mozilla_page_load_details 04:05:01 INFO - PROCESS | 29592 | |i|pagename|runs| 04:05:01 INFO - PROCESS | 29592 | |0;ai-astar;111;105;110;110;112;122;111;110;111;111 04:05:01 INFO - PROCESS | 29592 | |1;audio-beat-detection;160;127;151;171;130;152;152;131;169;132 04:05:01 INFO - PROCESS | 29592 | |2;audio-dft;164;163;162;162;162;162;162;163;169;162 04:05:01 INFO - PROCESS | 29592 | |3;audio-fft;73;76;73;74;75;72;134;71;71;76 04:05:01 INFO - PROCESS | 29592 | |4;audio-oscillator;96;96;143;96;96;96;145;96;96;137 04:05:01 INFO - PROCESS | 29592 | |5;imaging-gaussian-blur;109;110;110;110;110;110;109;110;110;110 04:05:01 INFO - PROCESS | 29592 | |6;imaging-darkroom;146;148;146;147;146;146;147;147;146;147 04:05:01 INFO - PROCESS | 29592 | |7;imaging-desaturate;97;96;96;96;97;97;96;96;95;96 04:05:01 INFO - PROCESS | 29592 | |8;json-parse-financial;60;63;63;62;61;59;58;60;57;60 04:05:01 INFO - PROCESS | 29592 | |9;json-stringify-tinderbox;67;68;67;69;68;68;68;69;69;67 04:05:01 INFO - PROCESS | 29592 | |10;stanford-crypto-aes;86;90;87;87;88;86;95;100;87;91 04:05:01 INFO - PROCESS | 29592 | |11;stanford-crypto-ccm;116;117;118;121;128;128;124;135;125;132 04:05:01 INFO - PROCESS | 29592 | |12;stanford-crypto-pbkdf2;146;153;151;154;164;144;151;154;155;151 04:05:01 INFO - PROCESS | 29592 | |13;stanford-crypto-sha256-iterative;67;69;69;70;69;77;67;69;69;70 04:05:01 INFO - PROCESS | 29592 | __end_tp_report 04:05:01 INFO - PROCESS | 29592 | __start_cc_report 04:05:01 INFO - PROCESS | 29592 | _x_x_mozilla_cycle_collect,715 04:05:01 INFO - PROCESS | 29592 | __end_cc_report 04:05:01 INFO - PROCESS | 29592 | __startTimestamp1451477101030__endTimestamp 04:05:01 INFO - PROCESS | 29592 | 04:05:01 INFO - PROCESS | 29592 | ------- Summary: start ------- 04:05:01 INFO - PROCESS | 29592 | Number of tests: 14 04:05:01 INFO - PROCESS | 29592 | 04:05:01 INFO - PROCESS | 29592 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.00 stddev:4.22 (3.8%) stddev-sans-first:4.47 04:05:01 INFO - PROCESS | 29592 | Values: 111.0 105.0 110.0 110.0 112.0 122.0 111.0 110.0 111.0 111.0 04:05:01 INFO - PROCESS | 29592 | 04:05:01 INFO - PROCESS | 29592 | [#1] audio-beat-detection Cycles:10 Average:147.50 Median:152.00 stddev:16.54 (10.9%) stddev-sans-first:16.91 04:05:01 INFO - PROCESS | 29592 | Values: 160.0 127.0 151.0 171.0 130.0 152.0 152.0 131.0 169.0 132.0 04:05:01 INFO - PROCESS | 29592 | 04:05:01 INFO - PROCESS | 29592 | [#2] audio-dft Cycles:10 Average:163.10 Median:162.50 stddev:2.18 (1.3%) stddev-sans-first:2.29 04:05:01 INFO - PROCESS | 29592 | Values: 164.0 163.0 162.0 162.0 162.0 162.0 162.0 163.0 169.0 162.0 04:05:01 INFO - PROCESS | 29592 | 04:05:01 INFO - PROCESS | 29592 | [#3] audio-fft Cycles:10 Average:79.50 Median:73.50 stddev:19.24 (26.2%) stddev-sans-first:20.26 04:05:01 INFO - PROCESS | 29592 | Values: 73.0 76.0 73.0 74.0 75.0 72.0 134.0 71.0 71.0 76.0 04:05:01 INFO - PROCESS | 29592 | 04:05:01 INFO - PROCESS | 29592 | [#4] audio-oscillator Cycles:10 Average:109.70 Median:96.00 stddev:22.15 (23.1%) stddev-sans-first:22.93 04:05:01 INFO - PROCESS | 29592 | Values: 96.0 96.0 143.0 96.0 96.0 96.0 145.0 96.0 96.0 137.0 04:05:01 INFO - PROCESS | 29592 | 04:05:01 INFO - PROCESS | 29592 | [#5] imaging-gaussian-blur Cycles:10 Average:109.80 Median:110.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 04:05:01 INFO - PROCESS | 29592 | Values: 109.0 110.0 110.0 110.0 110.0 110.0 109.0 110.0 110.0 110.0 04:05:01 INFO - PROCESS | 29592 | 04:05:01 INFO - PROCESS | 29592 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 04:05:01 INFO - PROCESS | 29592 | Values: 146.0 148.0 146.0 147.0 146.0 146.0 147.0 147.0 146.0 147.0 04:05:01 INFO - PROCESS | 29592 | 04:05:01 INFO - PROCESS | 29592 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 04:05:01 INFO - PROCESS | 29592 | Values: 97.0 96.0 96.0 96.0 97.0 97.0 96.0 96.0 95.0 96.0 04:05:01 INFO - PROCESS | 29592 | 04:05:01 INFO - PROCESS | 29592 | [#8] json-parse-financial Cycles:10 Average:60.30 Median:60.50 stddev:2.00 (3.3%) stddev-sans-first:2.12 04:05:01 INFO - PROCESS | 29592 | Values: 60.0 63.0 63.0 62.0 61.0 59.0 58.0 60.0 57.0 60.0 04:05:01 INFO - PROCESS | 29592 | 04:05:01 INFO - PROCESS | 29592 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:68.00 stddev:0.82 (1.2%) stddev-sans-first:0.78 04:05:01 INFO - PROCESS | 29592 | Values: 67.0 68.0 67.0 69.0 68.0 68.0 68.0 69.0 69.0 67.0 04:05:01 INFO - PROCESS | 29592 | 04:05:01 INFO - PROCESS | 29592 | [#10] stanford-crypto-aes Cycles:10 Average:89.70 Median:87.50 stddev:4.57 (5.2%) stddev-sans-first:4.65 04:05:01 INFO - PROCESS | 29592 | Values: 86.0 90.0 87.0 87.0 88.0 86.0 95.0 100.0 87.0 91.0 04:05:01 INFO - PROCESS | 29592 | 04:05:01 INFO - PROCESS | 29592 | [#11] stanford-crypto-ccm Cycles:10 Average:124.40 Median:126.50 stddev:6.45 (5.1%) stddev-sans-first:6.08 04:05:01 INFO - PROCESS | 29592 | Values: 116.0 117.0 118.0 121.0 128.0 128.0 124.0 135.0 125.0 132.0 04:05:01 INFO - PROCESS | 29592 | 04:05:01 INFO - PROCESS | 29592 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.30 Median:153.50 stddev:5.42 (3.5%) stddev-sans-first:5.24 04:05:01 INFO - PROCESS | 29592 | Values: 146.0 153.0 151.0 154.0 164.0 144.0 151.0 154.0 155.0 151.0 04:05:01 INFO - PROCESS | 29592 | 04:05:01 INFO - PROCESS | 29592 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:69.00 stddev:2.80 (4.1%) stddev-sans-first:2.80 04:05:01 INFO - PROCESS | 29592 | Values: 67.0 69.0 69.0 70.0 69.0 77.0 67.0 69.0 69.0 70.0 04:05:01 INFO - PROCESS | 29592 | -------- Summary: end -------- 04:05:01 INFO - PROCESS | 29592 | 04:05:01 INFO - TEST-INFO | 29592: exit 0 04:05:01 INFO - TEST-OK | kraken | took 59585ms 04:05:01 INFO - TEST-START | v8_7 04:05:01 INFO - operating with platform_type : linux_ 04:05:01 INFO - Initialising browser for v8_7 test... 04:05:01 INFO - TEST-INFO | started process 29876 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphzebp7/profile http://localhost:47110/getInfo.html) 04:05:06 INFO - PROCESS | 29876 | __metrics Screen width/height:1600/1200 04:05:06 INFO - PROCESS | 29876 | colorDepth:24 04:05:06 INFO - PROCESS | 29876 | Browser inner width/height: 1024/697 04:05:06 INFO - PROCESS | 29876 | __metrics 04:05:06 INFO - PROCESS | 29876 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 04:05:07 INFO - TEST-INFO | 29876: exit 0 04:05:07 INFO - Browser initialized. 04:05:07 INFO - Running cycle 1/1 for v8_7 test... 04:05:07 INFO - TEST-INFO | started process 29997 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphzebp7/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:05:18 INFO - PROCESS | 29997 | RSS: Main: 149712896 04:05:18 INFO - PROCESS | 29997 | 04:05:40 INFO - PROCESS | 29997 | Cycle 1(1): loaded http://localhost:47110/tests/v8_7/run.html (next: http://localhost:47110/tests/v8_7/run.html) 04:05:40 INFO - PROCESS | 29997 | RSS: Main: 146604032 04:05:40 INFO - PROCESS | 29997 | 04:05:40 INFO - PROCESS | 29997 | __start_tp_report 04:05:40 INFO - PROCESS | 29997 | _x_x_mozilla_page_load 04:05:40 INFO - PROCESS | 29997 | _x_x_mozilla_page_load_details 04:05:40 INFO - PROCESS | 29997 | |i|pagename|runs| 04:05:40 INFO - PROCESS | 29997 | |0;Richards;167.84155756965424 04:05:40 INFO - PROCESS | 29997 | |1;DeltaBlue;188.64365214110546 04:05:40 INFO - PROCESS | 29997 | |2;Encrypt;280.0336040324839 04:05:40 INFO - PROCESS | 29997 | |3;Decrypt;5202.072538860104 04:05:40 INFO - PROCESS | 29997 | |4;RayTrace;1070.6638115631692 04:05:40 INFO - PROCESS | 29997 | |5;Earley;760.4562737642585 04:05:40 INFO - PROCESS | 29997 | |6;Boyer;12444.444444444445 04:05:40 INFO - PROCESS | 29997 | |7;RegExp;26710.526315789473 04:05:40 INFO - PROCESS | 29997 | |8;Splay;553.4034311012729 04:05:40 INFO - PROCESS | 29997 | |9;NavierStokes;4835.748792270531 04:05:40 INFO - PROCESS | 29997 | __end_tp_report 04:05:40 INFO - PROCESS | 29997 | __startTimestamp1451477140643__endTimestamp 04:05:40 INFO - PROCESS | 29997 | 04:05:40 INFO - PROCESS | 29997 | ------- Summary: start ------- 04:05:40 INFO - PROCESS | 29997 | Number of tests: 10 04:05:40 INFO - PROCESS | 29997 | 04:05:40 INFO - PROCESS | 29997 | [#0] Richards Cycles:1 Average:167.84 Median:167.84 stddev:0.00 (0.0%) 04:05:40 INFO - PROCESS | 29997 | Values: 167.8 04:05:40 INFO - PROCESS | 29997 | 04:05:40 INFO - PROCESS | 29997 | [#1] DeltaBlue Cycles:1 Average:188.64 Median:188.64 stddev:0.00 (0.0%) 04:05:40 INFO - PROCESS | 29997 | Values: 188.6 04:05:40 INFO - PROCESS | 29997 | 04:05:40 INFO - PROCESS | 29997 | [#2] Encrypt Cycles:1 Average:280.03 Median:280.03 stddev:0.00 (0.0%) 04:05:40 INFO - PROCESS | 29997 | Values: 280.0 04:05:40 INFO - PROCESS | 29997 | 04:05:40 INFO - PROCESS | 29997 | [#3] Decrypt Cycles:1 Average:5202.07 Median:5202.07 stddev:0.00 (0.0%) 04:05:40 INFO - PROCESS | 29997 | Values: 5202.1 04:05:40 INFO - PROCESS | 29997 | 04:05:40 INFO - PROCESS | 29997 | [#4] RayTrace Cycles:1 Average:1070.66 Median:1070.66 stddev:0.00 (0.0%) 04:05:40 INFO - PROCESS | 29997 | Values: 1070.7 04:05:40 INFO - PROCESS | 29997 | 04:05:40 INFO - PROCESS | 29997 | [#5] Earley Cycles:1 Average:760.46 Median:760.46 stddev:0.00 (0.0%) 04:05:40 INFO - PROCESS | 29997 | Values: 760.5 04:05:40 INFO - PROCESS | 29997 | 04:05:40 INFO - PROCESS | 29997 | [#6] Boyer Cycles:1 Average:12444.44 Median:12444.44 stddev:0.00 (0.0%) 04:05:40 INFO - PROCESS | 29997 | Values: 12444.4 04:05:40 INFO - PROCESS | 29997 | 04:05:40 INFO - PROCESS | 29997 | [#7] RegExp Cycles:1 Average:26710.53 Median:26710.53 stddev:0.00 (0.0%) 04:05:40 INFO - PROCESS | 29997 | Values: 26710.5 04:05:40 INFO - PROCESS | 29997 | 04:05:40 INFO - PROCESS | 29997 | [#8] Splay Cycles:1 Average:553.40 Median:553.40 stddev:0.00 (0.0%) 04:05:40 INFO - PROCESS | 29997 | Values: 553.4 04:05:40 INFO - PROCESS | 29997 | 04:05:40 INFO - PROCESS | 29997 | [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 04:05:40 INFO - PROCESS | 29997 | Values: 4835.7 04:05:40 INFO - PROCESS | 29997 | -------- Summary: end -------- 04:05:40 INFO - PROCESS | 29997 | 04:05:41 INFO - TEST-INFO | 29997: exit 0 04:05:41 INFO - TEST-OK | v8_7 | took 39587ms 04:05:41 INFO - SUITE-END | took 1209s 04:05:41 INFO - Completed test suite (00:20:09) 04:05:41 INFO - Working with test: dromaeo_css 04:05:41 INFO - Generating results file: dromaeo_css 04:05:41 INFO - Working with test: kraken 04:05:41 INFO - Generating results file: kraken 04:05:41 INFO - javascript benchmark 04:05:41 INFO - Working with test: v8_7 04:05:41 INFO - Generating results file: v8_7 04:05:41 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:05:41 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,63,43]] 04:05:41 INFO - process_Request line: dromaeo_css 6905.26 graph.html#tests=[[72,63,43]] 04:05:41 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:05:41 INFO - process_Request line: kraken 1528.00 graph.html#tests=[[232,63,43]] 04:05:41 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:05:42 INFO - process_Request line: v8_7 21238.83 graph.html#tests=[[230,63,43]] 04:05:42 INFO - RETURN: dromaeo_css: 6905.26 04:05:42 INFO - RETURN: kraken: 1528.00 04:05:42 INFO - RETURN: v8_7: 21238.83 04:05:42 INFO - javascript benchmark 04:05:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3169.5404166395874, 3209.6790320967893, 3252.788568743033, 3262.657420970798, 3272.1001724525754, 5284.656497327671, 5373.220882971967, 5392.56859451244, 5437.809676661318, 5442.12925931851, 5348.411674715778, 5352.625316227856, 5388.515033646971, 5438.945610543893, 5455.345358556969, 4665.650076244278, 4932.605391568666, 5080.593552515808, 5087.898242035157, 5097.5667068299235, 4809.470958194595, 4970.254461830718, 4983.6013118950395, 5036.84889453317, 5048.6970781753025, 4877.122118018738, 4922.729249891255, 4938.925916111261, 4964.826231081895, 5000.024995125952, 4951.925721114186, 4952.959878425535, 4983.651144419892, 4987.975060124712, 4996.000799840047, 4843.443004054544, 4914.11545921734, 4936.827211047596, 4957.652964292501, 5014.971930754223, 5246.501582349671, 5269.235960785703, 5299.6025298102795, 5317.202419637047, 5371.221172929903, 5705.971470142665, 5736.541076713853, 5747.942520574783, 5783.132530120473, 5789.507891829199, 5729.512991395736, 5835.328937172195, 5838.766449342022, 5888.38171991939, 5900.409959004065, 5438.9394068156325, 5440.455954404607, 5444.809431669913, 5462.453754624546, 5472.315960504937, 5875.4124587541755, 5944.0, 5961.314448838352, 5965.8366618509845, 5972.4326189012845, 5672.14917762339, 5673.120666296731, 5723.627964182315, 5798.478136967693, 5851.034579294454, 5810.738516766671, 5863.472287494146, 5879.882402352015, 5881.264841894763, 5882.588218824641, 5588.580856435784, 5780.739866706008, 5836.153757705109, 5849.327327357323, 5852.824415267549, 5669.574781891375, 5712.314522257273, 5795.449432303924, 5827.184194212814, 5856.238688970492, 4519.593236608721, 4522.638188944877, 4527.660425468103, 4532.138893610204, 4541.046380260116, 3871.7483363581277, 3998.0404702871683, 4004.3993400989502, 4009.67922566194, 4041.636252737209, 5411.783528658888, 5437.809676661298, 5475.616706830484, 5502.614816962774, 5521.5306698930235, 5829.300483941955, 5850.297964244232, 5852.385499522574, 5856.472917437451, 5858.941410585925, 5278.366596008426, 5412.161115027177, 5412.512873841373, 5438.15708565173, 5441.5102640762525, 4984.053029924303, 5027.79888804451, 5028.371453568304, 5075.974620126875, 5103.055934652101, 5884.676342801102, 5899.587028908021, 5903.498202652737, 5907.05487122067, 5907.881842363128, 5704.80033198836, 5868.823935281948, 5883.499902508163, 5884.0, 5893.469587737124, 5751.281089863767, 5755.079187330007, 5824.417558244311, 5824.767009319749, 5958.225430693982, 5683.914741278802, 5686.459786320293, 5691.772329106955, 5702.059160238514, 5719.370869204467, 5749.482546570829, 5792.94207057924, 5803.39064398249, 5811.041178205549, 5819.447152520673, 5533.253010843485, 5639.6334238274385, 5706.914396284142, 5780.0, 5820.214271073351, 5687.77248910039, 5720.542356611378, 5723.656580605177, 5724.713764311851, 5736.311642602915, 5452.263944367618, 5649.576281778801, 5666.03677374839, 5668.574856885833, 5668.688222147822, 5670.858228544319, 5751.568632352674, 5810.477057064884, 5813.505852002458, 5858.7070646466655, 5560.471755183251, 5576.330840299028, 5604.579656525695, 5618.494335509824, 5618.943810562006], "unit": "score", "name": "dojo.html", "value": 5356.140520661278}, {"lowerIsBetter": false, "replicates": [534.4361698408179, 548.9039418101831, 549.5603517186253, 558.3523113188701, 601.2454369765943, 28238.57642135367, 28873.278168045785, 35449.82275088621, 35880.820595896985, 36238.45642315367, 38432.423513647314, 38726.8063659682, 38759.0, 38943.805280973625, 39015.41476877849, 8531.232189102979, 8648.43785153963, 8730.519821409851, 8754.956225218897, 8756.781080472976, 8458.3656225783, 8531.402801803877, 8598.527081010541, 8604.139586041378, 8611.870821937644, 8595.656173753043, 8673.609687564043, 8678.826423471559, 8689.131086891324, 8692.956535217316, 8529.488230706147, 8587.0, 8617.741467755977, 8623.482591044494, 8625.051244363114, 8445.1977062179, 8525.488470691716, 8585.484870907765, 8585.871211931826, 8590.613422396007, 38892.41661375053, 39109.608903910885, 39332.21335573301, 39472.0, 39632.20735585272, 3274.4433446314183, 3352.1619595101224, 3369.073504786179, 3538.115471132217, 3817.675497582709, 29434.0, 29546.0, 29881.402371952656, 29951.0, 30034.24914377136, 3742.8315725792404, 3775.6979441644603, 3813.3707938190164, 3848.807559622008, 3874.418837174418, 254515.0, 254552.0, 255764.0, 256298.0, 257635.0, 50802.23796643053, 52159.478405215465, 55958.160627590616, 56171.43828561744, 56177.0, 61667.69166154141, 63038.36961630417, 63225.683871580346, 63289.68355158195, 63351.683241583494, 15615.843841561666, 15701.921490392704, 15716.29276682552, 15809.209539522977, 15854.524364269091, 11899.167058305835, 11970.341631210367, 12002.459889305002, 12009.159358844798, 12023.338716370683, 18346.449606511826, 18449.815501845078, 18645.81354186441, 18688.34590789316, 19108.0, 7160.140783106061, 7272.8181795454475, 7350.649350649351, 7354.852902941911, 7429.442791790637, 915.0666320353336, 1010.4947526236881, 1031.4430207687765, 1062.1184416933927, 1090.4166271045112, 28548.596047987827, 28607.713922860923, 29010.564841527394, 29176.27059323534, 29376.26559336034, 1090.4711215060688, 1113.0427832064418, 1157.2015309436463, 1168.3457263932055, 1169.8713141554422, 15045.849541504445, 15092.849071509365, 15095.849041509444, 15099.0, 15386.307616157299, 308087.0, 309785.0, 310976.0, 313470.0, 313513.0, 50404.0, 50858.0, 51109.48890511048, 51112.233316499536, 51209.48790512122, 1331.5672406467936, 1340.1824886819034, 1342.0, 1350.1426594112809, 1360.3946243921557, 27725.86137069302, 28473.572896406156, 28549.42901142007, 28657.42685146244, 29110.41779164447, 45532.54467455283, 45902.770486147354, 46024.079518410115, 46047.0, 46053.30920036269, 241302.0, 241438.0, 242682.786586073, 243162.0, 243245.0, 31763.0, 31864.840675796473, 31914.840425797724, 32533.186670332485, 32591.67408325981, 39129.80435097806, 41921.16157676891, 42587.36118958156, 43164.568354317315, 43449.782751086044, 32756.0, 32990.5051424224, 33024.834875825465, 33489.162770930925, 33625.66374336225, 32915.0, 35311.29377412386, 36805.44791828178, 37003.44494832634, 37504.437433437975, 34893.47659785156, 34897.0, 35161.0, 35171.47242791309, 35863.0, 29753.0, 31476.0, 32685.0, 32722.672773271963, 32768.67231327656, 24295.392615184053, 24998.875005624854, 26187.476250475265, 26299.868500657376, 26448.8677556611], "unit": "score", "name": "ext.html", "value": 18098.22722651286}, {"lowerIsBetter": false, "replicates": [361.9493270942067, 363.7526481992246, 364.5370379617885, 367.34795737565815, 367.7095094875049, 16469.0, 17428.215730292137, 17654.82345176548, 18124.81875181248, 18391.0, 12937.611871643841, 12977.61067167984, 13402.396892139852, 13701.3834377453, 17048.914755426238, 5007.474215207405, 5107.7701503432245, 5126.641135120524, 5127.051495473349, 5128.128218202895, 4589.0363023764885, 5080.0, 5104.183330667094, 5107.616928730342, 5110.080185566597, 4715.504871988426, 5029.497050294959, 5088.1095808233595, 5135.871603209912, 5219.817306394277, 5120.206368012964, 5132.384113906336, 5152.536271735543, 5153.845384638467, 5170.689758614495, 5076.18780995039, 5233.764480598345, 5260.92108618371, 5264.341957255343, 5267.710275934822, 13248.20510769357, 13290.667733306647, 13298.069135160542, 16982.575435614086, 19748.40754777359, 2022.6258142243732, 2096.6330892093824, 2118.7881211878844, 2162.675598660198, 2205.911763529457, 9476.905230947672, 9717.514124293757, 9821.80356392868, 12259.774022597758, 12376.876231237662, 1771.1852547828014, 1773.8492228160621, 1797.9460616181536, 1828.9176987035614, 1834.9082545872652, 61548.0, 64692.67653661749, 65729.67135164341, 66023.0, 66145.3385466144, 10136.898631013742, 10321.535530901128, 10353.534090965924, 10370.481475926174, 10387.792244155075, 13488.0, 13522.594322170155, 13542.255175965416, 13618.727625447635, 13650.726985460435, 7566.886496702554, 7656.808579785438, 7707.190744971809, 7839.608019598997, 7937.920620793833, 7432.702691892372, 7440.069991251094, 7458.5151965122095, 7480.438967077491, 7618.047744031996, 4414.690971631955, 4426.0, 4426.75652839097, 4439.778011099432, 4490.50604433512, 1824.5529845187748, 1850.2506484873647, 1854.8887066776037, 1855.5116381203252, 1855.8608104392224, 437.65862627150875, 457.2455448509985, 458.5712358944398, 460.716659254556, 468.861685802689, 10665.453509241117, 11231.157663175294, 11277.379744113989, 11300.94349528247, 11414.258073225214, 451.50560136650313, 457.38938517079407, 459.36836849332167, 465.8113464047066, 470.9576138147583, 6108.511319094462, 6228.22147231596, 6267.71795269214, 6281.277653069864, 6328.557001009885, 180786.0, 500616.0, 501607.0, 507795.0, 541039.0, 80290.0, 81321.0, 82292.58853705693, 83238.58380708058, 83958.58020709857, 505.7547089661511, 507.76642744338017, 509.5006893244567, 509.8750806052541, 511.71599762132377, 10247.897521024694, 10500.579976800842, 10555.261131721012, 10669.41318227505, 10776.245662803529, 35311.0, 35753.46369804403, 36332.27335453329, 36495.0, 36536.81731591325, 47793.28310075282, 48151.0, 48795.0, 49077.01845963132, 49506.75246623744, 9523.380980236263, 9681.564329605044, 9713.514324283617, 10103.696889093339, 10147.797044059225, 11873.82189267179, 12017.4592143352, 13308.600741977756, 18036.18837152305, 18067.367642132464, 10959.835602466128, 14287.57137285883, 14489.347979341166, 14864.702705946036, 15000.399984000518, 14014.229217393142, 14116.01187916836, 14177.43290268419, 14488.763430707044, 14636.780448293071, 14509.347079381665, 14742.926285368505, 15586.207586127286, 15651.843481565495, 15686.843131568538, 9596.0, 9723.416595004323, 9740.512974351112, 9835.655752048644, 9839.5080245986, 10499.895001049892, 11565.0, 14605.488807891672, 14966.0, 15103.773443398139], "unit": "score", "name": "jquery.html", "value": 7938.108875322573}, {"lowerIsBetter": false, "replicates": [1424.3234463629776, 1445.9927700361498, 1608.4933246027501, 1774.3789673614244, 1812.5378028602713, 7217.422606191505, 7325.5238409503345, 7436.442266829989, 7572.772816815497, 7619.276168763959, 7444.590547519884, 7446.218147094558, 7503.924960750391, 7527.698892044312, 7558.697652093923, 2297.4715815362433, 2313.2250696016854, 2322.7212734471887, 2323.152049501928, 2339.076064954342, 2289.713785776778, 2334.054707843326, 2340.1341503643675, 2341.3210169050953, 2341.8711970841596, 2210.469487323047, 2277.2257432472957, 2278.453171238899, 2298.9310320690406, 2318.4435735423544, 2282.942926426836, 2306.4003359126664, 2343.109618345035, 2344.2146880794867, 2347.8826058696995, 2308.7806658367426, 2328.2898715891647, 2343.9882800585888, 2356.8468049576722, 2360.44529535559, 7508.211637778064, 7607.923920760777, 7628.237176282383, 7672.731454399125, 7673.50122242058, 4643.16423043852, 4686.929696054562, 4696.7651617419, 4699.976500117512, 4700.318453824177, 2977.0, 3015.834129122897, 3095.8761649534217, 3099.1012606344134, 3137.30979184579, 952.8999455057258, 955.603424578806, 957.2055194188806, 965.7826988927505, 977.6480467031934, 10462.686119416354, 10483.161347092215, 10515.369077855354, 10545.472726363729, 10546.419946902917, 4134.793260336971, 4217.873463796091, 4241.024564350217, 4263.531011588785, 4265.1043280910735, 8470.195331443503, 8508.957455212685, 8511.404201705946, 8513.233808957224, 8513.914860851437, 4619.745913974696, 4658.813647454132, 4671.766411679402, 4677.368555245066, 4698.859034228979, 4243.511996120424, 4259.403683484315, 4304.418903448057, 4305.655547556251, 4316.719413238129, 3338.382399256097, 3344.080377896098, 3368.865245390206, 3382.644822293673, 3388.8136152511356, 1029.8815636201932, 1049.6063976008998, 1067.791780602775, 1068.636663534402, 1090.0897750378522, 1055.2560444886337, 1088.0697004061572, 1095.9616413425492, 1263.677762170659, 1361.1764882246296, 7044.436445084381, 7064.32888875556, 7107.466939979522, 7120.786376408716, 7141.89287160693, 1117.536222467667, 1121.7644294698205, 1124.4883577972003, 1133.7562424078862, 1141.9543218271176, 5659.943400566024, 5693.231413759172, 5731.0, 5750.539956803445, 5754.280714910637, 8879.200871921557, 8917.331200160013, 9143.268538516902, 9190.172884440433, 9193.494357810385, 8971.372003959661, 8981.05698901619, 8997.190252877272, 9008.549572521348, 9109.635614575343, 6869.690863911136, 7097.89353159703, 7116.181639111568, 7120.2167761547225, 7196.136463624399, 7150.42796576283, 7194.4244460442, 7218.927810721825, 7229.349358557755, 7236.855262894818, 7842.568658723825, 8102.270795628186, 8114.715984940499, 8123.18768123183, 8232.83534329322, 8055.597220139087, 8274.503529788231, 8284.0, 8386.664533418594, 8433.746987590383, 6530.706118224596, 6567.9014814776865, 6569.408753212234, 6635.402813746786, 6651.567648102858, 7318.707251709872, 7321.670524826289, 7324.08448943882, 7332.926670733438, 7352.485326027127, 6315.747370105144, 6867.725290988305, 6985.1617805863625, 7082.2917708230825, 7099.290070992859, 6568.14614100183, 6595.67021648906, 6628.171478565179, 6698.631575263407, 6703.798886033427, 6486.902696459651, 6546.869062618625, 6563.3764792344655, 6574.211094668671, 6601.636909969998, 6170.167027451416, 6171.5988460751705, 6177.4749146321265, 6204.255489341127, 6223.284322302993, 6109.144719739151, 6161.630302181883, 6195.721192546256, 6205.03821907605, 6262.185915830913], "unit": "score", "name": "mootools.html", "value": 4396.410138911061}, {"lowerIsBetter": false, "replicates": [342.2692551402755, 343.3236524047625, 348.794211415265, 350.8070561191344, 351.174739362498, 6268.529860260482, 6282.52881033922, 6286.465650419714, 6305.747770089202, 6402.615843049421, 6182.443580077792, 6226.657533835637, 6258.718357673904, 6280.811575652738, 6328.177336946191, 4807.110684523357, 4810.542998415162, 4819.686720363183, 4838.056578967103, 4841.3222148899185, 4820.060088282787, 4839.951600483985, 4853.757312134379, 4854.660173787836, 4855.441624213207, 4689.062187562484, 4796.808127674889, 4807.1587472192405, 4807.278908163768, 4816.0, 4779.402574678165, 4783.067301876135, 4794.784234709429, 4798.880027999328, 4800.519948005206, 4150.128473020669, 4163.500379954395, 4166.0, 4172.165566886635, 4173.979130104361, 6063.454289114001, 6104.542159338067, 6108.389161083901, 6115.724792384353, 6127.662978536223, 5415.539679127279, 5543.251661025791, 5549.639273447254, 5551.77792888284, 5602.1036634138345, 6760.357766012221, 6771.153605799275, 6772.458203343721, 6773.221079575863, 6773.72905083796, 4588.311753237008, 4592.816287348502, 4598.0803839232285, 4603.286490593963, 4604.746738929358, 6734.8989765153565, 6904.240533541307, 6917.550359226634, 6921.4808889333, 6986.161660600709, 4522.819087236539, 4536.705114167568, 4538.795754191069, 4540.841070562515, 4546.318052292183, 4594.816207351735, 4596.287575425815, 4607.262837945913, 4618.97690511552, 4624.583787459145, 5429.891402171935, 5439.782408703687, 5445.754941027663, 5455.427180146107, 5459.508644222039, 6616.239132499728, 6737.764178253738, 6741.325867413219, 6748.325167483213, 6749.358810912955, 1593.9920300398423, 1594.8484893935058, 1597.9600509987342, 1598.0, 1604.8635865951292, 1057.376148072641, 1069.8609180806445, 1070.325694812279, 1081.6106201767357, 1081.7836432713489, 6209.65846878417, 6248.875022499525, 6248.9062664060075, 6265.498760099181, 6267.9373206268265, 6824.624645644438, 6859.725610975505, 6883.827904302432, 6886.0, 6902.551334163263, 6144.508439324933, 6244.219472565929, 6284.937150628527, 6290.433860952537, 6293.874122517524, 3737.439384092408, 3746.550413950289, 3759.0414444316525, 3760.793156376371, 3761.4169803680475, 6935.6878940447805, 6947.444204463631, 6949.89575156373, 6952.409045231212, 6978.546394484342, 6950.0964874567, 6995.720171193096, 7008.614526201006, 7023.543469674557, 7042.647867606702, 7043.823904402431, 7068.787936361918, 7079.327463890922, 7081.433485321162, 7098.716051357991, 7000.334968178015, 7005.824854378681, 7013.684384202621, 7014.807482728051, 7028.156621205488, 6807.251202367636, 6847.1098757163245, 6927.792166235021, 6942.583444993316, 6951.304869513008, 7043.436525078084, 7053.964730176316, 7063.328983746535, 7065.0815393998455, 7065.894011589933, 4664.3469914211355, 4678.789454474489, 4691.390119284471, 4694.2723877799, 4704.341392205162, 6878.140232470942, 7013.544119632412, 7018.403435708022, 7036.472264580075, 7077.433805295461, 6752.054712340375, 6772.5936443813525, 6793.762218322335, 6796.660166991532, 6804.4216241620015, 6759.763408280686, 6765.42493888025, 6776.728930842908, 6778.356056174655, 6781.0, 6579.769708060195, 6635.4359879410795, 6680.465562754871, 6683.899741503784, 6701.497387695845, 6785.864282714417, 6818.352256535622, 6826.965865170642, 6834.589924604539, 6849.0, 6649.933500664932, 6659.500537459807, 6661.16735408074, 6670.599764014175, 6688.130543029375], "unit": "score", "name": "prototype.html", "value": 5010.976414585752}, {"lowerIsBetter": false, "replicates": [749.4978364495787, 806.8386322735454, 833.7540425574455, 859.1709000814213, 873.3406278259913, 13582.25297608631, 13822.861771382295, 13844.169349839016, 13880.236586987725, 13922.095063820845, 13242.139260948032, 13334.266615336153, 13336.933315333434, 13342.332883355843, 13356.198628082322, 811.0267678785466, 826.2481142160635, 830.995845020774, 843.5149788871393, 848.0840692052568, 845.2899564365931, 849.1932663969224, 853.0616322045731, 853.0999795216045, 853.8975322961253, 884.880541126946, 893.4639216470099, 895.3777124898198, 895.4492986813095, 896.2426749396749, 882.5190271302156, 882.7131182365757, 883.3021912689001, 883.9116088391141, 884.1423818895661, 650.1645385679426, 653.630698655263, 653.8626888353451, 653.9574927629689, 655.1515787055727, 13699.589012329647, 13707.931460342734, 13818.792718109236, 13853.792193117111, 13880.305984700826, 12128.696782580506, 12165.20926139807, 12421.254724716455, 12522.686932826753, 12936.223826570436, 10534.736631584195, 10611.31026483276, 10616.628418005334, 10698.411587362692, 10756.247062705614, 3610.494530765695, 3620.584986216308, 3654.2326111516613, 3669.2294618130227, 3680.3743363627977, 45236.54763452389, 45859.0, 45893.31160032569, 45911.0, 46002.53997460016, 9125.680601178927, 9611.711648650551, 10026.248031397674, 10104.545295461721, 10284.588616455258, 17259.482215533553, 17899.37352192667, 18026.2789488419, 18055.09724513769, 18097.728534072, 7501.549907005598, 7641.312281894656, 7711.961440192876, 7735.381169506426, 7887.369010479148, 4567.1550763108935, 4605.148047611203, 4607.262837945913, 4625.167469855391, 4635.3742244797195, 3134.717875391226, 3142.9371412571622, 3159.526071089309, 3161.826099564546, 3165.6201255849446, 560.4983539731917, 573.191799562623, 579.3077272659216, 582.7232064769286, 589.0251633546487, 563.8252141836044, 649.6264647827423, 690.8203866994517, 692.2143367278176, 705.8180281830136, 8561.871571926426, 8609.956950215334, 8677.1756683115, 8709.172628600272, 8758.080401557872, 472.7021976155069, 521.6374619639314, 546.5327145290798, 553.2531083037948, 666.2005593288073, 4022.235775202702, 4094.201630682048, 4513.796879140446, 4541.772911354419, 4565.589096981287, 56017.719911400185, 57458.13812792811, 57682.0, 57891.131633025536, 57994.55013624693, 48110.51889481131, 48513.0, 48862.26706599404, 48924.75537622289, 48945.26582101271, 42615.57384426178, 44205.0, 44383.55616443859, 44593.33110003352, 44747.77626111849, 8413.789655258668, 8452.0, 8617.82764344722, 8797.780055498664, 8891.377603567687, 34583.308333832676, 34670.30659386849, 35301.1174720634, 35476.0, 35509.64490355063, 40585.391219132325, 41217.0, 44535.55464445314, 44580.77709611431, 44967.55032449633, 11412.31526108436, 11469.139814514112, 11573.652790416301, 11583.247088939192, 11762.0, 11748.88251117478, 11760.470778815145, 12017.399130043288, 12581.0, 12867.099303048697, 11675.241109327866, 11961.880381196077, 11970.581029664268, 12058.457369418222, 12336.383180841101, 11452.656420307405, 11844.703882402664, 11864.76270474603, 11981.460834262654, 12383.19509231897, 10964.780704385708, 11577.594784182513, 11598.88401115978, 11981.880181198076, 12006.579769708018, 10157.593696252363, 10253.231007674603, 10347.27569070158, 10425.895741042492, 10893.128549715846, 9332.860007099764, 9584.468327472747, 9736.264063189625, 10134.290599657954, 10222.0], "unit": "score", "name": "yui.html", "value": 6394.6550996721735}], "name": "dromaeo_css", "value": 6905.255522704199}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 105.0, 110.0, 110.0, 112.0, 122.0, 111.0, 110.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 111.3}, {"lowerIsBetter": true, "replicates": [160.0, 127.0, 151.0, 171.0, 130.0, 152.0, 152.0, 131.0, 169.0, 132.0], "unit": "score", "name": "audio-beat-detection", "value": 147.5}, {"lowerIsBetter": true, "replicates": [164.0, 163.0, 162.0, 162.0, 162.0, 162.0, 162.0, 163.0, 169.0, 162.0], "unit": "score", "name": "audio-dft", "value": 163.1}, {"lowerIsBetter": true, "replicates": [73.0, 76.0, 73.0, 74.0, 75.0, 72.0, 134.0, 71.0, 71.0, 76.0], "unit": "score", "name": "audio-fft", "value": 79.5}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 143.0, 96.0, 96.0, 96.0, 145.0, 96.0, 96.0, 137.0], "unit": "score", "name": "audio-oscillator", "value": 109.7}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0, 110.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.8}, {"lowerIsBetter": true, "replicates": [146.0, 148.0, 146.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 146.6}, {"lowerIsBetter": true, "replicates": [97.0, 96.0, 96.0, 96.0, 97.0, 97.0, 96.0, 96.0, 95.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.2}, {"lowerIsBetter": true, "replicates": [60.0, 63.0, 63.0, 62.0, 61.0, 59.0, 58.0, 60.0, 57.0, 60.0], "unit": "score", "name": "json-parse-financial", "value": 60.3}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 67.0, 69.0, 68.0, 68.0, 68.0, 69.0, 69.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.0}, {"lowerIsBetter": true, "replicates": [86.0, 90.0, 87.0, 87.0, 88.0, 86.0, 95.0, 100.0, 87.0, 91.0], "unit": "score", "name": "stanford-crypto-aes", "value": 89.7}, {"lowerIsBetter": true, "replicates": [116.0, 117.0, 118.0, 121.0, 128.0, 128.0, 124.0, 135.0, 125.0, 132.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.4}, {"lowerIsBetter": true, "replicates": [146.0, 153.0, 151.0, 154.0, 164.0, 144.0, 151.0, 154.0, 155.0, 151.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 152.3}, {"lowerIsBetter": true, "replicates": [67.0, 69.0, 69.0, 70.0, 69.0, 77.0, 67.0, 69.0, 69.0, 70.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 69.6}], "name": "kraken", "value": 1528.0}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.84155756965424], "unit": "score", "name": "Richards", "value": 210.32931599999992}, {"lowerIsBetter": false, "replicates": [188.64365214110546], "unit": "score", "name": "DeltaBlue", "value": 350.49151800000004}, {"lowerIsBetter": false, "replicates": [280.0336040324839], "unit": "score", "name": "Encrypt", "value": 950.5323509999997}, {"lowerIsBetter": false, "replicates": [5202.072538860104], "unit": "score", "name": "Decrypt", "value": 51.16825996015938}, {"lowerIsBetter": false, "replicates": [1070.6638115631692], "unit": "score", "name": "RayTrace", "value": 691.1497260000002}, {"lowerIsBetter": false, "replicates": [760.4562737642585], "unit": "score", "name": "Earley", "value": 876.398845}, {"lowerIsBetter": false, "replicates": [12444.444444444445], "unit": "score", "name": "Boyer", "value": 53.555062500000005}, {"lowerIsBetter": false, "replicates": [26710.526315789473], "unit": "score", "name": "RegExp", "value": 34.105842364532016}, {"lowerIsBetter": false, "replicates": [553.4034311012729], "unit": "score", "name": "Splay", "value": 147.25423699999993}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 21238.83101697635}]} 04:05:42 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,43]]", "result": "21238.83"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,43]]", "result": "1528.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,43]]", "result": "6905.26"}}} 04:05:42 INFO - Return code: 0 04:05:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 04:05:42 INFO - # TBPL SUCCESS # 04:05:42 INFO - Running post-action listener: _resource_record_post_action 04:05:42 INFO - Running post-run listener: _resource_record_post_run 04:05:43 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 8192; Write bytes: 556584960; Read time: 24; Write time: 545612 04:05:43 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 235364352; Read time: 0; Write time: 313552 04:05:43 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 8192; Write bytes: 321220608; Read time: 24; Write time: 232060 04:05:43 INFO - Running post-run listener: _upload_blobber_files 04:05:43 INFO - Blob upload gear active. 04:05:43 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 04:05:43 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 04:05:43 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-Inbound', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 04:05:43 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-Inbound -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 04:05:43 INFO - (blobuploader) - INFO - Open directory for files ... 04:05:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:05:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:05:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:05:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:05:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:05:44 INFO - (blobuploader) - INFO - Done attempting. 04:05:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:05:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:05:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:05:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:05:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:05:45 INFO - (blobuploader) - INFO - Done attempting. 04:05:45 INFO - (blobuploader) - INFO - Iteration through files over. 04:05:45 INFO - Return code: 0 04:05:45 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 04:05:45 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 04:05:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3fc36e107c6893f2ee4708132679c99fab2657af5d19abed506d5efb294dd65d7a1142b9270a26fe6e1d1ace9325475978ee6e61b542ab97198df2577a25f9fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/99f88e3f9611a0719ae06156a4aa3f7e9140f6e09f260ea31579aa3692aaaa16f669077c0e916828794edaf70bbe0f1cd998fbe889e834ce68becfda97715234"} 04:05:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 04:05:45 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 04:05:45 INFO - Contents: 04:05:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3fc36e107c6893f2ee4708132679c99fab2657af5d19abed506d5efb294dd65d7a1142b9270a26fe6e1d1ace9325475978ee6e61b542ab97198df2577a25f9fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/99f88e3f9611a0719ae06156a4aa3f7e9140f6e09f260ea31579aa3692aaaa16f669077c0e916828794edaf70bbe0f1cd998fbe889e834ce68becfda97715234"} 04:05:45 INFO - Copying logs to upload dir... 04:05:45 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1278.350679 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2015-12-30 04:05:44.982133) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-30 04:05:44.986435) ========= 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=801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3fc36e107c6893f2ee4708132679c99fab2657af5d19abed506d5efb294dd65d7a1142b9270a26fe6e1d1ace9325475978ee6e61b542ab97198df2577a25f9fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/99f88e3f9611a0719ae06156a4aa3f7e9140f6e09f260ea31579aa3692aaaa16f669077c0e916828794edaf70bbe0f1cd998fbe889e834ce68becfda97715234"} build_url:https://queue.taskcluster.net/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011434 build_url: 'https://queue.taskcluster.net/v1/task/EZCSH6q0Tt6b03fCvVHRhw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3fc36e107c6893f2ee4708132679c99fab2657af5d19abed506d5efb294dd65d7a1142b9270a26fe6e1d1ace9325475978ee6e61b542ab97198df2577a25f9fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/99f88e3f9611a0719ae06156a4aa3f7e9140f6e09f260ea31579aa3692aaaa16f669077c0e916828794edaf70bbe0f1cd998fbe889e834ce68becfda97715234"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-30 04:05:45.012878) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 04:05:45.013348) ========= 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=801756c90a5e5cfe63934219000001c7-1451474778.929857-1834322170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004510 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 04:05:45.096679) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-30 04:05:45.097115) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-30 04:05:45.097418) ========= ========= Total master_lag: 0.28 =========