builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-064 starttime: 1451918669.7 results: success (0) buildid: 20160104043005 builduid: d76d07f0516f4d488cf519f1b7d88dce revision: ae7f6845ecc6cededdc9fafbf48dc4ce5270fb79 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:29.703719) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:29.704185) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:29.704551) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006326 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:29.728140) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:29.728450) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:29.742483) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:29.742829) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004574 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:29.792469) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:29.792786) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:29.793168) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:29.793477) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-04 06:44:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 216M=0s 2016-01-04 06:44:30 (216 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104526 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:29.910807) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:29.911219) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022244 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:29.962343) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:29.962801) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev ae7f6845ecc6cededdc9fafbf48dc4ce5270fb79 --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 ae7f6845ecc6cededdc9fafbf48dc4ce5270fb79 --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=a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-04 06:44:30,403 truncating revision to first 12 chars 2016-01-04 06:44:30,403 Setting DEBUG logging. 2016-01-04 06:44:30,403 attempt 1/10 2016-01-04 06:44:30,403 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/ae7f6845ecc6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-04 06:44:30,647 unpacking tar archive at: mozilla-inbound-ae7f6845ecc6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.548824 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:30.525153) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:30.525752) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:30.534450) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:30.534741) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-04 06:44:30.535145) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-01-04 06:44:30.535458) ========= /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=a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:44:31 INFO - MultiFileLogger online at 20160104 06:44:31 in /builds/slave/test-pgo 06:44:31 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 06:44:31 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 06:44:31 INFO - {'append_to_log': False, 06:44:31 INFO - 'base_work_dir': '/builds/slave/test-pgo', 06:44:31 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 06:44:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 06:44:31 INFO - 'branch': 'Mozilla-Inbound', 06:44:31 INFO - 'buildbot_json_path': 'buildprops.json', 06:44:31 INFO - 'config_files': ('talos/linux_config.py',), 06:44:31 INFO - 'default_actions': ('clobber', 06:44:31 INFO - 'read-buildbot-config', 06:44:31 INFO - 'download-and-extract', 06:44:31 INFO - 'populate-webroot', 06:44:31 INFO - 'create-virtualenv', 06:44:31 INFO - 'install', 06:44:31 INFO - 'run-tests'), 06:44:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:44:31 INFO - 'download_minidump_stackwalk': True, 06:44:31 INFO - 'download_symbols': 'ondemand', 06:44:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:44:31 INFO - 'tooltool.py': '/tools/tooltool.py', 06:44:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:44:31 INFO - '/tools/misc-python/virtualenv.py')}, 06:44:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:44:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:44:31 INFO - 'installer_path': 'installer.exe', 06:44:31 INFO - 'log_level': 'info', 06:44:31 INFO - 'log_name': 'talos', 06:44:31 INFO - 'log_to_console': True, 06:44:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:44:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:44:31 INFO - 'opt_config_files': (), 06:44:31 INFO - 'pip_index': False, 06:44:31 INFO - 'sps_profile': False, 06:44:31 INFO - 'sps_profile_interval': 0, 06:44:31 INFO - 'suite': 'dromaeojs-e10s', 06:44:31 INFO - 'system_bits': '32', 06:44:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:44:31 INFO - 'title': 'talos-linux64-ix-064', 06:44:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:44:31 INFO - 'use_talos_json': True, 06:44:31 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 06:44:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:44:31 INFO - 'work_dir': 'build'} 06:44:31 INFO - ##### 06:44:31 INFO - ##### Running clobber step. 06:44:31 INFO - ##### 06:44:31 INFO - Running pre-action listener: _resource_record_pre_action 06:44:31 INFO - Running main action method: clobber 06:44:31 INFO - rmtree: /builds/slave/test-pgo/build 06:44:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 06:44:31 INFO - Running post-action listener: _resource_record_post_action 06:44:31 INFO - ##### 06:44:31 INFO - ##### Running read-buildbot-config step. 06:44:31 INFO - ##### 06:44:31 INFO - Running pre-action listener: _resource_record_pre_action 06:44:31 INFO - Running main action method: read_buildbot_config 06:44:31 INFO - Using buildbot properties: 06:44:31 INFO - { 06:44:31 INFO - "project": "", 06:44:31 INFO - "product": "firefox", 06:44:31 INFO - "script_repo_revision": "production", 06:44:31 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 06:44:31 INFO - "repository": "", 06:44:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 06:44:31 INFO - "buildid": "20160104043005", 06:44:31 INFO - "pgo_build": "True", 06:44:31 INFO - "basedir": "/builds/slave/test-pgo", 06:44:31 INFO - "buildnumber": 285, 06:44:31 INFO - "slavename": "talos-linux64-ix-064", 06:44:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 06:44:31 INFO - "platform": "ubuntu64_hw", 06:44:31 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 06:44:31 INFO - "branch": "mozilla-inbound", 06:44:31 INFO - "repo_path": "integration/mozilla-inbound", 06:44:31 INFO - "revision": "ae7f6845ecc6cededdc9fafbf48dc4ce5270fb79", 06:44:31 INFO - "stage_platform": "linux64-pgo", 06:44:31 INFO - "builduid": "d76d07f0516f4d488cf519f1b7d88dce", 06:44:31 INFO - "slavebuilddir": "test" 06:44:31 INFO - } 06:44:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 06:44:31 INFO - Running post-action listener: _resource_record_post_action 06:44:31 INFO - ##### 06:44:31 INFO - ##### Running download-and-extract step. 06:44:31 INFO - ##### 06:44:31 INFO - Running pre-action listener: _resource_record_pre_action 06:44:31 INFO - Running main action method: download_and_extract 06:44:31 INFO - mkdir: /builds/slave/test-pgo/build/tests 06:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:44:31 INFO - https://queue.taskcluster.net/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 06:44:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/test_packages.json 06:44:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/test_packages.json 06:44:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 06:44:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 06:44:33 INFO - Downloaded 1302 bytes. 06:44:33 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 06:44:33 INFO - Using the following test package requirements: 06:44:33 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 06:44:33 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 06:44:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 06:44:33 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 06:44:33 INFO - u'jsshell-linux-x86_64.zip'], 06:44:33 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 06:44:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 06:44:33 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 06:44:33 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 06:44:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 06:44:33 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 06:44:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 06:44:33 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 06:44:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 06:44:33 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 06:44:33 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 06:44:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 06:44:33 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:44:33 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')]} 06:44:33 INFO - https://queue.taskcluster.net/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:44:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 06:44:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 06:44:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/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 06:44:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/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 06:44:36 INFO - Downloaded 23198169 bytes. 06:44:36 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 06:44:36 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 06:44:36 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 06:44:37 INFO - Return code: 0 06:44:37 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 06:44:37 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')]} 06:44:37 INFO - https://queue.taskcluster.net/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:44:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 06:44:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 06:44:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/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 06:44:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/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 06:44:46 INFO - Downloaded 23198169 bytes. 06:44:46 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 06:44:46 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 06:44:46 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 06:44:47 INFO - Return code: 0 06:44:47 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')]} 06:44:47 INFO - https://queue.taskcluster.net/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:44:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 06:44:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 06:44:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/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 06:44:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/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 06:44:48 INFO - Downloaded 11132285 bytes. 06: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 06:44:48 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 06: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 06:44:48 INFO - Return code: 0 06: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')]} 06:44:48 INFO - https://queue.taskcluster.net/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:44:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 06:44:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 06:44:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/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 06:44:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/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 06:44:50 INFO - Downloaded 54983874 bytes. 06:44:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 06:44:50 INFO - mkdir: /builds/slave/test-pgo/properties 06:44:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 06:44:50 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 06:44:50 INFO - Contents: 06:44:50 INFO - build_url:https://queue.taskcluster.net/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 06:44:50 INFO - Running post-action listener: _resource_record_post_action 06:44:50 INFO - Running post-action listener: set_extra_try_arguments 06:44:50 INFO - ##### 06:44:50 INFO - ##### Running populate-webroot step. 06:44:50 INFO - ##### 06:44:50 INFO - Running pre-action listener: _resource_record_pre_action 06:44:50 INFO - Running main action method: populate_webroot 06:44:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:44:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:44:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:44:50 INFO - '--tppagecycles', 06:44:50 INFO - '7'], 06:44:50 INFO - 'tests': ['tsvgm']}}, 06:44:50 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 06:44:50 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 06:44:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:44:50 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 06:44:50 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 06:44:50 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 06:44:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:44:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:44:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:44:50 INFO - 'talos_options': ['--e10s'], 06:44:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:44:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:44:50 INFO - 'tests': ['damp', 'tps']}, 06:44:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:44:50 INFO - 'talos_options': ['--e10s'], 06:44:50 INFO - 'tests': ['damp', 'tps']}, 06:44:50 INFO - 'g3': {'tests': ['dromaeo_dom']}, 06:44:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:44:50 INFO - 'other': {'tests': ['a11yr', 06:44:50 INFO - 'ts_paint', 06:44:50 INFO - 'tpaint', 06:44:50 INFO - 'sessionrestore', 06:44:50 INFO - 'sessionrestore_no_auto_restore']}, 06:44:50 INFO - 'other-e10s': {'talos_options': ['--e10s'], 06:44:50 INFO - 'tests': ['a11yr', 06:44:50 INFO - 'ts_paint', 06:44:50 INFO - 'tpaint', 06:44:50 INFO - 'sessionrestore', 06:44:50 INFO - 'sessionrestore_no_auto_restore']}, 06:44:50 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 06:44:50 INFO - 'tests': ['a11yr', 06:44:50 INFO - 'ts_paint', 06:44:50 INFO - 'tpaint', 06:44:50 INFO - 'sessionrestore', 06:44:50 INFO - 'sessionrestore_no_auto_restore']}, 06:44:50 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 06:44:50 INFO - 'tests': ['a11yr', 06:44:50 INFO - 'ts_paint', 06:44:50 INFO - 'tpaint', 06:44:50 INFO - 'sessionrestore', 06:44:50 INFO - 'sessionrestore_no_auto_restore']}, 06:44:50 INFO - 'other_l64': {'tests': ['a11yr', 06:44:50 INFO - 'ts_paint', 06:44:50 INFO - 'tpaint', 06:44:50 INFO - 'sessionrestore', 06:44:50 INFO - 'sessionrestore_no_auto_restore']}, 06:44:50 INFO - 'other_nol64': {'tests': ['a11yr', 06:44:50 INFO - 'ts_paint', 06:44:50 INFO - 'tpaint', 06:44:50 INFO - 'sessionrestore', 06:44:50 INFO - 'sessionrestore_no_auto_restore']}, 06:44:50 INFO - 'svgr': {'tests': ['tsvgx', 06:44:50 INFO - 'tsvgr_opacity', 06:44:50 INFO - 'tart', 06:44:50 INFO - 'tscrollx', 06:44:50 INFO - 'cart']}, 06:44:50 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 06:44:50 INFO - 'tests': ['tsvgx', 06:44:50 INFO - 'tsvgr_opacity', 06:44:50 INFO - 'tart', 06:44:50 INFO - 'tscrollx', 06:44:50 INFO - 'cart']}, 06:44:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:44:50 INFO - 'tests': ['tp5o']}, 06:44:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:44:50 INFO - 'talos_options': ['--e10s'], 06:44:50 INFO - 'tests': ['tp5o']}, 06:44:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:44:50 INFO - 'talos_options': ['--xperf_path', 06:44:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:44:50 INFO - 'tests': ['tp5n']}, 06:44:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:44:50 INFO - 'talos_options': ['--e10s', 06:44:50 INFO - '--xperf_path', 06:44:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:44:50 INFO - 'tests': ['tp5n']}}, 06:44:50 INFO - 'talos.zip': {'path': '', 06:44:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:44:50 INFO - Running post-action listener: _resource_record_post_action 06:44:50 INFO - ##### 06:44:50 INFO - ##### Running create-virtualenv step. 06:44:50 INFO - ##### 06:44:50 INFO - Running pre-action listener: _resource_record_pre_action 06:44:50 INFO - Running main action method: create_virtualenv 06:44:50 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 06:44:50 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 06:44:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 06:44:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:44:50 INFO - Using real prefix '/usr' 06:44:50 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 06:44:51 INFO - Installing distribute.............................................................................................................................................................................................done. 06:44:53 INFO - Installing pip.................done. 06:44:53 INFO - Return code: 0 06:44:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 06: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')]} 06:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:44:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06: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')]} 06:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:44:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06: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 0x7fa941d1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a87f0>, '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 0x18142d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1814760>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', '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 06: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 06: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 06:44:53 INFO - Using env: {'DISPLAY': ':0', 06:44:53 INFO - 'HOME': '/home/cltbld', 06:44:53 INFO - 'LANG': 'en_US.UTF-8', 06:44:53 INFO - 'LANGUAGE': 'en_US:en', 06:44:53 INFO - 'LOGNAME': 'cltbld', 06:44:53 INFO - 'MAIL': '/var/mail/cltbld', 06:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:44:53 INFO - 'MOZ_NO_REMOTE': '1', 06:44:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:44:53 INFO - 'NO_EM_RESTART': '1', 06:44:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:44:53 INFO - 'PWD': '/builds/slave/test-pgo', 06:44:53 INFO - 'SHELL': '/bin/bash', 06:44:53 INFO - 'SHLVL': '1', 06:44:53 INFO - 'TERM': 'linux', 06:44:53 INFO - 'TMOUT': '86400', 06:44:53 INFO - 'USER': 'cltbld', 06:44:53 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', 06:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:44:53 INFO - '_': '/tools/buildbot/bin/python'} 06:44:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:44:53 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 06:44:53 INFO - Cleaning up... 06:44:53 INFO - Return code: 0 06:44:53 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 06: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')]} 06:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:44:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06: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')]} 06:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:44:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06: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', '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 0x7fa941d1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a87f0>, '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 0x18142d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1814760>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', '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 06: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', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 06: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 psutil>=0.7.1 06:44:53 INFO - Using env: {'DISPLAY': ':0', 06:44:53 INFO - 'HOME': '/home/cltbld', 06:44:53 INFO - 'LANG': 'en_US.UTF-8', 06:44:53 INFO - 'LANGUAGE': 'en_US:en', 06:44:53 INFO - 'LOGNAME': 'cltbld', 06:44:53 INFO - 'MAIL': '/var/mail/cltbld', 06:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:44:53 INFO - 'MOZ_NO_REMOTE': '1', 06:44:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:44:53 INFO - 'NO_EM_RESTART': '1', 06:44:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:44:53 INFO - 'PWD': '/builds/slave/test-pgo', 06:44:53 INFO - 'SHELL': '/bin/bash', 06:44:53 INFO - 'SHLVL': '1', 06:44:53 INFO - 'TERM': 'linux', 06:44:53 INFO - 'TMOUT': '86400', 06:44:53 INFO - 'USER': 'cltbld', 06:44:53 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', 06:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:44:53 INFO - '_': '/tools/buildbot/bin/python'} 06:44:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:44:53 INFO - Downloading/unpacking psutil>=0.7.1 06:44:53 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 06:44:53 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 06:44:53 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 06:44:53 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 06:44:53 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 06:44:53 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 06:44:55 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 06:44:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 06:44:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 06:44:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:44:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:44:55 INFO - Installing collected packages: psutil 06:44:55 INFO - Running setup.py install for psutil 06:44:55 INFO - building 'psutil._psutil_linux' extension 06:44:55 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 06:44:55 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 06:44:55 INFO - building 'psutil._psutil_posix' extension 06:44:55 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 06:44:55 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 06:44:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:44:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:44:55 INFO - Successfully installed psutil 06:44:55 INFO - Cleaning up... 06:44:55 INFO - Return code: 0 06:44:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 06:44:55 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')]} 06:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:44:55 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')]} 06:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:44:55 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 0x7fa941d1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a87f0>, '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 0x18142d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1814760>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', '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 06:44:55 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 06:44:55 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 06:44:55 INFO - Using env: {'DISPLAY': ':0', 06:44:55 INFO - 'HOME': '/home/cltbld', 06:44:55 INFO - 'LANG': 'en_US.UTF-8', 06:44:55 INFO - 'LANGUAGE': 'en_US:en', 06:44:55 INFO - 'LOGNAME': 'cltbld', 06:44:55 INFO - 'MAIL': '/var/mail/cltbld', 06:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:44:55 INFO - 'MOZ_NO_REMOTE': '1', 06:44:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:44:55 INFO - 'NO_EM_RESTART': '1', 06:44:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:44:55 INFO - 'PWD': '/builds/slave/test-pgo', 06:44:55 INFO - 'SHELL': '/bin/bash', 06:44:55 INFO - 'SHLVL': '1', 06:44:55 INFO - 'TERM': 'linux', 06:44:55 INFO - 'TMOUT': '86400', 06:44:55 INFO - 'USER': 'cltbld', 06:44:55 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', 06:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:44:55 INFO - '_': '/tools/buildbot/bin/python'} 06:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:44:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06: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 06: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 06: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 06: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 06: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 06: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 06:44:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:44:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 06:44:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:44:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 06:44:57 INFO - Installing collected packages: mozsystemmonitor 06:44:57 INFO - Running setup.py install for mozsystemmonitor 06:44:57 INFO - Successfully installed mozsystemmonitor 06:44:57 INFO - Cleaning up... 06:44:57 INFO - Return code: 0 06:44:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 06:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:44:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:44:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:44:57 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 0x7fa941d1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a87f0>, '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 0x18142d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1814760>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', '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 06:44:57 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 06:44:57 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 06:44:57 INFO - Using env: {'DISPLAY': ':0', 06:44:57 INFO - 'HOME': '/home/cltbld', 06:44:57 INFO - 'LANG': 'en_US.UTF-8', 06:44:57 INFO - 'LANGUAGE': 'en_US:en', 06:44:57 INFO - 'LOGNAME': 'cltbld', 06:44:57 INFO - 'MAIL': '/var/mail/cltbld', 06:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:44:57 INFO - 'MOZ_NO_REMOTE': '1', 06:44:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:44:57 INFO - 'NO_EM_RESTART': '1', 06:44:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:44:57 INFO - 'PWD': '/builds/slave/test-pgo', 06:44:57 INFO - 'SHELL': '/bin/bash', 06:44:57 INFO - 'SHLVL': '1', 06:44:57 INFO - 'TERM': 'linux', 06:44:57 INFO - 'TMOUT': '86400', 06:44:57 INFO - 'USER': 'cltbld', 06:44:57 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', 06:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:44:57 INFO - '_': '/tools/buildbot/bin/python'} 06:44:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:44:58 INFO - Downloading/unpacking blobuploader==1.2.4 06:44:58 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 06:44:58 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 06:44:58 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 06:44:58 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 06:44:58 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 06:44:58 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 06:44:59 INFO - Downloading blobuploader-1.2.4.tar.gz 06:44:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 06:44:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:44:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06: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 06: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 06: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 06: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 06: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 06: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 06:45:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 06:45:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 06:45:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06: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 06: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 06: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 06: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 06: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 06: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 06:45:00 INFO - Downloading docopt-0.6.1.tar.gz 06:45:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 06:45:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 06:45:00 INFO - Installing collected packages: blobuploader, requests, docopt 06:45:00 INFO - Running setup.py install for blobuploader 06:45:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:45:00 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 06:45:00 INFO - Running setup.py install for requests 06:45:01 INFO - Running setup.py install for docopt 06:45:01 INFO - Successfully installed blobuploader requests docopt 06:45:01 INFO - Cleaning up... 06:45:01 INFO - Return code: 0 06:45:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:45:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:45:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:45:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:45:01 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 0x7fa941d1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a87f0>, '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 0x18142d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1814760>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', '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 06:45:01 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 06:45:01 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 06:45:01 INFO - Using env: {'DISPLAY': ':0', 06:45:01 INFO - 'HOME': '/home/cltbld', 06:45:01 INFO - 'LANG': 'en_US.UTF-8', 06:45:01 INFO - 'LANGUAGE': 'en_US:en', 06:45:01 INFO - 'LOGNAME': 'cltbld', 06:45:01 INFO - 'MAIL': '/var/mail/cltbld', 06:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:45:01 INFO - 'MOZ_NO_REMOTE': '1', 06:45:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:45:01 INFO - 'NO_EM_RESTART': '1', 06:45:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:45:01 INFO - 'PWD': '/builds/slave/test-pgo', 06:45:01 INFO - 'SHELL': '/bin/bash', 06:45:01 INFO - 'SHLVL': '1', 06:45:01 INFO - 'TERM': 'linux', 06:45:01 INFO - 'TMOUT': '86400', 06:45:01 INFO - 'USER': 'cltbld', 06:45:01 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', 06:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:45:01 INFO - '_': '/tools/buildbot/bin/python'} 06:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:45:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:45:01 INFO - Running setup.py (path:/tmp/pip-fJ3rUc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:45:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:45:01 INFO - Running setup.py (path:/tmp/pip-4XwPDH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:45:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:45:01 INFO - Running setup.py (path:/tmp/pip-Op39DT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:45:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:45:01 INFO - Running setup.py (path:/tmp/pip-Gt1xu3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:45:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 06:45:01 INFO - Running setup.py (path:/tmp/pip-uymVlA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 06:45:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:45:01 INFO - Running setup.py (path:/tmp/pip-j4Cc3h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:45:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:45:01 INFO - Running setup.py (path:/tmp/pip-HFAEd3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:45:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:45:01 INFO - Running setup.py (path:/tmp/pip-T1qu81-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:45:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 06:45:01 INFO - Running setup.py (path:/tmp/pip-6NSDQE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 06:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 06:45:02 INFO - Running setup.py (path:/tmp/pip-Y9xa5W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 06:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:45:02 INFO - Running setup.py (path:/tmp/pip-9I6mqb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:45:02 INFO - Running setup.py (path:/tmp/pip-wo6i9P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:45:02 INFO - Running setup.py (path:/tmp/pip-q_YEvQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:45:02 INFO - Running setup.py (path:/tmp/pip-yvXPrB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:45:02 INFO - Running setup.py (path:/tmp/pip-qOvIk6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 06:45:02 INFO - Running setup.py (path:/tmp/pip-oTUSmH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 06:45:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 06:45:02 INFO - Running setup.py (path:/tmp/pip-8sJMxC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 06:45:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:45:02 INFO - Running setup.py install for manifestparser 06:45:02 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 06:45:02 INFO - Running setup.py install for mozcrash 06:45:02 INFO - Running setup.py install for mozdebug 06:45:02 INFO - Running setup.py install for mozdevice 06:45:03 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 06:45:03 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 06:45:03 INFO - Running setup.py install for mozfile 06:45:03 INFO - Running setup.py install for mozhttpd 06:45:03 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 06:45:03 INFO - Running setup.py install for mozinfo 06:45:03 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 06:45:03 INFO - Running setup.py install for mozInstall 06:45:03 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 06:45:03 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 06:45:03 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 06:45:03 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 06:45:03 INFO - Running setup.py install for mozleak 06:45:03 INFO - Running setup.py install for mozlog 06:45:03 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 06:45:03 INFO - Running setup.py install for moznetwork 06:45:03 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 06:45:03 INFO - Running setup.py install for mozprocess 06:45:03 INFO - Running setup.py install for mozprofile 06:45:03 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 06:45:03 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 06:45:03 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 06:45:03 INFO - Running setup.py install for mozrunner 06:45:04 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 06:45:04 INFO - Running setup.py install for mozscreenshot 06:45:04 INFO - Running setup.py install for moztest 06:45:04 INFO - Running setup.py install for mozversion 06:45:04 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 06:45:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:45:04 INFO - Cleaning up... 06:45:04 INFO - Return code: 0 06:45:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:45:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:45:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:45:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:45:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:45:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:45:04 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 0x7fa941d1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a87f0>, '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 0x18142d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1814760>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', '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 06:45:04 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 06:45:04 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 06:45:04 INFO - Using env: {'DISPLAY': ':0', 06:45:04 INFO - 'HOME': '/home/cltbld', 06:45:04 INFO - 'LANG': 'en_US.UTF-8', 06:45:04 INFO - 'LANGUAGE': 'en_US:en', 06:45:04 INFO - 'LOGNAME': 'cltbld', 06:45:04 INFO - 'MAIL': '/var/mail/cltbld', 06:45:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:45:04 INFO - 'MOZ_NO_REMOTE': '1', 06:45:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:45:04 INFO - 'NO_EM_RESTART': '1', 06:45:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:45:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:45:04 INFO - 'PWD': '/builds/slave/test-pgo', 06:45:04 INFO - 'SHELL': '/bin/bash', 06:45:04 INFO - 'SHLVL': '1', 06:45:04 INFO - 'TERM': 'linux', 06:45:04 INFO - 'TMOUT': '86400', 06:45:04 INFO - 'USER': 'cltbld', 06:45:04 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', 06:45:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:45:04 INFO - '_': '/tools/buildbot/bin/python'} 06:45:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:45:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:45:04 INFO - Running setup.py (path:/tmp/pip-Te9ExT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:45:04 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)) 06:45:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:45:04 INFO - Running setup.py (path:/tmp/pip-U7xvdw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:45:04 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)) 06:45:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:45:04 INFO - Running setup.py (path:/tmp/pip-0N8Cff-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:45:04 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)) 06:45:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:45:04 INFO - Running setup.py (path:/tmp/pip-c9PZKc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:45:04 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)) 06:45:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 06:45:04 INFO - Running setup.py (path:/tmp/pip-6FRB0P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 06: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)) 06:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:45:05 INFO - Running setup.py (path:/tmp/pip-zbqt25-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:45:05 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)) 06:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:45:05 INFO - Running setup.py (path:/tmp/pip-oGfDp3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:45:05 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)) 06:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:45:05 INFO - Running setup.py (path:/tmp/pip-ohnswE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:45:05 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)) 06:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 06:45:05 INFO - Running setup.py (path:/tmp/pip-Ytspey-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 06:45:05 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)) 06:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 06:45:05 INFO - Running setup.py (path:/tmp/pip-EniyVo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 06:45:05 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)) 06:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:45:05 INFO - Running setup.py (path:/tmp/pip-t_1yEB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:45:05 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)) 06:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:45:05 INFO - Running setup.py (path:/tmp/pip-M3hMFX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:45:05 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)) 06:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:45:05 INFO - Running setup.py (path:/tmp/pip-M1oeCL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:45:05 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)) 06:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:45:05 INFO - Running setup.py (path:/tmp/pip-Exj0rD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:45:05 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)) 06:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:45:05 INFO - Running setup.py (path:/tmp/pip-G2ud62-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:45:05 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)) 06:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 06:45:05 INFO - Running setup.py (path:/tmp/pip-dcXD7r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 06:45:05 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)) 06:45:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 06:45:05 INFO - Running setup.py (path:/tmp/pip-beSSIZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 06:45:05 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)) 06:45:05 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)) 06:45:05 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)) 06:45:05 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)) 06:45:05 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)) 06:45:05 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)) 06:45:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 06:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:45:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:45:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:45:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:45:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:45:07 INFO - Downloading blessings-1.5.1.tar.gz 06:45:07 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 06:45:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 06:45:07 INFO - Installing collected packages: blessings 06:45:07 INFO - Running setup.py install for blessings 06:45:07 INFO - Successfully installed blessings 06:45:07 INFO - Cleaning up... 06:45:07 INFO - Return code: 0 06:45:07 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 06:45:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 06:45:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 06:45:08 INFO - Reading from file tmpfile_stdout 06:45:08 INFO - Current package versions: 06:45:08 INFO - argparse == 1.2.1 06:45:08 INFO - blessings == 1.5.1 06:45:08 INFO - blobuploader == 1.2.4 06:45:08 INFO - docopt == 0.6.1 06:45:08 INFO - manifestparser == 1.1 06:45:08 INFO - mozInstall == 1.12 06:45:08 INFO - mozcrash == 0.16 06:45:08 INFO - mozdebug == 0.1 06:45:08 INFO - mozdevice == 0.47 06:45:08 INFO - mozfile == 1.2 06:45:08 INFO - mozhttpd == 0.7 06:45:08 INFO - mozinfo == 0.9 06:45:08 INFO - mozleak == 0.1 06:45:08 INFO - mozlog == 3.1 06:45:08 INFO - moznetwork == 0.27 06:45:08 INFO - mozprocess == 0.22 06:45:08 INFO - mozprofile == 0.28 06:45:08 INFO - mozrunner == 6.11 06:45:08 INFO - mozscreenshot == 0.1 06:45:08 INFO - mozsystemmonitor == 0.0 06:45:08 INFO - moztest == 0.7 06:45:08 INFO - mozversion == 1.4 06:45:08 INFO - psutil == 3.1.1 06:45:08 INFO - requests == 1.2.3 06:45:08 INFO - wsgiref == 0.1.2 06:45:08 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06: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')]} 06:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:45:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06: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')]} 06:45:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:45:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06: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 0x7fa941d1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a87f0>, '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 0x18142d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1814760>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', '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 06: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 06: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 06:45:08 INFO - Using env: {'DISPLAY': ':0', 06:45:08 INFO - 'HOME': '/home/cltbld', 06:45:08 INFO - 'LANG': 'en_US.UTF-8', 06:45:08 INFO - 'LANGUAGE': 'en_US:en', 06:45:08 INFO - 'LOGNAME': 'cltbld', 06:45:08 INFO - 'MAIL': '/var/mail/cltbld', 06:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:45:08 INFO - 'MOZ_NO_REMOTE': '1', 06:45:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:45:08 INFO - 'NO_EM_RESTART': '1', 06:45:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:45:08 INFO - 'PWD': '/builds/slave/test-pgo', 06:45:08 INFO - 'SHELL': '/bin/bash', 06:45:08 INFO - 'SHLVL': '1', 06:45:08 INFO - 'TERM': 'linux', 06:45:08 INFO - 'TMOUT': '86400', 06:45:08 INFO - 'USER': 'cltbld', 06:45:08 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', 06:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:45:08 INFO - '_': '/tools/buildbot/bin/python'} 06:45:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:45:08 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)) 06:45:08 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)) 06:45:08 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)) 06:45:08 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)) 06:45:08 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)) 06:45:08 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)) 06:45:08 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)) 06:45:08 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)) 06:45:08 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)) 06:45:08 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)) 06:45:08 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)) 06:45:08 INFO - Cleaning up... 06:45:08 INFO - Return code: 0 06:45:08 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 06: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')]} 06:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:45:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06: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')]} 06:45:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:45:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06: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', '--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 0x7fa941d1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a87f0>, '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 0x18142d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1814760>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', '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 06: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', '--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 06: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 --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 06:45:08 INFO - Using env: {'DISPLAY': ':0', 06:45:08 INFO - 'HOME': '/home/cltbld', 06:45:08 INFO - 'LANG': 'en_US.UTF-8', 06:45:08 INFO - 'LANGUAGE': 'en_US:en', 06:45:08 INFO - 'LOGNAME': 'cltbld', 06:45:08 INFO - 'MAIL': '/var/mail/cltbld', 06:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:45:08 INFO - 'MOZ_NO_REMOTE': '1', 06:45:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:45:08 INFO - 'NO_EM_RESTART': '1', 06:45:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:45:08 INFO - 'PWD': '/builds/slave/test-pgo', 06:45:08 INFO - 'SHELL': '/bin/bash', 06:45:08 INFO - 'SHLVL': '1', 06:45:08 INFO - 'TERM': 'linux', 06:45:08 INFO - 'TMOUT': '86400', 06:45:08 INFO - 'USER': 'cltbld', 06:45:08 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', 06:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:45:08 INFO - '_': '/tools/buildbot/bin/python'} 06:45:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:45:08 INFO - Downloading/unpacking jsonschema 06:45:08 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 06:45:08 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 06:45:08 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 06:45:08 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 06:45:08 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 06:45:08 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 06:45:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:45:10 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 06:45:10 INFO - Downloading/unpacking functools32 (from jsonschema) 06:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:45:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:45:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:45:10 INFO - Downloading functools32-3.2.3-2.tar.gz 06:45:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 06:45:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 06:45:10 INFO - warning: no files found matching '*.txt' 06:45:10 INFO - no previously-included directories found matching 'build' 06:45:10 INFO - no previously-included directories found matching 'dist' 06:45:10 INFO - no previously-included directories found matching '.git*' 06:45:10 INFO - Installing collected packages: jsonschema, functools32 06:45:10 INFO - Running setup.py install for functools32 06:45:10 INFO - warning: no files found matching '*.txt' 06:45:10 INFO - no previously-included directories found matching 'build' 06:45:10 INFO - no previously-included directories found matching 'dist' 06:45:10 INFO - no previously-included directories found matching '.git*' 06:45:10 INFO - Successfully installed jsonschema functools32 06:45:10 INFO - Cleaning up... 06:45:10 INFO - Return code: 0 06:45:10 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 06:45:10 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')]} 06:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:45:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:45:10 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')]} 06:45:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:45:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:45:10 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 0x7fa941d1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a87f0>, '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 0x18142d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1814760>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', '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 06:45:10 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 06:45:10 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 06:45:10 INFO - Using env: {'DISPLAY': ':0', 06:45:10 INFO - 'HOME': '/home/cltbld', 06:45:10 INFO - 'LANG': 'en_US.UTF-8', 06:45:10 INFO - 'LANGUAGE': 'en_US:en', 06:45:10 INFO - 'LOGNAME': 'cltbld', 06:45:10 INFO - 'MAIL': '/var/mail/cltbld', 06:45:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:45:10 INFO - 'MOZ_NO_REMOTE': '1', 06:45:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:45:10 INFO - 'NO_EM_RESTART': '1', 06:45:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:45:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:45:10 INFO - 'PWD': '/builds/slave/test-pgo', 06:45:10 INFO - 'SHELL': '/bin/bash', 06:45:10 INFO - 'SHLVL': '1', 06:45:10 INFO - 'TERM': 'linux', 06:45:10 INFO - 'TMOUT': '86400', 06:45:10 INFO - 'USER': 'cltbld', 06:45:10 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', 06:45:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:45:10 INFO - '_': '/tools/buildbot/bin/python'} 06:45:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:45:10 INFO - Downloading/unpacking flake8 06:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:45:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:45:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:45:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:45:12 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 06:45:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:45:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:45:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:45:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:45:12 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 06:45:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:45:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:45:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:45:12 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 06:45:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06: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 06: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 06: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 06: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 06: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 06: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 06:45:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 06:45:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 06:45:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:45:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:45:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:45:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:45:13 INFO - no previously-included directories found matching 'docs/_build' 06:45:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:45:13 INFO - Running setup.py install for pep8 06:45:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:45:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:45:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:45:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:45:13 INFO - no previously-included directories found matching 'docs/_build' 06:45:13 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 06:45:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:45:13 INFO - Cleaning up... 06:45:13 INFO - Return code: 0 06:45:13 INFO - Running post-action listener: _resource_record_post_action 06:45:13 INFO - Running post-action listener: _start_resource_monitoring 06:45:13 INFO - Starting resource monitoring. 06:45:13 INFO - ##### 06:45:13 INFO - ##### Running install step. 06:45:13 INFO - ##### 06:45:13 INFO - Running pre-action listener: _resource_record_pre_action 06:45:13 INFO - Running main action method: install 06:45:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 06:45:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 06:45:13 INFO - Reading from file tmpfile_stdout 06:45:13 INFO - Detecting whether we're running mozinstall >=1.0... 06:45:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 06:45:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 06:45:13 INFO - Reading from file tmpfile_stdout 06:45:13 INFO - Output received: 06:45:13 INFO - Usage: mozinstall [options] installer 06:45:13 INFO - Options: 06:45:13 INFO - -h, --help show this help message and exit 06:45:13 INFO - -d DEST, --destination=DEST 06:45:13 INFO - Directory to install application into. [default: 06:45:13 INFO - "/builds/slave/test-pgo"] 06:45:13 INFO - --app=APP Application being installed. [default: firefox] 06:45:13 INFO - mkdir: /builds/slave/test-pgo/build/application 06:45:13 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'] 06:45:13 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 06:45:29 INFO - Reading from file tmpfile_stdout 06:45:29 INFO - Output received: 06:45:29 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 06:45:29 INFO - Running post-action listener: _resource_record_post_action 06:45:29 INFO - ##### 06:45:29 INFO - ##### Running run-tests step. 06:45:29 INFO - ##### 06:45:29 INFO - Running pre-action listener: _resource_record_pre_action 06:45:29 INFO - Running main action method: run_tests 06:45:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 06:45:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 06:45:29 INFO - Python 2.7.3 06:45:29 INFO - Return code: 0 06:45:29 INFO - grabbing minidump binary from tooltool 06:45: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')]} 06:45:29 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 0x18a87f0>, '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 0x18142d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1814760>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 06:45:29 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 06:45:29 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 06:45:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:45:29 INFO - Return code: 0 06:45:29 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 06:45:29 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 06:45:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 06:45:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 06:45:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 06:45:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 06:45:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 06:45:30 INFO - Return code: 0 06:45:30 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/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 06:45:30 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/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --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 06:45:30 INFO - Using env: {'DISPLAY': ':0', 06:45:30 INFO - 'HOME': '/home/cltbld', 06:45:30 INFO - 'LANG': 'en_US.UTF-8', 06:45:30 INFO - 'LANGUAGE': 'en_US:en', 06:45:30 INFO - 'LOGNAME': 'cltbld', 06:45:30 INFO - 'MAIL': '/var/mail/cltbld', 06:45:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 06:45:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 06:45:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:45:30 INFO - 'MOZ_NO_REMOTE': '1', 06:45:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 06:45:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:45:30 INFO - 'NO_EM_RESTART': '1', 06:45:30 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:45:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:45:30 INFO - 'PWD': '/builds/slave/test-pgo', 06:45:30 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 06:45:30 INFO - 'SHELL': '/bin/bash', 06:45:30 INFO - 'SHLVL': '1', 06:45:30 INFO - 'TERM': 'linux', 06:45:30 INFO - 'TMOUT': '86400', 06:45:30 INFO - 'USER': 'cltbld', 06:45:30 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500', 06:45:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:45:30 INFO - '_': '/tools/buildbot/bin/python'} 06:45:30 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/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 06:45:30 INFO - mozversion application_buildid: 20160104043005 06:45:30 INFO - mozversion application_changeset: ae7f6845ecc6cededdc9fafbf48dc4ce5270fb79 06:45:30 INFO - mozversion application_display_name: Nightly 06:45:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:45:30 INFO - mozversion application_name: Firefox 06:45:30 INFO - mozversion application_remotingname: firefox 06:45:30 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 06:45:30 INFO - mozversion application_vendor: Mozilla 06:45:30 INFO - mozversion application_version: 46.0a1 06:45:30 INFO - mozversion platform_buildid: 20160104043005 06:45:30 INFO - mozversion platform_changeset: ae7f6845ecc6cededdc9fafbf48dc4ce5270fb79 06:45:30 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 06:45:30 INFO - mozversion platform_version: 46.0a1 06:45:30 INFO - using testdate: 1451918730 06:45:30 INFO - actual date: 1451918730 06:45:31 INFO - starting webserver on 'localhost:56791' 06:45:31 INFO - SUITE-START | Running 3 tests 06:45:31 INFO - TEST-START | dromaeo_css 06:45:31 INFO - operating with platform_type : linux_ 06:45:31 INFO - Initialising browser for dromaeo_css test... 06:45:31 INFO - TEST-INFO | started process 19725 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaJturv/profile http://localhost:56791/getInfo.html) 06:45:35 INFO - PROCESS | 19725 | __metrics Screen width/height:1600/1200 06:45:35 INFO - PROCESS | 19725 | colorDepth:24 06:45:35 INFO - PROCESS | 19725 | Browser inner width/height: 1024/697 06:45:35 INFO - PROCESS | 19725 | __metrics 06:45:36 INFO - PROCESS | 19725 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 06:45:36 INFO - TEST-INFO | 19725: exit 0 06:45:36 INFO - Browser initialized. 06:45:36 INFO - Running cycle 1/1 for dromaeo_css test... 06:45:36 INFO - TEST-INFO | started process 19856 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaJturv/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:45:48 INFO - PROCESS | 19856 | RSS: Main: 150810624 06:45:48 INFO - PROCESS | 19856 | 06:46:38 INFO - PROCESS | 19856 | 1451918798426 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] 06:48:47 INFO - PROCESS | 19856 | Cycle 1(1): loaded http://localhost:56791/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56791/tests/dromaeo/cssquery-ext.html) 06:48:48 INFO - PROCESS | 19856 | RSS: Main: 158339072 06:48:48 INFO - PROCESS | 19856 | 06:51:50 INFO - PROCESS | 19856 | Cycle 1(1): loaded http://localhost:56791/tests/dromaeo/cssquery-ext.html (next: http://localhost:56791/tests/dromaeo/cssquery-jquery.html) 06:51:51 INFO - PROCESS | 19856 | RSS: Main: 153812992 06:51:51 INFO - PROCESS | 19856 | 06:54:53 INFO - PROCESS | 19856 | Cycle 1(1): loaded http://localhost:56791/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56791/tests/dromaeo/cssquery-mootools.html) 06:54:53 INFO - PROCESS | 19856 | RSS: Main: 153501696 06:54:53 INFO - PROCESS | 19856 | 06:57:55 INFO - PROCESS | 19856 | Cycle 1(1): loaded http://localhost:56791/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56791/tests/dromaeo/cssquery-prototype.html) 06:57:56 INFO - PROCESS | 19856 | RSS: Main: 151646208 06:57:56 INFO - PROCESS | 19856 | 07:00:58 INFO - PROCESS | 19856 | Cycle 1(1): loaded http://localhost:56791/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56791/tests/dromaeo/cssquery-yui.html) 07:00:58 INFO - PROCESS | 19856 | RSS: Main: 153210880 07:00:58 INFO - PROCESS | 19856 | 07:04:01 INFO - PROCESS | 19856 | Cycle 1(1): loaded http://localhost:56791/tests/dromaeo/cssquery-yui.html (next: http://localhost:56791/tests/dromaeo/cssquery-dojo.html) 07:04:01 INFO - PROCESS | 19856 | RSS: Main: 154267648 07:04:01 INFO - PROCESS | 19856 | 07:04:01 INFO - PROCESS | 19856 | __start_tp_report 07:04:01 INFO - PROCESS | 19856 | _x_x_mozilla_page_load 07:04:01 INFO - PROCESS | 19856 | _x_x_mozilla_page_load_details 07:04:01 INFO - PROCESS | 19856 | |i|pagename|runs| 07:04:01 INFO - PROCESS | 19856 | |0;dojo.html;3161.462551366268;3232.8221947792877;3263.298390845968;3277.8199848054696;3279.721223695986;5392.73036348182;5459.853430779531;5471.589630777693;5480.150576660619;5482.80810171644;5455.345358556979;5478.233047373371;5490.203920431545;5512.5589952803775;5536.916946245799;4939.43196532398;4981.427135879384;5086.3387759591205;5205.16717325228;5243.2135179723155;5144.09978253806;5189.584833213334;5196.428392876781;5234.214867769846;5240.187770895517;4885.926711099319;4949.975250123763;4997.875053123683;5022.673526220784;5053.69677819332;4962.776675049631;4968;4969.105560999019;4975.154223781929;5016.8996620067755;4934.210526315772;4951.801927922878;4977.228529577885;4986.750662466862;5058.949410505921;5297.390800058004;5336.4129945705945;5391.649542779746;5400.378956420022;5450.618456708032;5930.436608522226;5951.137085122677;5953.613015154045;5953.940460595425;5962.25471816023;5917.082852157922;5995.880082398327;6000.279966404016;6015.368386319417;6020.6989650517735;5550.69471179085;5577.972110139464;5606.131049687305;5607.55139588836;5653.208550802891;6119.877602448015;6135.9079613805825;6139.26328840542;6147.44672979434;6151.2618485781995;5687.402822703557;5781.71091445426;5859.472647461664;5862.501687356537;5864.530837532987;5858.619189752652;5906.911396328973;5908.350081491032;5914.556408269311;5947.9702601486715;5856.970715146482;5858.941410585925;5892;5913.852153696106;5916.615419997686;5808.30300363959;5877.382874798084;5881.088431293157;5903.0260007099205;5920;4542.886427839265;4567.018091110361;4578.9771051144535;4582.564656357641;4616.884577885579;4078.9592104079175;4087.4481944937647;4096.5903409658795;4101.52832424275;4135.338345864648;5414.972925135428;5540.836424350931;5592.273004509388;5605.0751625982075;5618.438156184349;5777.768889244468;5856.736446859902;5861.88276234473;5863.179154918314;5910.7931222407005;5486.588505861996;5502.477264659851;5510.421405752419;5531.419200983839;5561.58288128392;4946.3817022872145;4949.183384741477;5026;5040.445550989388;5049.091163590516;6156.076588511759;6216.751329946842;6224.564280500322;6241.407066328691;6241.812745617548;5974.462298392991;5992.43071908168;5994.490468310242;6002.639841609517;6055.1220073090035;6058.27300723916;6133.478654314434;6144.569880108528;6162.198914141133;6215;5874.853128671817;5900.527957763283;5904;5917.467427931507;5944.5838791284195;5989.910151347821;6011.368806275278;6015;6016.2479690038745;6017.037274036134;5709.143628455765;5753.741081651252;5827.067669172912;5834.883302333845;5879.088741245114;5825.50483208932;5844.298684157929;5855.677937713466;5861.648301101947;5872.207252020922;5670.659760414388;5740.3685594585395;5741;5743.59794814359;5748.9425105748405;5683.460071293221;5709.9714501427225;5826.533877289722;5828.213191219131;5832.416758324133;5623.775048998158;5666.688332141772;5749.252597162342;5777.3933736957015;5778 07:04:01 INFO - PROCESS | 19856 | |1;ext.html;539.0835579514826;554.0442736279916;564.7289301135452;568.2045136808471;589.9734511946963;36424.63575364249;36839.4474082889;36886.26227475449;36981.44527832081;37033.25933481329;39793;40006.199876002465;40355;40529;40554;8427.283680887133;8701;8706.825863482727;8723.520206388646;8726.38915275931;8552.743417697448;8662.61018254177;8669.523176225304;8809.515476648781;8881.600327985223;8801.207891289783;8810.207081362676;8841.027486976427;8871.423357481775;8875.822483550324;8719.258862996652;8763.561821908943;8786.297096232318;8795.604197811113;8804.207621314112;8667.653293868238;8777.517236551987;8783.736487905373;8812.955935220347;8857.291416686714;40696.38955415671;40769.592304076876;40931.590684093375;40945.59054409448;41025;3240.330308493778;3273.6726327367073;3390.0507857799857;3460.636633153533;3542.2738338640515;29788;29888.85055574708;29946.251343716365;29986.70013299861;31201.531977020364;3299.2036405005765;3345.481450375196;3352.7317814574776;3368.713659338959;3418.3505134024454;274926;275472.999999998;275757;276798;276863;42733;42786.14427711428;42940.57059429428;58090.419095809346;58289;40810.59189408127;40833.79583102065;40946;41034.38448423276;41239.58760412417;14054.85945140556;14163.433462661584;14190.503332383318;14192.148471091768;14197.432102715982;11110.61112861062;11226.607068752555;11227.887721122848;11239.325640461599;11292.096632269398;14426.206558639165;14586.708265834835;14590.124592524482;14603.488877889224;14620.25063856974;5673;5915.3788852170765;5995.970020149872;5996.850078747979;6002.4897883679505;878.4202426398547;895.7671005538607;1045.5190612318268;1107.1747113722072;1130.1354463835173;28520.71479285222;28881.89620350392;28940.42119157605;29059;29123;948.6063283737171;1032.9896701033044;1057.9788404231872;1059.9576016959236;1096.2490693874722;13264.469421223235;13354.799678004838;13543.796843047363;13579.117357371739;13581;322081;324990;325809;326824;326963;50245;51280.48719512832;51287;51327.48672513227;51771.74114129405;1326.5357125006365;1339.8124262603244;1341.3025226881966;1344.8050032745198;1349.466960550577;27559.724402756518;28384.43231135407;28568.285792855346;28582.285442864093;28697.139085827457;47466.28800568063;47743.522564773906;47772.522274776806;47787.76106119447;47792;287391;288633;288816;288817;289526;32410.35179296448;42373;42433.36349954815;42774.35838462488;42874.571254288305;42379.576204237565;42977.78511107424;43200.35199472073;43748.56251437445;43933.78033109814;35742;36310.45534316934;36947.63052369442;38600.80699596484;39401.60598393979;34156.31687366289;38263.42604860874;38697.226055479296;39392.80303598464;40163.79918100391;33422.832885835414;33997.49003764896;34130.14674633154;34132.317353653285;34166.487502686985;29970.850145749133;30588.235294116934;31484.842575786974;31517.212069697525;31630.36739265248;24441.755582443948;26445.60331594989;26698;26791.732082679704;27103.392423287693 07:04:01 INFO - PROCESS | 19856 | |2;jquery.html;365.6982989034047;368.8413981987746;370.6608453265262;371.5949614919737;374.7283219665741;16510.59659928906;17339.826601733996;17590.208440620172;18174.545636359082;18333.358332458367;11091.057260132879;11105.167112466557;11127.443627818617;11932.105092118083;16120.838791612081;4814.590759785414;4928.6303527235605;4957.182064959277;5360.115580929142;5372.7313634318125;5366.4633536646215;5453.23654688344;5453.7818487260465;5454.7272636368225;5457.126859702448;5148.099082660519;5257.421683614818;5352.437994010612;5395.838124856261;5415.566754659618;5217;5263.289455923439;5264.736763161826;5269.710165940872;5291.417944026173;4978.626603004789;5116.360454943132;5150.793968241303;5157.149070403379;5164.173732202867;11976.221545599508;12416.068794840423;12432.378381081;12496;19688.606227875505;1957.5889063296581;1961.9607607847906;2046.3247128447726;2078.137572907257;2194.297824696098;9187.173154416068;9437.858432123523;9513.143817056498;11868.643940681794;12087.335196564185;1637.6459337769497;1652.6777278430711;1657.734762438004;1683.4444633270991;1699.7535357373235;59914.70042649803;62996.68501657462;64716;64814.46298759089;65370.67314663396;10195.643152489627;10232.59069637206;10243.94878025605;10352.068313851789;10352.741181470374;13420.261885596386;13426.395812188475;13462.124961877447;13462.259575723234;13542.119762215425;7504.399648028254;7577.029972207156;7591.164971853092;7606.47870425906;7618.923810761932;7291.781246562611;7301;7330.376917962033;7331.560106393634;7407.8148046299275;4387.407699960529;4473.373727678128;4476.552344765498;4486.3494793254795;4497.2129877271345;1800.1359347513267;1834.8073452287317;1835.9908200459179;1851.3520267906395;1854.6105317883125;439.88562973627086;456.6894511732054;459.82066993872417;461.1514812744566;474.78160046379054;10553.261271710906;10960.835587466194;11097.33415995043;11207.327560346404;11315.15136364776;444.7176043212583;451.7515366548385;455.6354916067133;458.59414418240095;472.08886848382264;5874.500667443315;5923.585349025613;6015.729292181775;6089.451949324582;6117.327094019654;566725;569890;572421;585375;586134;81654.59172704098;82017.58991205125;82987.17012829916;84472.57763711266;86398.56800715956;475.4508542633264;483.2533735378848;486.6860874735776;487.2812601412888;1076.9784604308027;14742.778858316919;15530.689386212438;15564.84435155634;15763.369465221722;15879.603009924844;35162.824185878904;35365.64634353624;35676;36610.63389366179;37170.25659486849;45831.77084114558;46188;47426.05147896954;47983.520164797905;50932.49067509278;8889.19997200255;9591.42451452915;9667.419954802734;9795.314327997257;9807.9019209807;16107.597310066873;17456.47630571034;17563.82436175622;17772.644547109245;18059.458216253533;13494.122882012638;13705.314734263446;13817.240051797247;13865.722685546434;14187.716245675236;14039.438422462987;14310.284485775877;14340.856591433952;14355.856441435451;14419.639509012359;14410.711785764017;14556.563303100924;14601.12393256408;14669.706605868036;15313.693726125191;9185.816283674156;9227.492487913229;9636.71089867305;9644.324897257125;9655.324127311022;9876.654317098866;12406.751864962469;12776.169548979287;13291.53479628247;14148.363323650254 07:04:01 INFO - PROCESS | 19856 | |3;mootools.html;1472.9852701472983;1478.430804140406;1628.4626073395773;1764.9558761030964;1776.4049043570403;7249.637518124099;7357.963210183948;7361.852762944744;7496.962515187416;7688.077430708309;7579.431542634297;7603.961980190105;7720.459567830254;7720.884186737204;7741.264579864918;2209.723784526934;2249.977500224993;2253.6056190166737;2255.977440225593;2257.5033492631596;2268.897899594514;2286.7941885230325;2339.040993192791;2339.883005849709;2346.7066616672914;2226.3432287475234;2258.6386178211487;2286.4855407533337;2287.2452090809993;2287.942801429961;2163.0915015693445;2257.2099765082253;2279.8062164715852;2283.1552325639536;2287.370972982435;2264.1396269417683;2264.1509433962265;2274.5564614899936;2276.8520046196945;2277.1119263487253;7467.775966721007;7484.887726684104;7491.7003319867135;7610.353119984808;7615.086189657221;4583.702059366164;4615.607673347769;4676.392069031038;4678.929816052728;4697.084068606623;2803.6635603727477;2885.4084912592866;2899.347646779479;2915.4460652475964;2939.441506113853;861.2291998661226;871.4640496094919;896.789254525186;913.6710784117712;927.3091546797679;9981.451020193887;10138.594456221743;10153.187744980383;10185.388876667424;10195;4147.543770185277;4179.958200418017;4227.408162857202;4257.318828987347;4334.068175342317;8915.866262006075;8951.820963580692;8958.417702849294;8983;8984.32617553686;4580.931286030712;4594.609458196024;4595.4025976622825;4599.701019433726;4606.930896036562;4153;4229.830806767694;4236.894077648083;4266.552012038754;4267.253230684618;3576.4277715565386;3585.4442561403025;3601.045722883438;3601.549806274216;3652.41561350188;1170.052257671289;1194.988050119505;1202.8736982616874;1204.10895937006;1211.3398197982121;931.0828833598899;937.4656445825814;1034.4103860799423;1069.9679009629724;1228.6805430587933;6662.200535935719;6665.333466653393;6685.431738302202;6688.33116688337;6720.361565651256;1095.8246680531076;1107.4075369677184;1122.1583812140893;1124.6963319903743;1146.6961255267195;5242.895142097137;5294.2852714883;5302.761375738101;5316.548093412026;5325.786968521215;8724.607392667345;8768.868466973001;8834.381593288537;8957.372983891066;8965.686200982935;8896.065913079034;8937.597808098652;9055.637774488947;9125.406848554823;9147.405418647766;6714.529982901247;6812.046313516112;7243.818904527324;7294.416446684252;7313.451491138186;6866.4506839453725;6869.313068693291;6906.896396553955;6931.618760967994;6933.5493192942295;8141.307988820779;8368.330533557422;8430.494170349799;8480.660773568989;8543.829123417621;8062.1534738853925;8122.878156827534;8200.38497112707;8222.301104406193;8288.295494883003;6749.291324410866;6822.829429264308;6866.553674011173;6943.062686537455;6952.756653517103;7515.736949206752;7527.2849079337375;7564.4704870658525;7567.962160189164;7587;6842.965785171242;6878.415334696607;6917.23910369869;7034.402075823612;7080.929190708027;6534.1178940844275;6563.606183628997;6626.171728533934;6653.334666533308;6757.966210168917;6088.847778805565;6441.291457939717;6496.1230233919705;6505.21937367519;6525.249596296487;5833;5839.474447299764;5843.29880414353;5860.443257890493;5882.470577648032;5763.337216220189;5853.268341457318;5856.94143058564;5878.823635290948;5909.084091965752 07:04:01 INFO - PROCESS | 19856 | |4;prototype.html;345.70269568171375;346.6931765387631;349.18291198595267;352.8958957107653;357.2194754461501;6224.221972253468;6247.37526247375;6285.402886725759;6314.873702525947;6332.936670633292;6086.452219300262;6227.28386235583;6231.626102433857;6234.719437625306;6257.4368306852375;4777.593904518103;4784.162771514988;4805.303231031498;4809.567138957493;4812;4820.1082799682;4823.951760482385;4824.662273640846;4829.830955916544;4838.806447742086;4707.246840505502;4769.141554520185;4796.3764710587575;4800.639952003597;4807.591354734852;4789.952100479003;4795.424549054118;4796.232602783538;4800.495947925452;4800.639952003578;4198;4211.220924129046;4214.093969796478;4248.468941382327;4250.192463431938;5921.200637913869;5955.434233747787;5965.313988891289;5974.3726908674835;5992.520598352121;5385.057614917374;5556.249906262643;5579.0515612346;5586.134149206839;5590.133529302964;6606.768763093269;6633.93366066338;6634.341414646338;6639.738449694542;6664.900026499607;4651.139539185261;4652.046330502273;4654.162250794856;4657.11514812188;4672.112298663277;6745.763898263586;6962.303769623048;6966.4078553323025;6969.372756451944;6971.058907047534;4675.438947326343;4702.412198475191;4708.552687494683;4710.764461776897;4713.057388522309;4738.265568836836;4752.524747525289;4759.809607615656;4761.2382018876815;4774.594159496412;5523.088690366045;5524.309461317335;5527.336719593594;5530.557555395559;5530.6128571000445;6556.737730490727;6700.765473208415;6710.832229194211;6715.227748808852;6718.865622687519;1634.6648936967892;1667.6247844235193;1669.35729744047;1676.4551520755558;1677;1090.8363745438248;1111.5498223219608;1121.5345631562973;1122.4163435013747;1124.9887501125047;6213.596116252429;6222.439980401786;6240.781572645026;6243.906341404882;6258.9046916789375;6722.29415911332;6741.7303307868115;6790.253072162058;6797.184337879387;6804.965975170093;6063.878722425615;6179.598326108789;6200.255969283625;6232.189815323979;6232.407921247474;3702.500162478085;3714.0343510686876;3718.4236443351324;3720.479132921393;3723.7579557328686;6975;7071.61106139157;7101.644917754092;7106.964465177641;7111.217766045731;6702.36327548882;6916.308369163144;6940.9652951734915;6949.131358580177;6956.339147781054;6981.511294209458;7010.894836577456;7029.859402811915;7035.155781306174;7044.084269044992;6750.459963202834;6771.729130834711;6771.796846094625;6776.15298087739;6781.966090169518;6527.706253218712;6597.934020659924;6665.200175978914;6667.766628167991;6670.466362690876;7022.719091236293;7036.331548502884;7068.11648543932;7069.434445244471;7074.3633073023675;4686.601638860735;4687.32033855096;4687.695299805502;4688.249880019181;4698.553637404441;6685.130932978682;6720.462363011045;6730.125083739278;6761.256261811099;6967.268436814062;6717.664116794239;6723.932760672331;6734.124563806674;6764.323567643196;6772.0857684212;6677.198736151693;6697.129373181455;6735.888578384708;6743.730250789913;6750.9873518972545;6488.9026664601015;6548.377904099103;6590.5716128451495;6612.0743095965645;6621.569597976116;6719.697613607302;6746.291639377795;6762.459003279628;6777.390034896883;6782.389584937182;6586.37429444202;6598.703058362289;6601.603903765789;6601.702923368556;6606.933930660824 07:04:01 INFO - PROCESS | 19856 | |5;yui.html;725.2964624314416;739.3013602145971;785.1363500149836;788.7791418402848;870.9825803483926;12397.69005774855;12466.501339946402;12701.682457938556;13646.522371716992;13689.110207836484;11385.203035787497;11454.599089031886;11508.884911150886;11526;11529;810.6433169405443;826.4008593769499;830.5515021888173;831.6216121664659;833.2584000239791;821.5686764448652;837.4138103327695;842.6882053640162;843.1484200957046;845.2646197807916;875.763543843162;883.2227639677108;883.7083762358438;885.8671199320089;886.7960369115108;861.7802460372596;870.6996086350199;870.921617054465;870.9520976346298;875.3609864798701;640.6508452893185;644.3331152257391;645.0453329072951;645.3836586060302;648.0150171738977;12191.817122743167;12213.206141600856;12213.45039473226;12270.32513211773;12320.691982700415;11810.704732381673;12040.879591204151;12222.388880555936;12260.44827982743;12383.876161238362;9795.657151999645;9861;9979.700608981742;9993.25050621199;10126.139278161365;3425.143714071482;3519.665631764978;3548.840302186408;3570.107473131717;3601.891943241707;48565.757171214274;49642.255366169535;49758;49832.50167498351;49854.252186217236;8755.387122901464;8892.688755893512;9096.947525999047;9332.626694932127;9509.239260859114;17612.29550817953;17668.911655441898;17727.73408398875;17771.82228177702;17781.555461113574;6396.904046439307;6900.3099690031595;6953.79138625842;7081.539699919489;7109.1824440189;3963.980180099081;4017.859374921922;4056.2090392373793;4092.283850326181;4108.6918481114035;3006.4738670732536;3014.231371000381;3027.5307327364294;3033.833139177322;3041.847907604655;503.40849501835197;522.7699812082672;523.1002675398308;523.3170712220589;533.714462762428;608.6926102318301;611.0477025902288;613.9263288405419;635.7012204263989;658.0786898342263;7884.408669349822;8106.594670266463;8141.307988820898;8143.592820358958;8363.749087527383;495.86611614863784;503.40598094248406;505.3354838387582;529.2590373477162;533.8772082421065;3800.600936901641;3801.6388443097862;3829.9808500957874;3849.4418309345547;3868.0716628009077;48127.518724812304;48146.759266203444;48153.03693926172;48448.5155148444;49772.751136244086;48568.5143148564;48939.75530122327;48952.26571601429;49573.75213123983;49917.25124123141;44193.33709994417;44196.55803441989;44597.55402445934;44810;45112.3233151503;7678.1170165431695;7693.576853273124;7757.224277572198;7865.842683146419;7995.440889026584;30786.384272314877;31133.221669458446;31356.52965205478;31796.205094872814;31863.84068079645;36882.26235475329;38962.2985816347;39378.212435751695;40472;40784.79607601943;9777.462239576891;9887.110160085627;10117.949410252902;10361.63734269297;10936;10694.73263168427;11060.612878549213;11120.388378638929;11191.776164476827;11210.383428911487;10009.449480278363;10387.740306492398;10680.145588352758;10729.463526823783;10739.624113156002;11055.22613417053;11136.109111270916;11171.553137874394;11171.776564468502;11497.367644779282;9851.605935762489;10014.849777253492;10122.051576954;10268.281220314806;10431.478426078818;8716.520591367536;8812.250958668585;9020.594073266588;9024.363487678544;9127.634894604142;8616.535189018008;8653.307735381028;8722.694705685271;8819.735407937773;8846.513441760508 07:04:01 INFO - PROCESS | 19856 | __end_tp_report 07:04:01 INFO - PROCESS | 19856 | __start_cc_report 07:04:01 INFO - PROCESS | 19856 | _x_x_mozilla_cycle_collect,308 07:04:01 INFO - PROCESS | 19856 | __end_cc_report 07:04:01 INFO - PROCESS | 19856 | __startTimestamp1451919841529__endTimestamp 07:04:01 INFO - PROCESS | 19856 | 07:04:01 INFO - PROCESS | 19856 | ------- Summary: start ------- 07:04:01 INFO - PROCESS | 19856 | Number of tests: 6 07:04:01 INFO - PROCESS | 19856 | 07:04:01 INFO - PROCESS | 19856 | [#0] dojo.html Cycles:165 Average:5507.00 Median:5740.37 stddev:625.76 (10.9%) stddev-sans-first:600.01 07:04:01 INFO - PROCESS | 19856 | Values: 3161.5 3232.8 3263.3 3277.8 3279.7 5392.7 5459.9 5471.6 5480.2 5482.8 5455.3 5478.2 5490.2 5512.6 5536.9 4939.4 4981.4 5086.3 5205.2 5243.2 5144.1 5189.6 5196.4 5234.2 5240.2 4885.9 4950.0 4997.9 5022.7 5053.7 4962.8 4968.0 4969.1 4975.2 5016.9 4934.2 4951.8 4977.2 4986.8 5058.9 5297.4 5336.4 5391.6 5400.4 5450.6 5930.4 5951.1 5953.6 5953.9 5962.3 5917.1 5995.9 6000.3 6015.4 6020.7 5550.7 5578.0 5606.1 5607.6 5653.2 6119.9 6135.9 6139.3 6147.4 6151.3 5687.4 5781.7 5859.5 5862.5 5864.5 5858.6 5906.9 5908.4 5914.6 5948.0 5857.0 5858.9 5892.0 5913.9 5916.6 5808.3 5877.4 5881.1 5903.0 5920.0 4542.9 4567.0 4579.0 4582.6 4616.9 4079.0 4087.4 4096.6 4101.5 4135.3 5415.0 5540.8 5592.3 5605.1 5618.4 5777.8 5856.7 5861.9 5863.2 5910.8 5486.6 5502.5 5510.4 5531.4 5561.6 4946.4 4949.2 5026.0 5040.4 5049.1 6156.1 6216.8 6224.6 6241.4 6241.8 5974.5 5992.4 5994.5 6002.6 6055.1 6058.3 6133.5 6144.6 6162.2 6215.0 5874.9 5900.5 5904.0 5917.5 5944.6 5989.9 6011.4 6015.0 6016.2 6017.0 5709.1 5753.7 5827.1 5834.9 5879.1 5825.5 5844.3 5855.7 5861.6 5872.2 5670.7 5740.4 5741.0 5743.6 5748.9 5683.5 5710.0 5826.5 5828.2 5832.4 5623.8 5666.7 5749.3 5777.4 5778.0 07:04:01 INFO - PROCESS | 19856 | 07:04:01 INFO - PROCESS | 19856 | [#1] ext.html Cycles:180 Average:45794.25 Median:36367.55 stddev:77749.77 (213.8%) stddev-sans-first:77893.62 07:04:01 INFO - PROCESS | 19856 | Values: 539.1 554.0 564.7 568.2 590.0 36424.6 36839.4 36886.3 36981.4 37033.3 39793.0 40006.2 40355.0 40529.0 40554.0 8427.3 8701.0 8706.8 8723.5 8726.4 8552.7 8662.6 8669.5 8809.5 8881.6 8801.2 8810.2 8841.0 8871.4 8875.8 8719.3 8763.6 8786.3 8795.6 8804.2 8667.7 8777.5 8783.7 8813.0 8857.3 40696.4 40769.6 40931.6 40945.6 41025.0 3240.3 3273.7 3390.1 3460.6 3542.3 29788.0 29888.9 29946.3 29986.7 31201.5 3299.2 3345.5 3352.7 3368.7 3418.4 274926.0 275473.0 275757.0 276798.0 276863.0 42733.0 42786.1 42940.6 58090.4 58289.0 40810.6 40833.8 40946.0 41034.4 41239.6 14054.9 14163.4 14190.5 14192.1 14197.4 11110.6 11226.6 11227.9 11239.3 11292.1 14426.2 14586.7 14590.1 14603.5 14620.3 5673.0 5915.4 5996.0 5996.9 6002.5 878.4 895.8 1045.5 1107.2 1130.1 28520.7 28881.9 28940.4 29059.0 29123.0 948.6 1033.0 1058.0 1060.0 1096.2 13264.5 13354.8 13543.8 13579.1 13581.0 322081.0 324990.0 325809.0 326824.0 326963.0 50245.0 51280.5 51287.0 51327.5 51771.7 1326.5 1339.8 1341.3 1344.8 1349.5 27559.7 28384.4 28568.3 28582.3 28697.1 47466.3 47743.5 47772.5 47787.8 47792.0 287391.0 288633.0 288816.0 288817.0 289526.0 32410.4 42373.0 42433.4 42774.4 42874.6 42379.6 42977.8 43200.4 43748.6 43933.8 35742.0 36310.5 36947.6 38600.8 39401.6 34156.3 38263.4 38697.2 39392.8 40163.8 33422.8 33997.5 34130.1 34132.3 34166.5 29970.9 30588.2 31484.8 31517.2 31630.4 24441.8 26445.6 26698.0 26791.7 27103.4 07:04:01 INFO - PROCESS | 19856 | 07:04:01 INFO - PROCESS | 19856 | [#2] jquery.html Cycles:180 Average:29498.69 Median:4474.96 stddev:94281.47 (2106.9%) stddev-sans-first:94520.57 07:04:01 INFO - PROCESS | 19856 | Values: 365.7 368.8 370.7 371.6 374.7 16510.6 17339.8 17590.2 18174.5 18333.4 11091.1 11105.2 11127.4 11932.1 16120.8 4814.6 4928.6 4957.2 5360.1 5372.7 5366.5 5453.2 5453.8 5454.7 5457.1 5148.1 5257.4 5352.4 5395.8 5415.6 5217.0 5263.3 5264.7 5269.7 5291.4 4978.6 5116.4 5150.8 5157.1 5164.2 11976.2 12416.1 12432.4 12496.0 19688.6 1957.6 1962.0 2046.3 2078.1 2194.3 9187.2 9437.9 9513.1 11868.6 12087.3 1637.6 1652.7 1657.7 1683.4 1699.8 59914.7 62996.7 64716.0 64814.5 65370.7 10195.6 10232.6 10243.9 10352.1 10352.7 13420.3 13426.4 13462.1 13462.3 13542.1 7504.4 7577.0 7591.2 7606.5 7618.9 7291.8 7301.0 7330.4 7331.6 7407.8 4387.4 4473.4 4476.6 4486.3 4497.2 1800.1 1834.8 1836.0 1851.4 1854.6 439.9 456.7 459.8 461.2 474.8 10553.3 10960.8 11097.3 11207.3 11315.2 444.7 451.8 455.6 458.6 472.1 5874.5 5923.6 6015.7 6089.5 6117.3 566725.0 569890.0 572421.0 585375.0 586134.0 81654.6 82017.6 82987.2 84472.6 86398.6 475.5 483.3 486.7 487.3 1077.0 14742.8 15530.7 15564.8 15763.4 15879.6 35162.8 35365.6 35676.0 36610.6 37170.3 45831.8 46188.0 47426.1 47983.5 50932.5 8889.2 9591.4 9667.4 9795.3 9807.9 16107.6 17456.5 17563.8 17772.6 18059.5 13494.1 13705.3 13817.2 13865.7 14187.7 14039.4 14310.3 14340.9 14355.9 14419.6 14410.7 14556.6 14601.1 14669.7 15313.7 9185.8 9227.5 9636.7 9644.3 9655.3 9876.7 12406.8 12776.2 13291.5 14148.4 07:04:01 INFO - PROCESS | 19856 | 07:04:01 INFO - PROCESS | 19856 | [#3] mootools.html Cycles:180 Average:5268.94 Median:5895.78 stddev:2700.79 (45.8%) stddev-sans-first:2693.30 07:04:01 INFO - PROCESS | 19856 | Values: 1473.0 1478.4 1628.5 1765.0 1776.4 7249.6 7358.0 7361.9 7497.0 7688.1 7579.4 7604.0 7720.5 7720.9 7741.3 2209.7 2250.0 2253.6 2256.0 2257.5 2268.9 2286.8 2339.0 2339.9 2346.7 2226.3 2258.6 2286.5 2287.2 2287.9 2163.1 2257.2 2279.8 2283.2 2287.4 2264.1 2264.2 2274.6 2276.9 2277.1 7467.8 7484.9 7491.7 7610.4 7615.1 4583.7 4615.6 4676.4 4678.9 4697.1 2803.7 2885.4 2899.3 2915.4 2939.4 861.2 871.5 896.8 913.7 927.3 9981.5 10138.6 10153.2 10185.4 10195.0 4147.5 4180.0 4227.4 4257.3 4334.1 8915.9 8951.8 8958.4 8983.0 8984.3 4580.9 4594.6 4595.4 4599.7 4606.9 4153.0 4229.8 4236.9 4266.6 4267.3 3576.4 3585.4 3601.0 3601.5 3652.4 1170.1 1195.0 1202.9 1204.1 1211.3 931.1 937.5 1034.4 1070.0 1228.7 6662.2 6665.3 6685.4 6688.3 6720.4 1095.8 1107.4 1122.2 1124.7 1146.7 5242.9 5294.3 5302.8 5316.5 5325.8 8724.6 8768.9 8834.4 8957.4 8965.7 8896.1 8937.6 9055.6 9125.4 9147.4 6714.5 6812.0 7243.8 7294.4 7313.5 6866.5 6869.3 6906.9 6931.6 6933.5 8141.3 8368.3 8430.5 8480.7 8543.8 8062.2 8122.9 8200.4 8222.3 8288.3 6749.3 6822.8 6866.6 6943.1 6952.8 7515.7 7527.3 7564.5 7568.0 7587.0 6843.0 6878.4 6917.2 7034.4 7080.9 6534.1 6563.6 6626.2 6653.3 6758.0 6088.8 6441.3 6496.1 6505.2 6525.2 5833.0 5839.5 5843.3 5860.4 5882.5 5763.3 5853.3 5856.9 5878.8 5909.1 07:04:01 INFO - PROCESS | 19856 | 07:04:01 INFO - PROCESS | 19856 | [#4] prototype.html Cycles:180 Average:5545.04 Median:6233.56 stddev:1663.40 (26.7%) stddev-sans-first:1621.64 07:04:01 INFO - PROCESS | 19856 | Values: 345.7 346.7 349.2 352.9 357.2 6224.2 6247.4 6285.4 6314.9 6332.9 6086.5 6227.3 6231.6 6234.7 6257.4 4777.6 4784.2 4805.3 4809.6 4812.0 4820.1 4824.0 4824.7 4829.8 4838.8 4707.2 4769.1 4796.4 4800.6 4807.6 4790.0 4795.4 4796.2 4800.5 4800.6 4198.0 4211.2 4214.1 4248.5 4250.2 5921.2 5955.4 5965.3 5974.4 5992.5 5385.1 5556.2 5579.1 5586.1 5590.1 6606.8 6633.9 6634.3 6639.7 6664.9 4651.1 4652.0 4654.2 4657.1 4672.1 6745.8 6962.3 6966.4 6969.4 6971.1 4675.4 4702.4 4708.6 4710.8 4713.1 4738.3 4752.5 4759.8 4761.2 4774.6 5523.1 5524.3 5527.3 5530.6 5530.6 6556.7 6700.8 6710.8 6715.2 6718.9 1634.7 1667.6 1669.4 1676.5 1677.0 1090.8 1111.5 1121.5 1122.4 1125.0 6213.6 6222.4 6240.8 6243.9 6258.9 6722.3 6741.7 6790.3 6797.2 6805.0 6063.9 6179.6 6200.3 6232.2 6232.4 3702.5 3714.0 3718.4 3720.5 3723.8 6975.0 7071.6 7101.6 7107.0 7111.2 6702.4 6916.3 6941.0 6949.1 6956.3 6981.5 7010.9 7029.9 7035.2 7044.1 6750.5 6771.7 6771.8 6776.2 6782.0 6527.7 6597.9 6665.2 6667.8 6670.5 7022.7 7036.3 7068.1 7069.4 7074.4 4686.6 4687.3 4687.7 4688.2 4698.6 6685.1 6720.5 6730.1 6761.3 6967.3 6717.7 6723.9 6734.1 6764.3 6772.1 6677.2 6697.1 6735.9 6743.7 6751.0 6488.9 6548.4 6590.6 6612.1 6621.6 6719.7 6746.3 6762.5 6777.4 6782.4 6586.4 6598.7 6601.6 6601.7 6606.9 07:04:01 INFO - PROCESS | 19856 | 07:04:01 INFO - PROCESS | 19856 | [#5] yui.html Cycles:180 Average:12852.63 Median:24724.07 stddev:14807.07 (59.9%) stddev-sans-first:14820.60 07:04:01 INFO - PROCESS | 19856 | Values: 725.3 739.3 785.1 788.8 871.0 12397.7 12466.5 12701.7 13646.5 13689.1 11385.2 11454.6 11508.9 11526.0 11529.0 810.6 826.4 830.6 831.6 833.3 821.6 837.4 842.7 843.1 845.3 875.8 883.2 883.7 885.9 886.8 861.8 870.7 870.9 871.0 875.4 640.7 644.3 645.0 645.4 648.0 12191.8 12213.2 12213.5 12270.3 12320.7 11810.7 12040.9 12222.4 12260.4 12383.9 9795.7 9861.0 9979.7 9993.3 10126.1 3425.1 3519.7 3548.8 3570.1 3601.9 48565.8 49642.3 49758.0 49832.5 49854.3 8755.4 8892.7 9096.9 9332.6 9509.2 17612.3 17668.9 17727.7 17771.8 17781.6 6396.9 6900.3 6953.8 7081.5 7109.2 3964.0 4017.9 4056.2 4092.3 4108.7 3006.5 3014.2 3027.5 3033.8 3041.8 503.4 522.8 523.1 523.3 533.7 608.7 611.0 613.9 635.7 658.1 7884.4 8106.6 8141.3 8143.6 8363.7 495.9 503.4 505.3 529.3 533.9 3800.6 3801.6 3830.0 3849.4 3868.1 48127.5 48146.8 48153.0 48448.5 49772.8 48568.5 48939.8 48952.3 49573.8 49917.3 44193.3 44196.6 44597.6 44810.0 45112.3 7678.1 7693.6 7757.2 7865.8 7995.4 30786.4 31133.2 31356.5 31796.2 31863.8 36882.3 38962.3 39378.2 40472.0 40784.8 9777.5 9887.1 10117.9 10361.6 10936.0 10694.7 11060.6 11120.4 11191.8 11210.4 10009.4 10387.7 10680.1 10729.5 10739.6 11055.2 11136.1 11171.6 11171.8 11497.4 9851.6 10014.8 10122.1 10268.3 10431.5 8716.5 8812.3 9020.6 9024.4 9127.6 8616.5 8653.3 8722.7 8819.7 8846.5 07:04:01 INFO - PROCESS | 19856 | -------- Summary: end -------- 07:04:01 INFO - PROCESS | 19856 | 07:04:02 INFO - TEST-INFO | 19856: exit 0 07:04:02 INFO - TEST-OK | dromaeo_css | took 1111216ms 07:04:02 INFO - TEST-START | kraken 07:04:02 INFO - operating with platform_type : linux_ 07:04:02 INFO - Initialising browser for kraken test... 07:04:02 INFO - TEST-INFO | started process 20105 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpVDN7ju/profile http://localhost:56791/getInfo.html) 07:04:07 INFO - PROCESS | 20105 | __metrics Screen width/height:1600/1200 07:04:07 INFO - PROCESS | 20105 | colorDepth:24 07:04:07 INFO - PROCESS | 20105 | Browser inner width/height: 1024/697 07:04:07 INFO - PROCESS | 20105 | __metrics 07:04:07 INFO - PROCESS | 20105 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 07:04:08 INFO - TEST-INFO | 20105: exit 0 07:04:08 INFO - Browser initialized. 07:04:08 INFO - Running cycle 1/1 for kraken test... 07:04:08 INFO - TEST-INFO | started process 20228 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpVDN7ju/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:04:19 INFO - PROCESS | 20228 | RSS: Main: 150474752 07:04:19 INFO - PROCESS | 20228 | 07:04:22 INFO - PROCESS | 20228 | Cycle 1(1): loaded http://localhost:56791/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56791/tests/kraken/driver.html?testName=audio-beat-detection) 07:04:22 INFO - PROCESS | 20228 | RSS: Main: 150794240 07:04:22 INFO - PROCESS | 20228 | 07:04:25 INFO - PROCESS | 20228 | Cycle 1(1): loaded http://localhost:56791/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56791/tests/kraken/driver.html?testName=audio-dft) 07:04:26 INFO - PROCESS | 20228 | RSS: Main: 151859200 07:04:26 INFO - PROCESS | 20228 | 07:04:29 INFO - PROCESS | 20228 | Cycle 1(1): loaded http://localhost:56791/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56791/tests/kraken/driver.html?testName=audio-fft) 07:04:29 INFO - PROCESS | 20228 | RSS: Main: 146612224 07:04:29 INFO - PROCESS | 20228 | 07:04:31 INFO - PROCESS | 20228 | Cycle 1(1): loaded http://localhost:56791/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56791/tests/kraken/driver.html?testName=audio-oscillator) 07:04:32 INFO - PROCESS | 20228 | RSS: Main: 145960960 07:04:32 INFO - PROCESS | 20228 | 07:04:34 INFO - PROCESS | 20228 | Cycle 1(1): loaded http://localhost:56791/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56791/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:04:35 INFO - PROCESS | 20228 | RSS: Main: 146661376 07:04:35 INFO - PROCESS | 20228 | 07:04:38 INFO - PROCESS | 20228 | Cycle 1(1): loaded http://localhost:56791/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56791/tests/kraken/driver.html?testName=imaging-darkroom) 07:04:38 INFO - PROCESS | 20228 | RSS: Main: 147468288 07:04:38 INFO - PROCESS | 20228 | 07:04:42 INFO - PROCESS | 20228 | Cycle 1(1): loaded http://localhost:56791/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56791/tests/kraken/driver.html?testName=imaging-desaturate) 07:04:42 INFO - PROCESS | 20228 | RSS: Main: 148688896 07:04:42 INFO - PROCESS | 20228 | 07:04:45 INFO - PROCESS | 20228 | Cycle 1(1): loaded http://localhost:56791/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56791/tests/kraken/driver.html?testName=json-parse-financial) 07:04:46 INFO - PROCESS | 20228 | RSS: Main: 148774912 07:04:46 INFO - PROCESS | 20228 | 07:04:47 INFO - PROCESS | 20228 | Cycle 1(1): loaded http://localhost:56791/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56791/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:04:48 INFO - PROCESS | 20228 | RSS: Main: 148430848 07:04:48 INFO - PROCESS | 20228 | 07:04:50 INFO - PROCESS | 20228 | Cycle 1(1): loaded http://localhost:56791/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56791/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:04:50 INFO - PROCESS | 20228 | RSS: Main: 148897792 07:04:50 INFO - PROCESS | 20228 | 07:04:52 INFO - PROCESS | 20228 | Cycle 1(1): loaded http://localhost:56791/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56791/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:04:53 INFO - PROCESS | 20228 | RSS: Main: 149135360 07:04:53 INFO - PROCESS | 20228 | 07:04:56 INFO - PROCESS | 20228 | Cycle 1(1): loaded http://localhost:56791/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56791/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:04:56 INFO - PROCESS | 20228 | RSS: Main: 149176320 07:04:56 INFO - PROCESS | 20228 | 07:04:59 INFO - PROCESS | 20228 | Cycle 1(1): loaded http://localhost:56791/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56791/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:04:59 INFO - PROCESS | 20228 | RSS: Main: 149078016 07:04:59 INFO - PROCESS | 20228 | 07:05:01 INFO - PROCESS | 20228 | Cycle 1(1): loaded http://localhost:56791/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56791/tests/kraken/driver.html?testName=ai-astar) 07:05:01 INFO - PROCESS | 20228 | RSS: Main: 150138880 07:05:01 INFO - PROCESS | 20228 | 07:05:01 INFO - PROCESS | 20228 | __start_tp_report 07:05:01 INFO - PROCESS | 20228 | _x_x_mozilla_page_load 07:05:01 INFO - PROCESS | 20228 | _x_x_mozilla_page_load_details 07:05:01 INFO - PROCESS | 20228 | |i|pagename|runs| 07:05:01 INFO - PROCESS | 20228 | |0;ai-astar;112;106;111;115;110;122;109;110;112;111 07:05:01 INFO - PROCESS | 20228 | |1;audio-beat-detection;160;127;155;158;126;152;153;129;173;129 07:05:01 INFO - PROCESS | 20228 | |2;audio-dft;164;164;163;164;161;161;160;164;168;162 07:05:01 INFO - PROCESS | 20228 | |3;audio-fft;72;72;73;72;72;142;72;83;75;72 07:05:01 INFO - PROCESS | 20228 | |4;audio-oscillator;96;97;153;96;98;95;152;96;97;137 07:05:01 INFO - PROCESS | 20228 | |5;imaging-gaussian-blur;109;110;110;110;110;110;110;111;110;110 07:05:01 INFO - PROCESS | 20228 | |6;imaging-darkroom;147;147;149;148;147;147;149;146;149;148 07:05:01 INFO - PROCESS | 20228 | |7;imaging-desaturate;97;96;96;102;101;101;101;96;96;96 07:05:01 INFO - PROCESS | 20228 | |8;json-parse-financial;62;60;60;60;61;63;60;59;57;61 07:05:01 INFO - PROCESS | 20228 | |9;json-stringify-tinderbox;67;67;67;69;67;69;67;67;68;70 07:05:01 INFO - PROCESS | 20228 | |10;stanford-crypto-aes;84;88;94;86;88;93;87;87;87;86 07:05:01 INFO - PROCESS | 20228 | |11;stanford-crypto-ccm;130;116;123;121;118;125;119;123;129;121 07:05:01 INFO - PROCESS | 20228 | |12;stanford-crypto-pbkdf2;143;151;154;160;161;152;161;151;150;139 07:05:01 INFO - PROCESS | 20228 | |13;stanford-crypto-sha256-iterative;68;69;69;68;71;68;71;67;74;68 07:05:01 INFO - PROCESS | 20228 | __end_tp_report 07:05:01 INFO - PROCESS | 20228 | __start_cc_report 07:05:01 INFO - PROCESS | 20228 | _x_x_mozilla_cycle_collect,770 07:05:01 INFO - PROCESS | 20228 | __end_cc_report 07:05:01 INFO - PROCESS | 20228 | __startTimestamp1451919901811__endTimestamp 07:05:01 INFO - PROCESS | 20228 | 07:05:01 INFO - PROCESS | 20228 | ------- Summary: start ------- 07:05:01 INFO - PROCESS | 20228 | Number of tests: 14 07:05:01 INFO - PROCESS | 20228 | 07:05:01 INFO - PROCESS | 20228 | [#0] ai-astar Cycles:10 Average:111.80 Median:111.50 stddev:4.26 (3.8%) stddev-sans-first:4.52 07:05:01 INFO - PROCESS | 20228 | Values: 112.0 106.0 111.0 115.0 110.0 122.0 109.0 110.0 112.0 111.0 07:05:01 INFO - PROCESS | 20228 | 07:05:01 INFO - PROCESS | 20228 | [#1] audio-beat-detection Cycles:10 Average:146.20 Median:154.00 stddev:16.91 (11.0%) stddev-sans-first:17.18 07:05:01 INFO - PROCESS | 20228 | Values: 160.0 127.0 155.0 158.0 126.0 152.0 153.0 129.0 173.0 129.0 07:05:01 INFO - PROCESS | 20228 | 07:05:01 INFO - PROCESS | 20228 | [#2] audio-dft Cycles:10 Average:163.10 Median:164.00 stddev:2.28 (1.4%) stddev-sans-first:2.40 07:05:01 INFO - PROCESS | 20228 | Values: 164.0 164.0 163.0 164.0 161.0 161.0 160.0 164.0 168.0 162.0 07:05:01 INFO - PROCESS | 20228 | 07:05:01 INFO - PROCESS | 20228 | [#3] audio-fft Cycles:10 Average:80.50 Median:72.00 stddev:21.88 (30.4%) stddev-sans-first:22.99 07:05:01 INFO - PROCESS | 20228 | Values: 72.0 72.0 73.0 72.0 72.0 142.0 72.0 83.0 75.0 72.0 07:05:01 INFO - PROCESS | 20228 | 07:05:01 INFO - PROCESS | 20228 | [#4] audio-oscillator Cycles:10 Average:111.70 Median:96.00 stddev:24.96 (26.0%) stddev-sans-first:25.82 07:05:01 INFO - PROCESS | 20228 | Values: 96.0 97.0 153.0 96.0 98.0 95.0 152.0 96.0 97.0 137.0 07:05:01 INFO - PROCESS | 20228 | 07:05:01 INFO - PROCESS | 20228 | [#5] imaging-gaussian-blur Cycles:10 Average:110.00 Median:110.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 07:05:01 INFO - PROCESS | 20228 | Values: 109.0 110.0 110.0 110.0 110.0 110.0 110.0 111.0 110.0 110.0 07:05:01 INFO - PROCESS | 20228 | 07:05:01 INFO - PROCESS | 20228 | [#6] imaging-darkroom Cycles:10 Average:147.70 Median:148.00 stddev:1.06 (0.7%) stddev-sans-first:1.09 07:05:01 INFO - PROCESS | 20228 | Values: 147.0 147.0 149.0 148.0 147.0 147.0 149.0 146.0 149.0 148.0 07:05:01 INFO - PROCESS | 20228 | 07:05:01 INFO - PROCESS | 20228 | [#7] imaging-desaturate Cycles:10 Average:98.20 Median:96.00 stddev:2.66 (2.8%) stddev-sans-first:2.78 07:05:01 INFO - PROCESS | 20228 | Values: 97.0 96.0 96.0 102.0 101.0 101.0 101.0 96.0 96.0 96.0 07:05:01 INFO - PROCESS | 20228 | 07:05:01 INFO - PROCESS | 20228 | [#8] json-parse-financial Cycles:10 Average:60.30 Median:60.50 stddev:1.64 (2.7%) stddev-sans-first:1.62 07:05:01 INFO - PROCESS | 20228 | Values: 62.0 60.0 60.0 60.0 61.0 63.0 60.0 59.0 57.0 61.0 07:05:01 INFO - PROCESS | 20228 | 07:05:01 INFO - PROCESS | 20228 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:67.50 stddev:1.14 (1.7%) stddev-sans-first:1.17 07:05:01 INFO - PROCESS | 20228 | Values: 67.0 67.0 67.0 69.0 67.0 69.0 67.0 67.0 68.0 70.0 07:05:01 INFO - PROCESS | 20228 | 07:05:01 INFO - PROCESS | 20228 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:87.50 stddev:3.13 (3.6%) stddev-sans-first:2.96 07:05:01 INFO - PROCESS | 20228 | Values: 84.0 88.0 94.0 86.0 88.0 93.0 87.0 87.0 87.0 86.0 07:05:01 INFO - PROCESS | 20228 | 07:05:01 INFO - PROCESS | 20228 | [#11] stanford-crypto-ccm Cycles:10 Average:122.50 Median:123.00 stddev:4.53 (3.7%) stddev-sans-first:3.91 07:05:01 INFO - PROCESS | 20228 | Values: 130.0 116.0 123.0 121.0 118.0 125.0 119.0 123.0 129.0 121.0 07:05:01 INFO - PROCESS | 20228 | 07:05:01 INFO - PROCESS | 20228 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.20 Median:153.00 stddev:7.35 (4.8%) stddev-sans-first:7.00 07:05:01 INFO - PROCESS | 20228 | Values: 143.0 151.0 154.0 160.0 161.0 152.0 161.0 151.0 150.0 139.0 07:05:01 INFO - PROCESS | 20228 | 07:05:01 INFO - PROCESS | 20228 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:69.00 stddev:2.11 (3.1%) stddev-sans-first:2.19 07:05:01 INFO - PROCESS | 20228 | Values: 68.0 69.0 69.0 68.0 71.0 68.0 71.0 67.0 74.0 68.0 07:05:01 INFO - PROCESS | 20228 | -------- Summary: end -------- 07:05:01 INFO - PROCESS | 20228 | 07:05:02 INFO - TEST-INFO | 20228: exit 0 07:05:02 INFO - TEST-OK | kraken | took 60513ms 07:05:02 INFO - TEST-START | v8_7 07:05:02 INFO - operating with platform_type : linux_ 07:05:02 INFO - Initialising browser for v8_7 test... 07:05:02 INFO - TEST-INFO | started process 20515 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpWNr_LO/profile http://localhost:56791/getInfo.html) 07:05:07 INFO - PROCESS | 20515 | __metrics Screen width/height:1600/1200 07:05:07 INFO - PROCESS | 20515 | colorDepth:24 07:05:07 INFO - PROCESS | 20515 | Browser inner width/height: 1024/697 07:05:07 INFO - PROCESS | 20515 | __metrics 07:05:07 INFO - PROCESS | 20515 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 07:05:08 INFO - TEST-INFO | 20515: exit 0 07:05:08 INFO - Browser initialized. 07:05:08 INFO - Running cycle 1/1 for v8_7 test... 07:05:08 INFO - TEST-INFO | started process 20640 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpWNr_LO/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:05:20 INFO - PROCESS | 20640 | RSS: Main: 150441984 07:05:20 INFO - PROCESS | 20640 | 07:05:41 INFO - PROCESS | 20640 | Cycle 1(1): loaded http://localhost:56791/tests/v8_7/run.html (next: http://localhost:56791/tests/v8_7/run.html) 07:05:42 INFO - PROCESS | 20640 | RSS: Main: 146915328 07:05:42 INFO - PROCESS | 20640 | 07:05:42 INFO - PROCESS | 20640 | __start_tp_report 07:05:42 INFO - PROCESS | 20640 | _x_x_mozilla_page_load 07:05:42 INFO - PROCESS | 20640 | _x_x_mozilla_page_load_details 07:05:42 INFO - PROCESS | 20640 | |i|pagename|runs| 07:05:42 INFO - PROCESS | 20640 | |0;Richards;167.42005692281936 07:05:42 INFO - PROCESS | 20640 | |1;DeltaBlue;188.64365214110546 07:05:42 INFO - PROCESS | 20640 | |2;Encrypt;280.0336040324839 07:05:42 INFO - PROCESS | 20640 | |3;Decrypt;5208.333333333333 07:05:42 INFO - PROCESS | 20640 | |4;RayTrace;1060.4453870625662 07:05:42 INFO - PROCESS | 20640 | |5;Earley;705.2186177715091 07:05:42 INFO - PROCESS | 20640 | |6;Boyer;12345.67901234568 07:05:42 INFO - PROCESS | 20640 | |7;RegExp;25150 07:05:42 INFO - PROCESS | 20640 | |8;Splay;678.42605156038 07:05:42 INFO - PROCESS | 20640 | |9;NavierStokes;4840.579710144928 07:05:42 INFO - PROCESS | 20640 | __end_tp_report 07:05:42 INFO - PROCESS | 20640 | __startTimestamp1451919942072__endTimestamp 07:05:42 INFO - PROCESS | 20640 | 07:05:42 INFO - PROCESS | 20640 | ------- Summary: start ------- 07:05:42 INFO - PROCESS | 20640 | Number of tests: 10 07:05:42 INFO - PROCESS | 20640 | 07:05:42 INFO - PROCESS | 20640 | [#0] Richards Cycles:1 Average:167.42 Median:167.42 stddev:0.00 (0.0%) 07:05:42 INFO - PROCESS | 20640 | Values: 167.4 07:05:42 INFO - PROCESS | 20640 | 07:05:42 INFO - PROCESS | 20640 | [#1] DeltaBlue Cycles:1 Average:188.64 Median:188.64 stddev:0.00 (0.0%) 07:05:42 INFO - PROCESS | 20640 | Values: 188.6 07:05:42 INFO - PROCESS | 20640 | 07:05:42 INFO - PROCESS | 20640 | [#2] Encrypt Cycles:1 Average:280.03 Median:280.03 stddev:0.00 (0.0%) 07:05:42 INFO - PROCESS | 20640 | Values: 280.0 07:05:42 INFO - PROCESS | 20640 | 07:05:42 INFO - PROCESS | 20640 | [#3] Decrypt Cycles:1 Average:5208.33 Median:5208.33 stddev:0.00 (0.0%) 07:05:42 INFO - PROCESS | 20640 | Values: 5208.3 07:05:42 INFO - PROCESS | 20640 | 07:05:42 INFO - PROCESS | 20640 | [#4] RayTrace Cycles:1 Average:1060.45 Median:1060.45 stddev:0.00 (0.0%) 07:05:42 INFO - PROCESS | 20640 | Values: 1060.4 07:05:42 INFO - PROCESS | 20640 | 07:05:42 INFO - PROCESS | 20640 | [#5] Earley Cycles:1 Average:705.22 Median:705.22 stddev:0.00 (0.0%) 07:05:42 INFO - PROCESS | 20640 | Values: 705.2 07:05:42 INFO - PROCESS | 20640 | 07:05:42 INFO - PROCESS | 20640 | [#6] Boyer Cycles:1 Average:12345.68 Median:12345.68 stddev:0.00 (0.0%) 07:05:42 INFO - PROCESS | 20640 | Values: 12345.7 07:05:42 INFO - PROCESS | 20640 | 07:05:42 INFO - PROCESS | 20640 | [#7] RegExp Cycles:1 Average:25150.00 Median:25150.00 stddev:0.00 (0.0%) 07:05:42 INFO - PROCESS | 20640 | Values: 25150.0 07:05:42 INFO - PROCESS | 20640 | 07:05:42 INFO - PROCESS | 20640 | [#8] Splay Cycles:1 Average:678.43 Median:678.43 stddev:0.00 (0.0%) 07:05:42 INFO - PROCESS | 20640 | Values: 678.4 07:05:42 INFO - PROCESS | 20640 | 07:05:42 INFO - PROCESS | 20640 | [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 07:05:42 INFO - PROCESS | 20640 | Values: 4840.6 07:05:42 INFO - PROCESS | 20640 | -------- Summary: end -------- 07:05:42 INFO - PROCESS | 20640 | 07:05:42 INFO - TEST-INFO | 20640: exit 0 07:05:43 INFO - TEST-OK | v8_7 | took 40257ms 07:05:43 INFO - SUITE-END | took 1211s 07:05:43 INFO - Completed test suite (00:20:12) 07:05:43 INFO - Working with test: dromaeo_css 07:05:43 INFO - Generating results file: dromaeo_css 07:05:43 INFO - Working with test: kraken 07:05:43 INFO - Generating results file: kraken 07:05:43 INFO - javascript benchmark 07:05:43 INFO - Working with test: v8_7 07:05:43 INFO - Generating results file: v8_7 07:05:43 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:05:43 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,63,43]] 07:05:43 INFO - process_Request line: dromaeo_css 6830.21 graph.html#tests=[[72,63,43]] 07:05:43 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:05:43 INFO - process_Request line: kraken 1529.30 graph.html#tests=[[232,63,43]] 07:05:43 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:05:43 INFO - process_Request line: v8_7 21131.18 graph.html#tests=[[230,63,43]] 07:05:43 INFO - RETURN: dromaeo_css: 6830.21 07:05:43 INFO - RETURN: kraken: 1529.30 07:05:43 INFO - RETURN: v8_7: 21131.18 07:05:43 INFO - javascript benchmark 07:05:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3161.462551366268, 3232.8221947792877, 3263.298390845968, 3277.8199848054696, 3279.721223695986, 5392.73036348182, 5459.853430779531, 5471.589630777693, 5480.150576660619, 5482.80810171644, 5455.345358556979, 5478.233047373371, 5490.203920431545, 5512.5589952803775, 5536.916946245799, 4939.43196532398, 4981.427135879384, 5086.3387759591205, 5205.16717325228, 5243.2135179723155, 5144.09978253806, 5189.584833213334, 5196.428392876781, 5234.214867769846, 5240.187770895517, 4885.926711099319, 4949.975250123763, 4997.875053123683, 5022.673526220784, 5053.69677819332, 4962.776675049631, 4968.0, 4969.105560999019, 4975.154223781929, 5016.8996620067755, 4934.210526315772, 4951.801927922878, 4977.228529577885, 4986.750662466862, 5058.949410505921, 5297.390800058004, 5336.4129945705945, 5391.649542779746, 5400.378956420022, 5450.618456708032, 5930.436608522226, 5951.137085122677, 5953.613015154045, 5953.940460595425, 5962.25471816023, 5917.082852157922, 5995.880082398327, 6000.279966404016, 6015.368386319417, 6020.6989650517735, 5550.69471179085, 5577.972110139464, 5606.131049687305, 5607.55139588836, 5653.208550802891, 6119.877602448015, 6135.9079613805825, 6139.26328840542, 6147.44672979434, 6151.2618485781995, 5687.402822703557, 5781.71091445426, 5859.472647461664, 5862.501687356537, 5864.530837532987, 5858.619189752652, 5906.911396328973, 5908.350081491032, 5914.556408269311, 5947.9702601486715, 5856.970715146482, 5858.941410585925, 5892.0, 5913.852153696106, 5916.615419997686, 5808.30300363959, 5877.382874798084, 5881.088431293157, 5903.0260007099205, 5920.0, 4542.886427839265, 4567.018091110361, 4578.9771051144535, 4582.564656357641, 4616.884577885579, 4078.9592104079175, 4087.4481944937647, 4096.5903409658795, 4101.52832424275, 4135.338345864648, 5414.972925135428, 5540.836424350931, 5592.273004509388, 5605.0751625982075, 5618.438156184349, 5777.768889244468, 5856.736446859902, 5861.88276234473, 5863.179154918314, 5910.7931222407005, 5486.588505861996, 5502.477264659851, 5510.421405752419, 5531.419200983839, 5561.58288128392, 4946.3817022872145, 4949.183384741477, 5026.0, 5040.445550989388, 5049.091163590516, 6156.076588511759, 6216.751329946842, 6224.564280500322, 6241.407066328691, 6241.812745617548, 5974.462298392991, 5992.43071908168, 5994.490468310242, 6002.639841609517, 6055.1220073090035, 6058.27300723916, 6133.478654314434, 6144.569880108528, 6162.198914141133, 6215.0, 5874.853128671817, 5900.527957763283, 5904.0, 5917.467427931507, 5944.5838791284195, 5989.910151347821, 6011.368806275278, 6015.0, 6016.2479690038745, 6017.037274036134, 5709.143628455765, 5753.741081651252, 5827.067669172912, 5834.883302333845, 5879.088741245114, 5825.50483208932, 5844.298684157929, 5855.677937713466, 5861.648301101947, 5872.207252020922, 5670.659760414388, 5740.3685594585395, 5741.0, 5743.59794814359, 5748.9425105748405, 5683.460071293221, 5709.9714501427225, 5826.533877289722, 5828.213191219131, 5832.416758324133, 5623.775048998158, 5666.688332141772, 5749.252597162342, 5777.3933736957015, 5778.0], "unit": "score", "name": "dojo.html", "value": 5465.19787484589}, {"lowerIsBetter": false, "replicates": [539.0835579514826, 554.0442736279916, 564.7289301135452, 568.2045136808471, 589.9734511946963, 36424.63575364249, 36839.4474082889, 36886.26227475449, 36981.44527832081, 37033.25933481329, 39793.0, 40006.199876002465, 40355.0, 40529.0, 40554.0, 8427.283680887133, 8701.0, 8706.825863482727, 8723.520206388646, 8726.38915275931, 8552.743417697448, 8662.61018254177, 8669.523176225304, 8809.515476648781, 8881.600327985223, 8801.207891289783, 8810.207081362676, 8841.027486976427, 8871.423357481775, 8875.822483550324, 8719.258862996652, 8763.561821908943, 8786.297096232318, 8795.604197811113, 8804.207621314112, 8667.653293868238, 8777.517236551987, 8783.736487905373, 8812.955935220347, 8857.291416686714, 40696.38955415671, 40769.592304076876, 40931.590684093375, 40945.59054409448, 41025.0, 3240.330308493778, 3273.6726327367073, 3390.0507857799857, 3460.636633153533, 3542.2738338640515, 29788.0, 29888.85055574708, 29946.251343716365, 29986.70013299861, 31201.531977020364, 3299.2036405005765, 3345.481450375196, 3352.7317814574776, 3368.713659338959, 3418.3505134024454, 274926.0, 275472.999999998, 275757.0, 276798.0, 276863.0, 42733.0, 42786.14427711428, 42940.57059429428, 58090.419095809346, 58289.0, 40810.59189408127, 40833.79583102065, 40946.0, 41034.38448423276, 41239.58760412417, 14054.85945140556, 14163.433462661584, 14190.503332383318, 14192.148471091768, 14197.432102715982, 11110.61112861062, 11226.607068752555, 11227.887721122848, 11239.325640461599, 11292.096632269398, 14426.206558639165, 14586.708265834835, 14590.124592524482, 14603.488877889224, 14620.25063856974, 5673.0, 5915.3788852170765, 5995.970020149872, 5996.850078747979, 6002.4897883679505, 878.4202426398547, 895.7671005538607, 1045.5190612318268, 1107.1747113722072, 1130.1354463835173, 28520.71479285222, 28881.89620350392, 28940.42119157605, 29059.0, 29123.0, 948.6063283737171, 1032.9896701033044, 1057.9788404231872, 1059.9576016959236, 1096.2490693874722, 13264.469421223235, 13354.799678004838, 13543.796843047363, 13579.117357371739, 13581.0, 322081.0, 324990.0, 325809.0, 326824.0, 326963.0, 50245.0, 51280.48719512832, 51287.0, 51327.48672513227, 51771.74114129405, 1326.5357125006365, 1339.8124262603244, 1341.3025226881966, 1344.8050032745198, 1349.466960550577, 27559.724402756518, 28384.43231135407, 28568.285792855346, 28582.285442864093, 28697.139085827457, 47466.28800568063, 47743.522564773906, 47772.522274776806, 47787.76106119447, 47792.0, 287391.0, 288633.0, 288816.0, 288817.0, 289526.0, 32410.35179296448, 42373.0, 42433.36349954815, 42774.35838462488, 42874.571254288305, 42379.576204237565, 42977.78511107424, 43200.35199472073, 43748.56251437445, 43933.78033109814, 35742.0, 36310.45534316934, 36947.63052369442, 38600.80699596484, 39401.60598393979, 34156.31687366289, 38263.42604860874, 38697.226055479296, 39392.80303598464, 40163.79918100391, 33422.832885835414, 33997.49003764896, 34130.14674633154, 34132.317353653285, 34166.487502686985, 29970.850145749133, 30588.235294116934, 31484.842575786974, 31517.212069697525, 31630.36739265248, 24441.755582443948, 26445.60331594989, 26698.0, 26791.732082679704, 27103.392423287693], "unit": "score", "name": "ext.html", "value": 17843.65326523926}, {"lowerIsBetter": false, "replicates": [365.6982989034047, 368.8413981987746, 370.6608453265262, 371.5949614919737, 374.7283219665741, 16510.59659928906, 17339.826601733996, 17590.208440620172, 18174.545636359082, 18333.358332458367, 11091.057260132879, 11105.167112466557, 11127.443627818617, 11932.105092118083, 16120.838791612081, 4814.590759785414, 4928.6303527235605, 4957.182064959277, 5360.115580929142, 5372.7313634318125, 5366.4633536646215, 5453.23654688344, 5453.7818487260465, 5454.7272636368225, 5457.126859702448, 5148.099082660519, 5257.421683614818, 5352.437994010612, 5395.838124856261, 5415.566754659618, 5217.0, 5263.289455923439, 5264.736763161826, 5269.710165940872, 5291.417944026173, 4978.626603004789, 5116.360454943132, 5150.793968241303, 5157.149070403379, 5164.173732202867, 11976.221545599508, 12416.068794840423, 12432.378381081, 12496.0, 19688.606227875505, 1957.5889063296581, 1961.9607607847906, 2046.3247128447726, 2078.137572907257, 2194.297824696098, 9187.173154416068, 9437.858432123523, 9513.143817056498, 11868.643940681794, 12087.335196564185, 1637.6459337769497, 1652.6777278430711, 1657.734762438004, 1683.4444633270991, 1699.7535357373235, 59914.70042649803, 62996.68501657462, 64716.0, 64814.46298759089, 65370.67314663396, 10195.643152489627, 10232.59069637206, 10243.94878025605, 10352.068313851789, 10352.741181470374, 13420.261885596386, 13426.395812188475, 13462.124961877447, 13462.259575723234, 13542.119762215425, 7504.399648028254, 7577.029972207156, 7591.164971853092, 7606.47870425906, 7618.923810761932, 7291.781246562611, 7301.0, 7330.376917962033, 7331.560106393634, 7407.8148046299275, 4387.407699960529, 4473.373727678128, 4476.552344765498, 4486.3494793254795, 4497.2129877271345, 1800.1359347513267, 1834.8073452287317, 1835.9908200459179, 1851.3520267906395, 1854.6105317883125, 439.88562973627086, 456.6894511732054, 459.82066993872417, 461.1514812744566, 474.78160046379054, 10553.261271710906, 10960.835587466194, 11097.33415995043, 11207.327560346404, 11315.15136364776, 444.7176043212583, 451.7515366548385, 455.6354916067133, 458.59414418240095, 472.08886848382264, 5874.500667443315, 5923.585349025613, 6015.729292181775, 6089.451949324582, 6117.327094019654, 566725.0, 569890.0, 572421.0, 585375.0, 586134.0, 81654.59172704098, 82017.58991205125, 82987.17012829916, 84472.57763711266, 86398.56800715956, 475.4508542633264, 483.2533735378848, 486.6860874735776, 487.2812601412888, 1076.9784604308027, 14742.778858316919, 15530.689386212438, 15564.84435155634, 15763.369465221722, 15879.603009924844, 35162.824185878904, 35365.64634353624, 35676.0, 36610.63389366179, 37170.25659486849, 45831.77084114558, 46188.0, 47426.05147896954, 47983.520164797905, 50932.49067509278, 8889.19997200255, 9591.42451452915, 9667.419954802734, 9795.314327997257, 9807.9019209807, 16107.597310066873, 17456.47630571034, 17563.82436175622, 17772.644547109245, 18059.458216253533, 13494.122882012638, 13705.314734263446, 13817.240051797247, 13865.722685546434, 14187.716245675236, 14039.438422462987, 14310.284485775877, 14340.856591433952, 14355.856441435451, 14419.639509012359, 14410.711785764017, 14556.563303100924, 14601.12393256408, 14669.706605868036, 15313.693726125191, 9185.816283674156, 9227.492487913229, 9636.71089867305, 9644.324897257125, 9655.324127311022, 9876.654317098866, 12406.751864962469, 12776.169548979287, 13291.53479628247, 14148.363323650254], "unit": "score", "name": "jquery.html", "value": 8036.203817422633}, {"lowerIsBetter": false, "replicates": [1472.9852701472983, 1478.430804140406, 1628.4626073395773, 1764.9558761030964, 1776.4049043570403, 7249.637518124099, 7357.963210183948, 7361.852762944744, 7496.962515187416, 7688.077430708309, 7579.431542634297, 7603.961980190105, 7720.459567830254, 7720.884186737204, 7741.264579864918, 2209.723784526934, 2249.977500224993, 2253.6056190166737, 2255.977440225593, 2257.5033492631596, 2268.897899594514, 2286.7941885230325, 2339.040993192791, 2339.883005849709, 2346.7066616672914, 2226.3432287475234, 2258.6386178211487, 2286.4855407533337, 2287.2452090809993, 2287.942801429961, 2163.0915015693445, 2257.2099765082253, 2279.8062164715852, 2283.1552325639536, 2287.370972982435, 2264.1396269417683, 2264.1509433962265, 2274.5564614899936, 2276.8520046196945, 2277.1119263487253, 7467.775966721007, 7484.887726684104, 7491.7003319867135, 7610.353119984808, 7615.086189657221, 4583.702059366164, 4615.607673347769, 4676.392069031038, 4678.929816052728, 4697.084068606623, 2803.6635603727477, 2885.4084912592866, 2899.347646779479, 2915.4460652475964, 2939.441506113853, 861.2291998661226, 871.4640496094919, 896.789254525186, 913.6710784117712, 927.3091546797679, 9981.451020193887, 10138.594456221743, 10153.187744980383, 10185.388876667424, 10195.0, 4147.543770185277, 4179.958200418017, 4227.408162857202, 4257.318828987347, 4334.068175342317, 8915.866262006075, 8951.820963580692, 8958.417702849294, 8983.0, 8984.32617553686, 4580.931286030712, 4594.609458196024, 4595.4025976622825, 4599.701019433726, 4606.930896036562, 4153.0, 4229.830806767694, 4236.894077648083, 4266.552012038754, 4267.253230684618, 3576.4277715565386, 3585.4442561403025, 3601.045722883438, 3601.549806274216, 3652.41561350188, 1170.052257671289, 1194.988050119505, 1202.8736982616874, 1204.10895937006, 1211.3398197982121, 931.0828833598899, 937.4656445825814, 1034.4103860799423, 1069.9679009629724, 1228.6805430587933, 6662.200535935719, 6665.333466653393, 6685.431738302202, 6688.33116688337, 6720.361565651256, 1095.8246680531076, 1107.4075369677184, 1122.1583812140893, 1124.6963319903743, 1146.6961255267195, 5242.895142097137, 5294.2852714883, 5302.761375738101, 5316.548093412026, 5325.786968521215, 8724.607392667345, 8768.868466973001, 8834.381593288537, 8957.372983891066, 8965.686200982935, 8896.065913079034, 8937.597808098652, 9055.637774488947, 9125.406848554823, 9147.405418647766, 6714.529982901247, 6812.046313516112, 7243.818904527324, 7294.416446684252, 7313.451491138186, 6866.4506839453725, 6869.313068693291, 6906.896396553955, 6931.618760967994, 6933.5493192942295, 8141.307988820779, 8368.330533557422, 8430.494170349799, 8480.660773568989, 8543.829123417621, 8062.1534738853925, 8122.878156827534, 8200.38497112707, 8222.301104406193, 8288.295494883003, 6749.291324410866, 6822.829429264308, 6866.553674011173, 6943.062686537455, 6952.756653517103, 7515.736949206752, 7527.2849079337375, 7564.4704870658525, 7567.962160189164, 7587.0, 6842.965785171242, 6878.415334696607, 6917.23910369869, 7034.402075823612, 7080.929190708027, 6534.1178940844275, 6563.606183628997, 6626.171728533934, 6653.334666533308, 6757.966210168917, 6088.847778805565, 6441.291457939717, 6496.1230233919705, 6505.21937367519, 6525.249596296487, 5833.0, 5839.474447299764, 5843.29880414353, 5860.443257890493, 5882.470577648032, 5763.337216220189, 5853.268341457318, 5856.94143058564, 5878.823635290948, 5909.084091965752], "unit": "score", "name": "mootools.html", "value": 4347.398644653432}, {"lowerIsBetter": false, "replicates": [345.70269568171375, 346.6931765387631, 349.18291198595267, 352.8958957107653, 357.2194754461501, 6224.221972253468, 6247.37526247375, 6285.402886725759, 6314.873702525947, 6332.936670633292, 6086.452219300262, 6227.28386235583, 6231.626102433857, 6234.719437625306, 6257.4368306852375, 4777.593904518103, 4784.162771514988, 4805.303231031498, 4809.567138957493, 4812.0, 4820.1082799682, 4823.951760482385, 4824.662273640846, 4829.830955916544, 4838.806447742086, 4707.246840505502, 4769.141554520185, 4796.3764710587575, 4800.639952003597, 4807.591354734852, 4789.952100479003, 4795.424549054118, 4796.232602783538, 4800.495947925452, 4800.639952003578, 4198.0, 4211.220924129046, 4214.093969796478, 4248.468941382327, 4250.192463431938, 5921.200637913869, 5955.434233747787, 5965.313988891289, 5974.3726908674835, 5992.520598352121, 5385.057614917374, 5556.249906262643, 5579.0515612346, 5586.134149206839, 5590.133529302964, 6606.768763093269, 6633.93366066338, 6634.341414646338, 6639.738449694542, 6664.900026499607, 4651.139539185261, 4652.046330502273, 4654.162250794856, 4657.11514812188, 4672.112298663277, 6745.763898263586, 6962.303769623048, 6966.4078553323025, 6969.372756451944, 6971.058907047534, 4675.438947326343, 4702.412198475191, 4708.552687494683, 4710.764461776897, 4713.057388522309, 4738.265568836836, 4752.524747525289, 4759.809607615656, 4761.2382018876815, 4774.594159496412, 5523.088690366045, 5524.309461317335, 5527.336719593594, 5530.557555395559, 5530.6128571000445, 6556.737730490727, 6700.765473208415, 6710.832229194211, 6715.227748808852, 6718.865622687519, 1634.6648936967892, 1667.6247844235193, 1669.35729744047, 1676.4551520755558, 1677.0, 1090.8363745438248, 1111.5498223219608, 1121.5345631562973, 1122.4163435013747, 1124.9887501125047, 6213.596116252429, 6222.439980401786, 6240.781572645026, 6243.906341404882, 6258.9046916789375, 6722.29415911332, 6741.7303307868115, 6790.253072162058, 6797.184337879387, 6804.965975170093, 6063.878722425615, 6179.598326108789, 6200.255969283625, 6232.189815323979, 6232.407921247474, 3702.500162478085, 3714.0343510686876, 3718.4236443351324, 3720.479132921393, 3723.7579557328686, 6975.0, 7071.61106139157, 7101.644917754092, 7106.964465177641, 7111.217766045731, 6702.36327548882, 6916.308369163144, 6940.9652951734915, 6949.131358580177, 6956.339147781054, 6981.511294209458, 7010.894836577456, 7029.859402811915, 7035.155781306174, 7044.084269044992, 6750.459963202834, 6771.729130834711, 6771.796846094625, 6776.15298087739, 6781.966090169518, 6527.706253218712, 6597.934020659924, 6665.200175978914, 6667.766628167991, 6670.466362690876, 7022.719091236293, 7036.331548502884, 7068.11648543932, 7069.434445244471, 7074.3633073023675, 4686.601638860735, 4687.32033855096, 4687.695299805502, 4688.249880019181, 4698.553637404441, 6685.130932978682, 6720.462363011045, 6730.125083739278, 6761.256261811099, 6967.268436814062, 6717.664116794239, 6723.932760672331, 6734.124563806674, 6764.323567643196, 6772.0857684212, 6677.198736151693, 6697.129373181455, 6735.888578384708, 6743.730250789913, 6750.9873518972545, 6488.9026664601015, 6548.377904099103, 6590.5716128451495, 6612.0743095965645, 6621.569597976116, 6719.697613607302, 6746.291639377795, 6762.459003279628, 6777.390034896883, 6782.389584937182, 6586.37429444202, 6598.703058362289, 6601.603903765789, 6601.702923368556, 6606.933930660824], "unit": "score", "name": "prototype.html", "value": 5001.727426779575}, {"lowerIsBetter": false, "replicates": [725.2964624314416, 739.3013602145971, 785.1363500149836, 788.7791418402848, 870.9825803483926, 12397.69005774855, 12466.501339946402, 12701.682457938556, 13646.522371716992, 13689.110207836484, 11385.203035787497, 11454.599089031886, 11508.884911150886, 11526.0, 11529.0, 810.6433169405443, 826.4008593769499, 830.5515021888173, 831.6216121664659, 833.2584000239791, 821.5686764448652, 837.4138103327695, 842.6882053640162, 843.1484200957046, 845.2646197807916, 875.763543843162, 883.2227639677108, 883.7083762358438, 885.8671199320089, 886.7960369115108, 861.7802460372596, 870.6996086350199, 870.921617054465, 870.9520976346298, 875.3609864798701, 640.6508452893185, 644.3331152257391, 645.0453329072951, 645.3836586060302, 648.0150171738977, 12191.817122743167, 12213.206141600856, 12213.45039473226, 12270.32513211773, 12320.691982700415, 11810.704732381673, 12040.879591204151, 12222.388880555936, 12260.44827982743, 12383.876161238362, 9795.657151999645, 9861.0, 9979.700608981742, 9993.25050621199, 10126.139278161365, 3425.143714071482, 3519.665631764978, 3548.840302186408, 3570.107473131717, 3601.891943241707, 48565.757171214274, 49642.255366169535, 49758.0, 49832.50167498351, 49854.252186217236, 8755.387122901464, 8892.688755893512, 9096.947525999047, 9332.626694932127, 9509.239260859114, 17612.29550817953, 17668.911655441898, 17727.73408398875, 17771.82228177702, 17781.555461113574, 6396.904046439307, 6900.3099690031595, 6953.79138625842, 7081.539699919489, 7109.1824440189, 3963.980180099081, 4017.859374921922, 4056.2090392373793, 4092.283850326181, 4108.6918481114035, 3006.4738670732536, 3014.231371000381, 3027.5307327364294, 3033.833139177322, 3041.847907604655, 503.40849501835197, 522.7699812082672, 523.1002675398308, 523.3170712220589, 533.714462762428, 608.6926102318301, 611.0477025902288, 613.9263288405419, 635.7012204263989, 658.0786898342263, 7884.408669349822, 8106.594670266463, 8141.307988820898, 8143.592820358958, 8363.749087527383, 495.86611614863784, 503.40598094248406, 505.3354838387582, 529.2590373477162, 533.8772082421065, 3800.600936901641, 3801.6388443097862, 3829.9808500957874, 3849.4418309345547, 3868.0716628009077, 48127.518724812304, 48146.759266203444, 48153.03693926172, 48448.5155148444, 49772.751136244086, 48568.5143148564, 48939.75530122327, 48952.26571601429, 49573.75213123983, 49917.25124123141, 44193.33709994417, 44196.55803441989, 44597.55402445934, 44810.0, 45112.3233151503, 7678.1170165431695, 7693.576853273124, 7757.224277572198, 7865.842683146419, 7995.440889026584, 30786.384272314877, 31133.221669458446, 31356.52965205478, 31796.205094872814, 31863.84068079645, 36882.26235475329, 38962.2985816347, 39378.212435751695, 40472.0, 40784.79607601943, 9777.462239576891, 9887.110160085627, 10117.949410252902, 10361.63734269297, 10936.0, 10694.73263168427, 11060.612878549213, 11120.388378638929, 11191.776164476827, 11210.383428911487, 10009.449480278363, 10387.740306492398, 10680.145588352758, 10729.463526823783, 10739.624113156002, 11055.22613417053, 11136.109111270916, 11171.553137874394, 11171.776564468502, 11497.367644779282, 9851.605935762489, 10014.849777253492, 10122.051576954, 10268.281220314806, 10431.478426078818, 8716.520591367536, 8812.250958668585, 9020.594073266588, 9024.363487678544, 9127.634894604142, 8616.535189018008, 8653.307735381028, 8722.694705685271, 8819.735407937773, 8846.513441760508], "unit": "score", "name": "yui.html", "value": 5957.673568444213}], "name": "dromaeo_css", "value": 6830.205841602341}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 106.0, 111.0, 115.0, 110.0, 122.0, 109.0, 110.0, 112.0, 111.0], "unit": "score", "name": "ai-astar", "value": 111.8}, {"lowerIsBetter": true, "replicates": [160.0, 127.0, 155.0, 158.0, 126.0, 152.0, 153.0, 129.0, 173.0, 129.0], "unit": "score", "name": "audio-beat-detection", "value": 146.2}, {"lowerIsBetter": true, "replicates": [164.0, 164.0, 163.0, 164.0, 161.0, 161.0, 160.0, 164.0, 168.0, 162.0], "unit": "score", "name": "audio-dft", "value": 163.1}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 73.0, 72.0, 72.0, 142.0, 72.0, 83.0, 75.0, 72.0], "unit": "score", "name": "audio-fft", "value": 80.5}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 153.0, 96.0, 98.0, 95.0, 152.0, 96.0, 97.0, 137.0], "unit": "score", "name": "audio-oscillator", "value": 111.7}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 111.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.0}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 149.0, 148.0, 147.0, 147.0, 149.0, 146.0, 149.0, 148.0], "unit": "score", "name": "imaging-darkroom", "value": 147.7}, {"lowerIsBetter": true, "replicates": [97.0, 96.0, 96.0, 102.0, 101.0, 101.0, 101.0, 96.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 98.2}, {"lowerIsBetter": true, "replicates": [62.0, 60.0, 60.0, 60.0, 61.0, 63.0, 60.0, 59.0, 57.0, 61.0], "unit": "score", "name": "json-parse-financial", "value": 60.3}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 67.0, 69.0, 67.0, 69.0, 67.0, 67.0, 68.0, 70.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.8}, {"lowerIsBetter": true, "replicates": [84.0, 88.0, 94.0, 86.0, 88.0, 93.0, 87.0, 87.0, 87.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 88.0}, {"lowerIsBetter": true, "replicates": [130.0, 116.0, 123.0, 121.0, 118.0, 125.0, 119.0, 123.0, 129.0, 121.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.5}, {"lowerIsBetter": true, "replicates": [143.0, 151.0, 154.0, 160.0, 161.0, 152.0, 161.0, 151.0, 150.0, 139.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 152.2}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 69.0, 68.0, 71.0, 68.0, 71.0, 67.0, 74.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 69.3}], "name": "kraken", "value": 1529.3}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.42005692281936], "unit": "score", "name": "Richards", "value": 210.8588460000001}, {"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": [5208.333333333333], "unit": "score", "name": "Decrypt", "value": 51.10675199999996}, {"lowerIsBetter": false, "replicates": [1060.4453870625662], "unit": "score", "name": "RayTrace", "value": 697.809627}, {"lowerIsBetter": false, "replicates": [705.2186177715091], "unit": "score", "name": "Earley", "value": 945.0445340000002}, {"lowerIsBetter": false, "replicates": [12345.67901234568], "unit": "score", "name": "Boyer", "value": 53.98350299999995}, {"lowerIsBetter": false, "replicates": [25150.0], "unit": "score", "name": "RegExp", "value": 36.22206759443341}, {"lowerIsBetter": false, "replicates": [678.42605156038], "unit": "score", "name": "Splay", "value": 120.11773399999996}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 21131.178077779565}]} 07:05:43 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,43]]", "result": "21131.18"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,43]]", "result": "1529.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,43]]", "result": "6830.21"}}} 07:05:43 INFO - Return code: 0 07:05:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 07:05:43 INFO - # TBPL SUCCESS # 07:05:43 INFO - Running post-action listener: _resource_record_post_action 07:05:43 INFO - Running post-run listener: _resource_record_post_run 07:05:44 INFO - Total resource usage - Wall time: 1230s; CPU: 13.0%; Read bytes: 0; Write bytes: 553795584; Read time: 0; Write time: 499772 07:05:44 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 235130880; Read time: 0; Write time: 344312 07:05:44 INFO - run-tests - Wall time: 1214s; CPU: 13.0%; Read bytes: 0; Write bytes: 318390272; Read time: 0; Write time: 155436 07:05:44 INFO - Running post-run listener: _upload_blobber_files 07:05:44 INFO - Blob upload gear active. 07:05:44 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 07:05:44 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 07:05:44 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'] 07:05:44 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 07:05:45 INFO - (blobuploader) - INFO - Open directory for files ... 07:05:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:05:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:05:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:05:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:05:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:05:45 INFO - (blobuploader) - INFO - Done attempting. 07:05:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:05:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:05:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:05:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:05:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:05:46 INFO - (blobuploader) - INFO - Done attempting. 07:05:46 INFO - (blobuploader) - INFO - Iteration through files over. 07:05:46 INFO - Return code: 0 07:05:46 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 07:05:46 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 07:05:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b60d3e5934b8cb4b04c8f2315bf5acdbbfe18507b7598778dc1a7323ef6e4508da72da3fd382d89d0484c2d392afc75750a9324a01c81c96bc9851137f4f2166", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6a6c22ca5500bfa95b7c83288d9fd3767da87100c6b3fd0fec65416d662c052efba451835829b2eef3e5cd3d0e597e7f63bb1f43208451e460d09b0d9c8df019"} 07:05:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 07:05:46 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 07:05:46 INFO - Contents: 07:05:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b60d3e5934b8cb4b04c8f2315bf5acdbbfe18507b7598778dc1a7323ef6e4508da72da3fd382d89d0484c2d392afc75750a9324a01c81c96bc9851137f4f2166", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6a6c22ca5500bfa95b7c83288d9fd3767da87100c6b3fd0fec65416d662c052efba451835829b2eef3e5cd3d0e597e7f63bb1f43208451e460d09b0d9c8df019"} 07:05:46 INFO - Copying logs to upload dir... 07:05:46 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1276.018202 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-01-04 07:05:46.546266) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-04 07:05:46.549887) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500 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/b60d3e5934b8cb4b04c8f2315bf5acdbbfe18507b7598778dc1a7323ef6e4508da72da3fd382d89d0484c2d392afc75750a9324a01c81c96bc9851137f4f2166", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6a6c22ca5500bfa95b7c83288d9fd3767da87100c6b3fd0fec65416d662c052efba451835829b2eef3e5cd3d0e597e7f63bb1f43208451e460d09b0d9c8df019"} build_url:https://queue.taskcluster.net/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011350 build_url: 'https://queue.taskcluster.net/v1/task/e9g4dkN8TxOP8Zvbfqi-Kg/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/b60d3e5934b8cb4b04c8f2315bf5acdbbfe18507b7598778dc1a7323ef6e4508da72da3fd382d89d0484c2d392afc75750a9324a01c81c96bc9851137f4f2166", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6a6c22ca5500bfa95b7c83288d9fd3767da87100c6b3fd0fec65416d662c052efba451835829b2eef3e5cd3d0e597e7f63bb1f43208451e460d09b0d9c8df019"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-04 07:05:46.574573) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 07:05:46.574901) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1451918271.249066-855319500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004750 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 07:05:46.661197) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-04 07:05:46.661520) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-04 07:05:46.661815) ========= ========= Total master_lag: 0.21 =========