builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-010 starttime: 1452459324.52 results: success (0) buildid: 20160110073242 builduid: 0401266877d94b94b16fc9749d90cf0c revision: 359f86fecbc2a6531d9018fb81a74fd26502f5a1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:24.519568) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:24.520195) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:24.520539) ========= 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=eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006454 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:24.555970) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:24.556293) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:24.576497) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:24.576895) ========= 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=eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004715 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:24.626610) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:24.627276) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:24.628142) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:24.628903) ========= 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=eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-10 12:55:26-- 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% 220M=0s 2016-01-10 12:55:26 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.153531 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:24.798292) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:24.798645) ========= 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=eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020781 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:24.848773) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-10 12:55:24.849177) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 359f86fecbc2a6531d9018fb81a74fd26502f5a1 --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 359f86fecbc2a6531d9018fb81a74fd26502f5a1 --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=eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-10 12:55:26,842 truncating revision to first 12 chars 2016-01-10 12:55:26,842 Setting DEBUG logging. 2016-01-10 12:55:26,842 attempt 1/10 2016-01-10 12:55:26,842 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/359f86fecbc2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-10 12:55:27,977 unpacking tar archive at: mozilla-inbound-359f86fecbc2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.446709 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-10 12:55:26.310422) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:26.311177) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:26.321374) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:26.321659) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-10 12:55:26.322115) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-01-10 12:55:26.322445) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:55:28 INFO - MultiFileLogger online at 20160110 12:55:28 in /builds/slave/test-pgo 12:55:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 12:55:28 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 12:55:28 INFO - {'append_to_log': False, 12:55:28 INFO - 'base_work_dir': '/builds/slave/test-pgo', 12:55:28 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 12:55:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 12:55:28 INFO - 'branch': 'Mozilla-Inbound', 12:55:28 INFO - 'buildbot_json_path': 'buildprops.json', 12:55:28 INFO - 'config_files': ('talos/linux_config.py',), 12:55:28 INFO - 'default_actions': ('clobber', 12:55:28 INFO - 'read-buildbot-config', 12:55:28 INFO - 'download-and-extract', 12:55:28 INFO - 'populate-webroot', 12:55:28 INFO - 'create-virtualenv', 12:55:28 INFO - 'install', 12:55:28 INFO - 'run-tests'), 12:55:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:55:28 INFO - 'download_minidump_stackwalk': True, 12:55:28 INFO - 'download_symbols': 'ondemand', 12:55:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:55:28 INFO - 'tooltool.py': '/tools/tooltool.py', 12:55:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:55:28 INFO - '/tools/misc-python/virtualenv.py')}, 12:55:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:55:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:55:28 INFO - 'installer_path': 'installer.exe', 12:55:28 INFO - 'log_level': 'info', 12:55:28 INFO - 'log_name': 'talos', 12:55:28 INFO - 'log_to_console': True, 12:55:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:55:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:55:28 INFO - 'opt_config_files': (), 12:55:28 INFO - 'pip_index': False, 12:55:28 INFO - 'sps_profile': False, 12:55:28 INFO - 'sps_profile_interval': 0, 12:55:28 INFO - 'suite': 'dromaeojs', 12:55:28 INFO - 'system_bits': '32', 12:55:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:55:28 INFO - 'title': 'talos-linux64-ix-010', 12:55:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:55:28 INFO - 'use_talos_json': True, 12:55:28 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 12:55:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:55:28 INFO - 'work_dir': 'build'} 12:55:28 INFO - ##### 12:55:28 INFO - ##### Running clobber step. 12:55:28 INFO - ##### 12:55:28 INFO - Running pre-action listener: _resource_record_pre_action 12:55:28 INFO - Running main action method: clobber 12:55:28 INFO - rmtree: /builds/slave/test-pgo/build 12:55:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 12:55:28 INFO - Running post-action listener: _resource_record_post_action 12:55:28 INFO - ##### 12:55:28 INFO - ##### Running read-buildbot-config step. 12:55:28 INFO - ##### 12:55:28 INFO - Running pre-action listener: _resource_record_pre_action 12:55:28 INFO - Running main action method: read_buildbot_config 12:55:28 INFO - Using buildbot properties: 12:55:28 INFO - { 12:55:28 INFO - "project": "", 12:55:28 INFO - "product": "firefox", 12:55:28 INFO - "script_repo_revision": "production", 12:55:28 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 12:55:28 INFO - "repository": "", 12:55:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 12:55:28 INFO - "buildid": "20160110073242", 12:55:28 INFO - "pgo_build": "True", 12:55:28 INFO - "basedir": "/builds/slave/test-pgo", 12:55:28 INFO - "buildnumber": 1427, 12:55:28 INFO - "slavename": "talos-linux64-ix-010", 12:55:28 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:55:28 INFO - "platform": "ubuntu64_hw", 12:55:28 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 12:55:28 INFO - "branch": "mozilla-inbound", 12:55:28 INFO - "repo_path": "integration/mozilla-inbound", 12:55:28 INFO - "revision": "359f86fecbc2a6531d9018fb81a74fd26502f5a1", 12:55:28 INFO - "stage_platform": "linux64-pgo", 12:55:28 INFO - "builduid": "0401266877d94b94b16fc9749d90cf0c", 12:55:28 INFO - "slavebuilddir": "test" 12:55:28 INFO - } 12:55:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 12:55:28 INFO - Running post-action listener: _resource_record_post_action 12:55:28 INFO - ##### 12:55:28 INFO - ##### Running download-and-extract step. 12:55:28 INFO - ##### 12:55:28 INFO - Running pre-action listener: _resource_record_pre_action 12:55:28 INFO - Running main action method: download_and_extract 12:55:28 INFO - mkdir: /builds/slave/test-pgo/build/tests 12:55:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:55:28 INFO - https://queue.taskcluster.net/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 12:55:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/test_packages.json 12:55:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/test_packages.json 12:55:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 12:55:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 12:55:32 INFO - Downloaded 1302 bytes. 12:55:32 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 12:55:32 INFO - Using the following test package requirements: 12:55:32 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 12:55:32 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:55:32 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:55:32 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:55:32 INFO - u'jsshell-linux-x86_64.zip'], 12:55:32 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:55:32 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:55:32 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 12:55:32 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:55:32 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:55:32 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:55:32 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:55:32 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:55:32 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:55:32 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 12:55:32 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:55:32 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:55:32 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:55:32 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')]} 12:55:32 INFO - https://queue.taskcluster.net/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:55:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:55:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:55:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/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 12:55:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/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 12:55:35 INFO - Downloaded 24028685 bytes. 12:55:35 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 12:55:35 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:55:35 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 12:55:36 INFO - Return code: 0 12:55:36 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 12:55:36 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')]} 12:55:36 INFO - https://queue.taskcluster.net/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:55:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:55:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:55:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/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 12:55:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/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 12:55:39 INFO - Downloaded 24028685 bytes. 12:55:39 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 12:55:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:55:39 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 12:55:40 INFO - Return code: 0 12:55:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:55:40 INFO - https://queue.taskcluster.net/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:55:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 12:55:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 12:55:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/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 12:55:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/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 12:55:43 INFO - Downloaded 11132285 bytes. 12:55:43 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 12:55:43 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 12:55:43 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 12:55:43 INFO - Return code: 0 12:55:43 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')]} 12:55:43 INFO - https://queue.taskcluster.net/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:55:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 12:55:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 12:55:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/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 12:55:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A1R-PlgwTIaeE5HSswFjcQ/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 12:55:46 INFO - Downloaded 55902896 bytes. 12:55:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 12:55:46 INFO - mkdir: /builds/slave/test-pgo/properties 12:55:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 12:55:46 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 12:55:46 INFO - Contents: 12:55:46 INFO - build_url:https://queue.taskcluster.net/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 12:55:46 INFO - Running post-action listener: _resource_record_post_action 12:55:46 INFO - Running post-action listener: set_extra_try_arguments 12:55:46 INFO - ##### 12:55:46 INFO - ##### Running populate-webroot step. 12:55:46 INFO - ##### 12:55:46 INFO - Running pre-action listener: _resource_record_pre_action 12:55:46 INFO - Running main action method: populate_webroot 12:55:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:55:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:55:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:55:46 INFO - '--tppagecycles', 12:55:46 INFO - '7'], 12:55:46 INFO - 'tests': ['tsvgm']}}, 12:55:46 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 12:55:46 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 12:55:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:55:46 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 12:55:46 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 12:55:46 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 12:55:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:55:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:46 INFO - 'talos_options': ['--e10s'], 12:55:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:55:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:46 INFO - 'tests': ['damp', 'tps']}, 12:55:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:46 INFO - 'talos_options': ['--e10s'], 12:55:46 INFO - 'tests': ['damp', 'tps']}, 12:55:46 INFO - 'g3': {'tests': ['dromaeo_dom']}, 12:55:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:55:46 INFO - 'other': {'tests': ['a11yr', 12:55:46 INFO - 'ts_paint', 12:55:46 INFO - 'tpaint', 12:55:46 INFO - 'sessionrestore', 12:55:46 INFO - 'sessionrestore_no_auto_restore']}, 12:55:46 INFO - 'other-e10s': {'talos_options': ['--e10s'], 12:55:46 INFO - 'tests': ['a11yr', 12:55:46 INFO - 'ts_paint', 12:55:46 INFO - 'tpaint', 12:55:46 INFO - 'sessionrestore', 12:55:46 INFO - 'sessionrestore_no_auto_restore']}, 12:55:46 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 12:55:46 INFO - 'tests': ['a11yr', 12:55:46 INFO - 'ts_paint', 12:55:46 INFO - 'tpaint', 12:55:46 INFO - 'sessionrestore', 12:55:46 INFO - 'sessionrestore_no_auto_restore']}, 12:55:46 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 12:55:46 INFO - 'tests': ['a11yr', 12:55:46 INFO - 'ts_paint', 12:55:46 INFO - 'tpaint', 12:55:46 INFO - 'sessionrestore', 12:55:46 INFO - 'sessionrestore_no_auto_restore']}, 12:55:46 INFO - 'other_l64': {'tests': ['a11yr', 12:55:46 INFO - 'ts_paint', 12:55:46 INFO - 'tpaint', 12:55:46 INFO - 'sessionrestore', 12:55:46 INFO - 'sessionrestore_no_auto_restore']}, 12:55:46 INFO - 'other_nol64': {'tests': ['a11yr', 12:55:46 INFO - 'ts_paint', 12:55:46 INFO - 'tpaint', 12:55:46 INFO - 'sessionrestore', 12:55:46 INFO - 'sessionrestore_no_auto_restore']}, 12:55:46 INFO - 'svgr': {'tests': ['tsvgx', 12:55:46 INFO - 'tsvgr_opacity', 12:55:46 INFO - 'tart', 12:55:46 INFO - 'tscrollx', 12:55:46 INFO - 'cart']}, 12:55:46 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 12:55:46 INFO - 'tests': ['tsvgx', 12:55:46 INFO - 'tsvgr_opacity', 12:55:46 INFO - 'tart', 12:55:46 INFO - 'tscrollx', 12:55:46 INFO - 'cart']}, 12:55:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:46 INFO - 'tests': ['tp5o']}, 12:55:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:46 INFO - 'talos_options': ['--e10s'], 12:55:46 INFO - 'tests': ['tp5o']}, 12:55:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:46 INFO - 'talos_options': ['--xperf_path', 12:55:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:55:46 INFO - 'tests': ['tp5n']}, 12:55:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:46 INFO - 'talos_options': ['--e10s', 12:55:46 INFO - '--xperf_path', 12:55:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:55:46 INFO - 'tests': ['tp5n']}}, 12:55:46 INFO - 'talos.zip': {'path': '', 12:55:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:55:46 INFO - Running post-action listener: _resource_record_post_action 12:55:46 INFO - ##### 12:55:46 INFO - ##### Running create-virtualenv step. 12:55:46 INFO - ##### 12:55:46 INFO - Running pre-action listener: _resource_record_pre_action 12:55:46 INFO - Running main action method: create_virtualenv 12:55:46 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 12:55:46 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 12:55:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 12:55:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:55:46 INFO - Using real prefix '/usr' 12:55:46 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 12:55:47 INFO - Installing distribute.............................................................................................................................................................................................done. 12:55:49 INFO - Installing pip.................done. 12:55:49 INFO - Return code: 0 12:55:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 12:55:49 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')]} 12:55:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:49 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')]} 12:55:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:49 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 0x7fe5c546bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1172230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12047f0>, '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 0x11702d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1170760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', '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 12:55:49 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 12:55:49 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 12:55:49 INFO - Using env: {'DISPLAY': ':0', 12:55:49 INFO - 'HOME': '/home/cltbld', 12:55:49 INFO - 'LANG': 'en_US.UTF-8', 12:55:49 INFO - 'LANGUAGE': 'en_US:en', 12:55:49 INFO - 'LOGNAME': 'cltbld', 12:55:49 INFO - 'MAIL': '/var/mail/cltbld', 12:55:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:49 INFO - 'MOZ_NO_REMOTE': '1', 12:55:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:49 INFO - 'NO_EM_RESTART': '1', 12:55:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:55:49 INFO - 'PWD': '/builds/slave/test-pgo', 12:55:49 INFO - 'SHELL': '/bin/bash', 12:55:49 INFO - 'SHLVL': '1', 12:55:49 INFO - 'TERM': 'linux', 12:55:49 INFO - 'TMOUT': '86400', 12:55:49 INFO - 'USER': 'cltbld', 12:55:49 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', 12:55:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:49 INFO - '_': '/tools/buildbot/bin/python'} 12:55:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:49 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 12:55:49 INFO - Cleaning up... 12:55:49 INFO - Return code: 0 12:55:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 12:55:49 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')]} 12:55:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:49 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')]} 12:55:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:49 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 0x7fe5c546bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1172230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12047f0>, '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 0x11702d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1170760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', '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 12:55:49 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 12:55:49 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 12:55:49 INFO - Using env: {'DISPLAY': ':0', 12:55:49 INFO - 'HOME': '/home/cltbld', 12:55:49 INFO - 'LANG': 'en_US.UTF-8', 12:55:49 INFO - 'LANGUAGE': 'en_US:en', 12:55:49 INFO - 'LOGNAME': 'cltbld', 12:55:49 INFO - 'MAIL': '/var/mail/cltbld', 12:55:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:49 INFO - 'MOZ_NO_REMOTE': '1', 12:55:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:49 INFO - 'NO_EM_RESTART': '1', 12:55:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:55:49 INFO - 'PWD': '/builds/slave/test-pgo', 12:55:49 INFO - 'SHELL': '/bin/bash', 12:55:49 INFO - 'SHLVL': '1', 12:55:49 INFO - 'TERM': 'linux', 12:55:49 INFO - 'TMOUT': '86400', 12:55:49 INFO - 'USER': 'cltbld', 12:55:49 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', 12:55:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:49 INFO - '_': '/tools/buildbot/bin/python'} 12:55:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:49 INFO - Downloading/unpacking psutil>=0.7.1 12:55:49 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 12:55:49 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 12:55:49 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 12:55:49 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 12:55:49 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 12:55:49 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 12:55:51 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 12:55:51 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 12:55:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 12:55:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:55:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:55:51 INFO - Installing collected packages: psutil 12:55:51 INFO - Running setup.py install for psutil 12:55:51 INFO - building 'psutil._psutil_linux' extension 12:55:51 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 12:55:51 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 12:55:51 INFO - building 'psutil._psutil_posix' extension 12:55:51 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 12:55:51 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 12:55:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:55:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:55:51 INFO - Successfully installed psutil 12:55:51 INFO - Cleaning up... 12:55:51 INFO - Return code: 0 12:55:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 12:55:51 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')]} 12:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:51 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')]} 12:55:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:51 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 0x7fe5c546bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1172230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12047f0>, '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 0x11702d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1170760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', '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 12:55:51 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 12:55:51 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 12:55:51 INFO - Using env: {'DISPLAY': ':0', 12:55:51 INFO - 'HOME': '/home/cltbld', 12:55:51 INFO - 'LANG': 'en_US.UTF-8', 12:55:51 INFO - 'LANGUAGE': 'en_US:en', 12:55:51 INFO - 'LOGNAME': 'cltbld', 12:55:51 INFO - 'MAIL': '/var/mail/cltbld', 12:55:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:51 INFO - 'MOZ_NO_REMOTE': '1', 12:55:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:51 INFO - 'NO_EM_RESTART': '1', 12:55:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:55:51 INFO - 'PWD': '/builds/slave/test-pgo', 12:55:51 INFO - 'SHELL': '/bin/bash', 12:55:51 INFO - 'SHLVL': '1', 12:55:51 INFO - 'TERM': 'linux', 12:55:51 INFO - 'TMOUT': '86400', 12:55:51 INFO - 'USER': 'cltbld', 12:55:51 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', 12:55:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:51 INFO - '_': '/tools/buildbot/bin/python'} 12:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:55:52 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 12:55:52 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 12:55:52 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 12:55:52 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 12:55:52 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 12:55:52 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 12:55:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:55:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 12:55:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:55:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:55:53 INFO - Installing collected packages: mozsystemmonitor 12:55:53 INFO - Running setup.py install for mozsystemmonitor 12:55:53 INFO - Successfully installed mozsystemmonitor 12:55:53 INFO - Cleaning up... 12:55:53 INFO - Return code: 0 12:55:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 12:55: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')]} 12:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55: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')]} 12:55:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55: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', '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 0x7fe5c546bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1172230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12047f0>, '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 0x11702d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1170760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', '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 12:55: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', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 12:55: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 blobuploader==1.2.4 12:55:53 INFO - Using env: {'DISPLAY': ':0', 12:55:53 INFO - 'HOME': '/home/cltbld', 12:55:53 INFO - 'LANG': 'en_US.UTF-8', 12:55:53 INFO - 'LANGUAGE': 'en_US:en', 12:55:53 INFO - 'LOGNAME': 'cltbld', 12:55:53 INFO - 'MAIL': '/var/mail/cltbld', 12:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:53 INFO - 'MOZ_NO_REMOTE': '1', 12:55:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:53 INFO - 'NO_EM_RESTART': '1', 12:55:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:55:54 INFO - 'PWD': '/builds/slave/test-pgo', 12:55:54 INFO - 'SHELL': '/bin/bash', 12:55:54 INFO - 'SHLVL': '1', 12:55:54 INFO - 'TERM': 'linux', 12:55:54 INFO - 'TMOUT': '86400', 12:55:54 INFO - 'USER': 'cltbld', 12:55:54 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', 12:55:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:54 INFO - '_': '/tools/buildbot/bin/python'} 12:55:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:54 INFO - Downloading/unpacking blobuploader==1.2.4 12:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:55 INFO - Downloading blobuploader-1.2.4.tar.gz 12:55:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 12:55:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:55:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:55:55 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 12:55:55 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 12:55:55 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 12:55:55 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 12:55:55 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 12:55:55 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 12:55:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 12:55:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 12:55:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:55: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 12:55: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 12:55: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 12:55: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 12:55: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 12:55: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 12:55:56 INFO - Downloading docopt-0.6.1.tar.gz 12:55:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 12:55:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 12:55:56 INFO - Installing collected packages: blobuploader, requests, docopt 12:55:56 INFO - Running setup.py install for blobuploader 12:55:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:55:56 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 12:55:56 INFO - Running setup.py install for requests 12:55:57 INFO - Running setup.py install for docopt 12:55:57 INFO - Successfully installed blobuploader requests docopt 12:55:57 INFO - Cleaning up... 12:55:57 INFO - Return code: 0 12:55:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 12:55: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')]} 12:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55: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')]} 12:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:57 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 0x7fe5c546bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1172230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12047f0>, '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 0x11702d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1170760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', '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 12:55:57 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 12:55:57 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 12:55:57 INFO - Using env: {'DISPLAY': ':0', 12:55:57 INFO - 'HOME': '/home/cltbld', 12:55:57 INFO - 'LANG': 'en_US.UTF-8', 12:55:57 INFO - 'LANGUAGE': 'en_US:en', 12:55:57 INFO - 'LOGNAME': 'cltbld', 12:55:57 INFO - 'MAIL': '/var/mail/cltbld', 12:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:57 INFO - 'MOZ_NO_REMOTE': '1', 12:55:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:57 INFO - 'NO_EM_RESTART': '1', 12:55:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:55:57 INFO - 'PWD': '/builds/slave/test-pgo', 12:55:57 INFO - 'SHELL': '/bin/bash', 12:55:57 INFO - 'SHLVL': '1', 12:55:57 INFO - 'TERM': 'linux', 12:55:57 INFO - 'TMOUT': '86400', 12:55:57 INFO - 'USER': 'cltbld', 12:55:57 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', 12:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:57 INFO - '_': '/tools/buildbot/bin/python'} 12:55:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 12:55:57 INFO - Running setup.py (path:/tmp/pip-m1r1F4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 12:55:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 12:55:57 INFO - Running setup.py (path:/tmp/pip-S2pj90-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 12:55:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 12:55:57 INFO - Running setup.py (path:/tmp/pip-tvSPfb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 12:55:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 12:55:57 INFO - Running setup.py (path:/tmp/pip-IWQvT5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 12:55:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 12:55:57 INFO - Running setup.py (path:/tmp/pip-u8wv3E-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 12:55:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 12:55:57 INFO - Running setup.py (path:/tmp/pip-6IMuIu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 12:55:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 12:55:57 INFO - Running setup.py (path:/tmp/pip-mz1Upt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 12:55:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 12:55:57 INFO - Running setup.py (path:/tmp/pip-QSULQv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 12:55:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 12:55:57 INFO - Running setup.py (path:/tmp/pip-gZa4pJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 12:55:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 12:55:58 INFO - Running setup.py (path:/tmp/pip-_yEqnH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 12:55:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 12:55:58 INFO - Running setup.py (path:/tmp/pip-KhE3uv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 12:55:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 12:55:58 INFO - Running setup.py (path:/tmp/pip-8fUcTj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 12:55:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 12:55:58 INFO - Running setup.py (path:/tmp/pip-PCzHC1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 12:55:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 12:55:58 INFO - Running setup.py (path:/tmp/pip-QeXJn1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 12:55:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 12:55:58 INFO - Running setup.py (path:/tmp/pip-E_HRRK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 12:55:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 12:55:58 INFO - Running setup.py (path:/tmp/pip-XTmUOZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 12:55:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 12:55:58 INFO - Running setup.py (path:/tmp/pip-MkDKIF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 12:55:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:55:58 INFO - Running setup.py install for manifestparser 12:55:58 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 12:55:58 INFO - Running setup.py install for mozcrash 12:55:58 INFO - Running setup.py install for mozdebug 12:55:58 INFO - Running setup.py install for mozdevice 12:55:59 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 12:55:59 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 12:55:59 INFO - Running setup.py install for mozfile 12:55:59 INFO - Running setup.py install for mozhttpd 12:55:59 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 12:55:59 INFO - Running setup.py install for mozinfo 12:55:59 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 12:55:59 INFO - Running setup.py install for mozInstall 12:55:59 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 12:55:59 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 12:55:59 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 12:55:59 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 12:55:59 INFO - Running setup.py install for mozleak 12:55:59 INFO - Running setup.py install for mozlog 12:55:59 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 12:55:59 INFO - Running setup.py install for moznetwork 12:55:59 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 12:55:59 INFO - Running setup.py install for mozprocess 12:55:59 INFO - Running setup.py install for mozprofile 12:55:59 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 12:55:59 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 12:55:59 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 12:55:59 INFO - Running setup.py install for mozrunner 12:56:00 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 12:56:00 INFO - Running setup.py install for mozscreenshot 12:56:00 INFO - Running setup.py install for moztest 12:56:00 INFO - Running setup.py install for mozversion 12:56:00 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 12:56:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:56:00 INFO - Cleaning up... 12:56:00 INFO - Return code: 0 12:56:00 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 12:56:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:00 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 0x7fe5c546bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1172230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12047f0>, '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 0x11702d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1170760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', '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 12:56:00 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 12:56:00 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 12:56:00 INFO - Using env: {'DISPLAY': ':0', 12:56:00 INFO - 'HOME': '/home/cltbld', 12:56:00 INFO - 'LANG': 'en_US.UTF-8', 12:56:00 INFO - 'LANGUAGE': 'en_US:en', 12:56:00 INFO - 'LOGNAME': 'cltbld', 12:56:00 INFO - 'MAIL': '/var/mail/cltbld', 12:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:00 INFO - 'MOZ_NO_REMOTE': '1', 12:56:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:56:00 INFO - 'NO_EM_RESTART': '1', 12:56:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:56:00 INFO - 'PWD': '/builds/slave/test-pgo', 12:56:00 INFO - 'SHELL': '/bin/bash', 12:56:00 INFO - 'SHLVL': '1', 12:56:00 INFO - 'TERM': 'linux', 12:56:00 INFO - 'TMOUT': '86400', 12:56:00 INFO - 'USER': 'cltbld', 12:56:00 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', 12:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:00 INFO - '_': '/tools/buildbot/bin/python'} 12:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 12:56:00 INFO - Running setup.py (path:/tmp/pip-sYheln-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 12:56:00 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)) 12:56:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 12:56:00 INFO - Running setup.py (path:/tmp/pip-9XQHbi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 12:56:00 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)) 12:56:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 12:56:00 INFO - Running setup.py (path:/tmp/pip-PBX1nv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 12:56:00 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)) 12:56:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 12:56:00 INFO - Running setup.py (path:/tmp/pip-u8IlLO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 12:56:00 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)) 12:56:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 12:56:00 INFO - Running setup.py (path:/tmp/pip-ydKgJA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 12:56:01 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)) 12:56:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 12:56:01 INFO - Running setup.py (path:/tmp/pip-6tCMOl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 12:56:01 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)) 12:56:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 12:56:01 INFO - Running setup.py (path:/tmp/pip-Cb_mwJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 12:56:01 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)) 12:56:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 12:56:01 INFO - Running setup.py (path:/tmp/pip-NTE2lY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 12:56:01 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)) 12:56:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 12:56:01 INFO - Running setup.py (path:/tmp/pip-u3_Xne-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 12:56:01 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)) 12:56:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 12:56:01 INFO - Running setup.py (path:/tmp/pip-NfjyF_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 12:56:01 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)) 12:56:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 12:56:01 INFO - Running setup.py (path:/tmp/pip-zJXHbK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 12:56:01 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)) 12:56:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 12:56:01 INFO - Running setup.py (path:/tmp/pip-lVN0ye-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 12:56:01 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)) 12:56:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 12:56:01 INFO - Running setup.py (path:/tmp/pip-pKvq43-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 12:56:01 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)) 12:56:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 12:56:01 INFO - Running setup.py (path:/tmp/pip-QXF4WM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 12:56:01 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)) 12:56:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 12:56:01 INFO - Running setup.py (path:/tmp/pip-IZMUWm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 12:56:01 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)) 12:56:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 12:56:01 INFO - Running setup.py (path:/tmp/pip-FMoas4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 12:56:01 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)) 12:56:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 12:56:01 INFO - Running setup.py (path:/tmp/pip-gT3Nh3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 12:56:01 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)) 12:56:01 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)) 12:56:01 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)) 12:56:01 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)) 12:56:01 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)) 12:56:01 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)) 12:56:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 12:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:03 INFO - Downloading blessings-1.5.1.tar.gz 12:56:03 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 12:56:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 12:56:03 INFO - Installing collected packages: blessings 12:56:03 INFO - Running setup.py install for blessings 12:56:03 INFO - Successfully installed blessings 12:56:03 INFO - Cleaning up... 12:56:03 INFO - Return code: 0 12:56:03 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 12:56:03 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 12:56:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 12:56:04 INFO - Reading from file tmpfile_stdout 12:56:04 INFO - Current package versions: 12:56:04 INFO - argparse == 1.2.1 12:56:04 INFO - blessings == 1.5.1 12:56:04 INFO - blobuploader == 1.2.4 12:56:04 INFO - docopt == 0.6.1 12:56:04 INFO - manifestparser == 1.1 12:56:04 INFO - mozInstall == 1.12 12:56:04 INFO - mozcrash == 0.16 12:56:04 INFO - mozdebug == 0.1 12:56:04 INFO - mozdevice == 0.47 12:56:04 INFO - mozfile == 1.2 12:56:04 INFO - mozhttpd == 0.7 12:56:04 INFO - mozinfo == 0.9 12:56:04 INFO - mozleak == 0.1 12:56:04 INFO - mozlog == 3.1 12:56:04 INFO - moznetwork == 0.27 12:56:04 INFO - mozprocess == 0.22 12:56:04 INFO - mozprofile == 0.28 12:56:04 INFO - mozrunner == 6.11 12:56:04 INFO - mozscreenshot == 0.1 12:56:04 INFO - mozsystemmonitor == 0.0 12:56:04 INFO - moztest == 0.7 12:56:04 INFO - mozversion == 1.4 12:56:04 INFO - psutil == 3.1.1 12:56:04 INFO - requests == 1.2.3 12:56:04 INFO - wsgiref == 0.1.2 12:56:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 12:56: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')]} 12:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56: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')]} 12:56:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56: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/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 0x7fe5c546bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1172230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12047f0>, '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 0x11702d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1170760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', '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 12:56: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/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 12:56: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/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 12:56:04 INFO - Using env: {'DISPLAY': ':0', 12:56:04 INFO - 'HOME': '/home/cltbld', 12:56:04 INFO - 'LANG': 'en_US.UTF-8', 12:56:04 INFO - 'LANGUAGE': 'en_US:en', 12:56:04 INFO - 'LOGNAME': 'cltbld', 12:56:04 INFO - 'MAIL': '/var/mail/cltbld', 12:56:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:04 INFO - 'MOZ_NO_REMOTE': '1', 12:56:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:56:04 INFO - 'NO_EM_RESTART': '1', 12:56:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:56:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:56:04 INFO - 'PWD': '/builds/slave/test-pgo', 12:56:04 INFO - 'SHELL': '/bin/bash', 12:56:04 INFO - 'SHLVL': '1', 12:56:04 INFO - 'TERM': 'linux', 12:56:04 INFO - 'TMOUT': '86400', 12:56:04 INFO - 'USER': 'cltbld', 12:56:04 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', 12:56:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:04 INFO - '_': '/tools/buildbot/bin/python'} 12:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:04 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)) 12:56:04 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)) 12:56:04 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)) 12:56:04 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)) 12:56:04 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)) 12:56:04 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)) 12:56:04 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)) 12:56:04 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)) 12:56:04 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)) 12:56:04 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)) 12:56:04 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)) 12:56:04 INFO - Cleaning up... 12:56:04 INFO - Return code: 0 12:56:04 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 12:56: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')]} 12:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56: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')]} 12:56:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56: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', '--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 0x7fe5c546bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1172230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12047f0>, '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 0x11702d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1170760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', '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 12:56:04 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 12:56:04 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 12:56:04 INFO - Using env: {'DISPLAY': ':0', 12:56:04 INFO - 'HOME': '/home/cltbld', 12:56:04 INFO - 'LANG': 'en_US.UTF-8', 12:56:04 INFO - 'LANGUAGE': 'en_US:en', 12:56:04 INFO - 'LOGNAME': 'cltbld', 12:56:04 INFO - 'MAIL': '/var/mail/cltbld', 12:56:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:04 INFO - 'MOZ_NO_REMOTE': '1', 12:56:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:56:04 INFO - 'NO_EM_RESTART': '1', 12:56:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:56:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:56:04 INFO - 'PWD': '/builds/slave/test-pgo', 12:56:04 INFO - 'SHELL': '/bin/bash', 12:56:04 INFO - 'SHLVL': '1', 12:56:04 INFO - 'TERM': 'linux', 12:56:04 INFO - 'TMOUT': '86400', 12:56:04 INFO - 'USER': 'cltbld', 12:56:04 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', 12:56:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:04 INFO - '_': '/tools/buildbot/bin/python'} 12:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:04 INFO - Downloading/unpacking jsonschema 12:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:56:06 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 12:56:06 INFO - Downloading/unpacking functools32 (from jsonschema) 12:56:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:06 INFO - Downloading functools32-3.2.3-2.tar.gz 12:56:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 12:56:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 12:56:07 INFO - warning: no files found matching '*.txt' 12:56:07 INFO - no previously-included directories found matching 'build' 12:56:07 INFO - no previously-included directories found matching 'dist' 12:56:07 INFO - no previously-included directories found matching '.git*' 12:56:07 INFO - Installing collected packages: jsonschema, functools32 12:56:07 INFO - Running setup.py install for functools32 12:56:07 INFO - warning: no files found matching '*.txt' 12:56:07 INFO - no previously-included directories found matching 'build' 12:56:07 INFO - no previously-included directories found matching 'dist' 12:56:07 INFO - no previously-included directories found matching '.git*' 12:56:07 INFO - Successfully installed jsonschema functools32 12:56:07 INFO - Cleaning up... 12:56:07 INFO - Return code: 0 12:56:07 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 12:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:07 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 0x7fe5c546bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1172230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12047f0>, '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 0x11702d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1170760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', '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 12:56:07 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 12:56:07 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 12:56:07 INFO - Using env: {'DISPLAY': ':0', 12:56:07 INFO - 'HOME': '/home/cltbld', 12:56:07 INFO - 'LANG': 'en_US.UTF-8', 12:56:07 INFO - 'LANGUAGE': 'en_US:en', 12:56:07 INFO - 'LOGNAME': 'cltbld', 12:56:07 INFO - 'MAIL': '/var/mail/cltbld', 12:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:07 INFO - 'MOZ_NO_REMOTE': '1', 12:56:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:56:07 INFO - 'NO_EM_RESTART': '1', 12:56:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:56:07 INFO - 'PWD': '/builds/slave/test-pgo', 12:56:07 INFO - 'SHELL': '/bin/bash', 12:56:07 INFO - 'SHLVL': '1', 12:56:07 INFO - 'TERM': 'linux', 12:56:07 INFO - 'TMOUT': '86400', 12:56:07 INFO - 'USER': 'cltbld', 12:56:07 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', 12:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:07 INFO - '_': '/tools/buildbot/bin/python'} 12:56:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:07 INFO - Downloading/unpacking flake8 12:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:56:09 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 12:56:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:56:09 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 12:56:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:09 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 12:56:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 12:56:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 12:56:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:56:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:56:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:56:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:56:09 INFO - no previously-included directories found matching 'docs/_build' 12:56:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:56:09 INFO - Running setup.py install for pep8 12:56:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:56:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:56:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:56:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:56:09 INFO - no previously-included directories found matching 'docs/_build' 12:56:10 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 12:56:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:56:10 INFO - Cleaning up... 12:56:10 INFO - Return code: 0 12:56:10 INFO - Running post-action listener: _resource_record_post_action 12:56:10 INFO - Running post-action listener: _start_resource_monitoring 12:56:10 INFO - Starting resource monitoring. 12:56:10 INFO - ##### 12:56:10 INFO - ##### Running install step. 12:56:10 INFO - ##### 12:56:10 INFO - Running pre-action listener: _resource_record_pre_action 12:56:10 INFO - Running main action method: install 12:56:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 12:56:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 12:56:10 INFO - Reading from file tmpfile_stdout 12:56:10 INFO - Detecting whether we're running mozinstall >=1.0... 12:56:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 12:56:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 12:56:10 INFO - Reading from file tmpfile_stdout 12:56:10 INFO - Output received: 12:56:10 INFO - Usage: mozinstall [options] installer 12:56:10 INFO - Options: 12:56:10 INFO - -h, --help show this help message and exit 12:56:10 INFO - -d DEST, --destination=DEST 12:56:10 INFO - Directory to install application into. [default: 12:56:10 INFO - "/builds/slave/test-pgo"] 12:56:10 INFO - --app=APP Application being installed. [default: firefox] 12:56:10 INFO - mkdir: /builds/slave/test-pgo/build/application 12:56:10 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'] 12:56:10 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 12:56:26 INFO - Reading from file tmpfile_stdout 12:56:26 INFO - Output received: 12:56:26 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 12:56:26 INFO - Running post-action listener: _resource_record_post_action 12:56:26 INFO - ##### 12:56:26 INFO - ##### Running run-tests step. 12:56:26 INFO - ##### 12:56:26 INFO - Running pre-action listener: _resource_record_pre_action 12:56:26 INFO - Running main action method: run_tests 12:56:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 12:56:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 12:56:26 INFO - Python 2.7.3 12:56:26 INFO - Return code: 0 12:56:26 INFO - grabbing minidump binary from tooltool 12:56:26 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')]} 12:56:26 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 0x12047f0>, '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 0x11702d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1170760>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 12:56:26 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 12:56:26 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 12:56:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:56:26 INFO - Return code: 0 12:56:26 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 12:56:26 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 12:56:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 12:56:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 12:56:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 12:56:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 12:56:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 12:56:27 INFO - Return code: 0 12:56:27 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', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test-pgo/build 12:56:27 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 --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-010 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log 12:56:27 INFO - Using env: {'DISPLAY': ':0', 12:56:27 INFO - 'HOME': '/home/cltbld', 12:56:27 INFO - 'LANG': 'en_US.UTF-8', 12:56:27 INFO - 'LANGUAGE': 'en_US:en', 12:56:27 INFO - 'LOGNAME': 'cltbld', 12:56:27 INFO - 'MAIL': '/var/mail/cltbld', 12:56:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 12:56:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 12:56:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:27 INFO - 'MOZ_NO_REMOTE': '1', 12:56:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 12:56:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:56:27 INFO - 'NO_EM_RESTART': '1', 12:56:27 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:56:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:56:27 INFO - 'PWD': '/builds/slave/test-pgo', 12:56:27 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 12:56:27 INFO - 'SHELL': '/bin/bash', 12:56:27 INFO - 'SHLVL': '1', 12:56:27 INFO - 'TERM': 'linux', 12:56:27 INFO - 'TMOUT': '86400', 12:56:27 INFO - 'USER': 'cltbld', 12:56:27 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613', 12:56:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:27 INFO - '_': '/tools/buildbot/bin/python'} 12:56:27 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', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:56:27 INFO - mozversion application_buildid: 20160110073242 12:56:27 INFO - mozversion application_changeset: 359f86fecbc2a6531d9018fb81a74fd26502f5a1 12:56:27 INFO - mozversion application_display_name: Nightly 12:56:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:56:27 INFO - mozversion application_name: Firefox 12:56:27 INFO - mozversion application_remotingname: firefox 12:56:27 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 12:56:27 INFO - mozversion application_vendor: Mozilla 12:56:27 INFO - mozversion application_version: 46.0a1 12:56:27 INFO - mozversion platform_buildid: 20160110073242 12:56:27 INFO - mozversion platform_changeset: 359f86fecbc2a6531d9018fb81a74fd26502f5a1 12:56:27 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 12:56:27 INFO - mozversion platform_version: 46.0a1 12:56:27 INFO - using testdate: 1452459387 12:56:27 INFO - actual date: 1452459387 12:56:27 INFO - starting webserver on 'localhost:39741' 12:56:27 INFO - SUITE-START | Running 3 tests 12:56:27 INFO - TEST-START | dromaeo_css 12:56:27 INFO - operating with platform_type : linux_ 12:56:27 INFO - Initialising browser for dromaeo_css test... 12:56:27 INFO - TEST-INFO | started process 9376 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCR15Zx/profile http://localhost:39741/getInfo.html) 12:56:32 INFO - PROCESS | 9376 | __metrics Screen width/height:1600/1200 12:56:32 INFO - PROCESS | 9376 | colorDepth:24 12:56:32 INFO - PROCESS | 9376 | Browser inner width/height: 1024/697 12:56:32 INFO - PROCESS | 9376 | __metrics 12:56:32 INFO - PROCESS | 9376 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 12:56:32 INFO - PROCESS | 9376 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:56:33 INFO - TEST-INFO | 9376: exit 0 12:56:33 INFO - Browser initialized. 12:56:33 INFO - Running cycle 1/1 for dromaeo_css test... 12:56:33 INFO - TEST-INFO | started process 9470 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCR15Zx/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:56:45 INFO - PROCESS | 9470 | RSS: Main: 148967424 12:56:45 INFO - PROCESS | 9470 | 12:57:36 INFO - PROCESS | 9470 | 1452459456655 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] 12:59:45 INFO - PROCESS | 9470 | Cycle 1(1): loaded http://localhost:39741/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39741/tests/dromaeo/cssquery-ext.html) 12:59:46 INFO - PROCESS | 9470 | RSS: Main: 268492800 12:59:46 INFO - PROCESS | 9470 | 13:02:48 INFO - PROCESS | 9470 | Cycle 1(1): loaded http://localhost:39741/tests/dromaeo/cssquery-ext.html (next: http://localhost:39741/tests/dromaeo/cssquery-jquery.html) 13:02:49 INFO - PROCESS | 9470 | RSS: Main: 227180544 13:02:49 INFO - PROCESS | 9470 | 13:05:51 INFO - PROCESS | 9470 | Cycle 1(1): loaded http://localhost:39741/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39741/tests/dromaeo/cssquery-mootools.html) 13:05:52 INFO - PROCESS | 9470 | RSS: Main: 232034304 13:05:52 INFO - PROCESS | 9470 | 13:08:54 INFO - PROCESS | 9470 | Cycle 1(1): loaded http://localhost:39741/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39741/tests/dromaeo/cssquery-prototype.html) 13:08:54 INFO - PROCESS | 9470 | RSS: Main: 215461888 13:08:54 INFO - PROCESS | 9470 | 13:11:57 INFO - PROCESS | 9470 | Cycle 1(1): loaded http://localhost:39741/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39741/tests/dromaeo/cssquery-yui.html) 13:11:57 INFO - PROCESS | 9470 | RSS: Main: 219328512 13:11:57 INFO - PROCESS | 9470 | 13:15:00 INFO - PROCESS | 9470 | Cycle 1(1): loaded http://localhost:39741/tests/dromaeo/cssquery-yui.html (next: http://localhost:39741/tests/dromaeo/cssquery-dojo.html) 13:15:00 INFO - PROCESS | 9470 | RSS: Main: 237178880 13:15:00 INFO - PROCESS | 9470 | 13:15:00 INFO - PROCESS | 9470 | __start_tp_report 13:15:00 INFO - PROCESS | 9470 | _x_x_mozilla_page_load 13:15:00 INFO - PROCESS | 9470 | _x_x_mozilla_page_load_details 13:15:00 INFO - PROCESS | 9470 | |i|pagename|runs| 13:15:00 INFO - PROCESS | 9470 | |0;dojo.html;3097.5353696945454;3110.688931106888;3150.19669984154;3156.1793933577264;3169.413658473182;4927.852164435064;4973.0302590994725;4985.526374994374;5005.949940500594;5013.899722005558;4800.111979283826;4888.975555122228;4917.901641967167;4919.11455937931;4957.950420495794;4342.696011279212;4537.773111344436;4562.657800664947;4589.747563883985;4666.160091183586;4547.408836851221;4554.24854898943;4596.195665758496;4649.232876575361;4652.883677908046;4565.223911934962;4600;4610.124076425502;4611.515790841981;4621.468531118914;4597.402337696111;4615.353850460938;4619.769011549409;4641.675082744209;4653.6509761768;4505.346724724896;4523.95476045242;4604.171249175147;4607;4623.491415944243;4777.355057067286;4807.302941073524;4813.302071199691;4822.68652537584;4834.6374021948495;5282.260483532308;5286.94261147772;5289.973550132263;5301.469853014706;5340.652857564285;5308.858595401968;5325.627206095575;5350.839474815762;5406.783728650849;5413.160960051159;5100.38795344561;5112.3865136183895;5125.282460455502;5125.564327032207;5126.487351264881;5566.443355664482;5588.776448941997;5594.496495315441;5596.664200148004;5598.888022239614;5199.766010529535;5201.115810312256;5311.0174617695075;5313.654612450256;5338.092524270884;5253.159494480941;5360.115580929103;5371.946280537223;5376.91934620981;5418.8916221675345;5387.649802762808;5418.376886658085;5420.891582168413;5429.104197807396;5429.701366424805;5247.422783493813;5350.438203988603;5371.435999220103;5435.945640543544;5470.617056806065;4222.113356195172;4253.63844073251;4297.763623000703;4318.956810431855;4322.956770432318;3802.8478860845808;3845.0964023454467;3861.7296789225047;3876.8643097491454;3880.1075752577067;4998.600111991032;5150.793968241303;5152.407473140562;5157.174852023733;5196.1166601677805;5211.166213405837;5289.867753306121;5309.309789727388;5389.757460914268;5448.591355648263;5048.873778155576;5048.949510504922;5051.292819005343;5062.569681577033;5132.307138536325;4606.88482787933;4689.695169813951;4699.224627936353;4717.292406139038;4728.598069164091;5618.943810561924;5666.858328541737;5671.716414179275;5769.94230057694;5772.393898640582;5332.440093790096;5332.466753324637;5336.0395128875625;5347.0375332440535;5348.384935732441;5563.332400111851;5584;5584.134459158837;5587.692676902647;5593.440655934537;5176.042432150064;5297.205419187153;5299.046171689133;5304.787808487772;5327.387350454593;5299.8410047698635;5328.333958255218;5348.197770334481;5360.758765855624;5385.46145385474;5224.947750522598;5231.13686241781;5232.3982741985155;5243.187305967581;5282.418933917343;5167.793288268577;5222.764975576184;5241.475852414728;5286.762095705625;5297.258383826191;5207.4271830099415;5213.973930130325;5221.869453263699;5224.869378265574;5236.711980841091;5304.469553044819;5327.493888080626;5328.946710533;5362.900605375967;5374.892502150014;5116.258142569232;5121.7695203715175;5151.253068305;5196.740162991911;5234.084035293732 13:15:00 INFO - PROCESS | 9470 | |1;ext.html;498.4940285610106;530.9707966061865;534.8342013975667;559.5691317685383;571.2003195526263;24130.155444559445;24357.14749983751;34673.65326346736;35080.12299692503;35107;37332.440013399755;37845.62154378455;37879;37888.431673524916;37904;7136.393406560449;8132.430729848898;8323.458975166624;8341.332693384546;8342.457740246864;7189.640517974107;7304.196538380774;7309.269073092675;7312;9509.09663581962;9842.901570984306;10038.799224015514;10039.548220330104;10046.949765251164;10058.094771470565;9763.755906102333;9908.80182396348;9917.851232231522;9920.801583968352;9926.2059035277;7447.2180421055555;7630.694772209105;9712.902870971271;9883.357581757235;10153.238507111997;35302.82348588266;35423.64576354229;35626.821865890764;35678;35839.4624080639;3068.324968506925;3185.2355434695737;3191.377681352137;3582.677559019675;3614.5843228028853;27727.555721578883;27956.301092472648;28161.57757633637;28249.152525424066;28322.29194270139;3895.182720933473;3907.9643894368014;3976.483057202574;3990.940135897964;4018.4374187613757;266126.9999999981;267805;269034.6548267246;269854;270039;33909;57353;58253;58335.249942501796;58471.41528584745;51190;51333.743331283105;51435.742821285654;51438.48561514337;51478.48521514812;15059.47291844802;15076.773848392282;15160.31778569967;15230.619234519225;15238.466653667068;11758.235714678518;11761.647150585497;11870.287782733063;11879.168458208016;11897.64307070772;18596.535086622673;18971.905140474486;19075.332363367215;19086.61826763457;19098.2360705573;7159.89260161098;7360.447966402541;7393.926060739432;7403.370713489306;7406.851862962818;944.6032666280179;947.3273975477488;958.6836344006462;974.8976357482362;1057.9050682543684;28104.156875293775;28125.43749125006;28542.85728571344;28712.13863584054;28787.712122878922;999.3304485994402;1044.556063672936;1068.5031460370965;1079.1205167788385;1081.324172392255;14865.628359290888;14985.625359366102;14995.302724419811;15012.62468438276;15034;318968;323505;324947;325615;325699;42544.1074276456;42609;42611;42636.14727705428;42676;1169.590643274864;1171.051448326858;1187.4181650991125;1188.8216767484946;1290.832191815058;27315.726842732114;28209.294767630152;28338.858305708338;28571.571426429036;28630.284242894093;44271.77864110659;44489.777551112034;44570.55429445664;44617.77691111524;44677.10645787131;290269;292611;292762;294463;294635;23834.523309533364;30542.541861871647;31191.84404077965;31343.21641958969;31660;35432;36272.4559131608;36408.81795591005;36719.449208262435;36932;30591.694083058883;31602.683973159976;32230.19424514406;32346;32736.34527309488;26727.23188659027;31552.89812324383;33400.33199335951;34267;34891;35134.472982904765;35640.82179589189;36066.45900311445;36214.45678314775;36687.816560917025;29263.414731705674;29607.85196074006;30940.381192376477;32020;32037.839810800797;24271;24349.87825060863;24610.876945615157;24754.628680570167;24894.12870549522 13:15:00 INFO - PROCESS | 9470 | |2;jquery.html;347.32792047388307;356.90252473643534;359.43927473141895;362.4490774023483;364.43330620884524;12296.5696200633;12693.492260309577;15751.212439378029;16116.516504504869;17146.056966866832;13303.334833258323;13834.377453014613;14282.714345713079;15264.236788160575;15533.301001454933;5125.948740512603;5215.530602245797;5329.280547126127;5335.1197052486295;5402.081646120169;3653.0867283179205;3794.0894185395446;5242.475752424765;6348.746050157988;6638.634875081869;6453.128827608283;6594.967025164868;6629.900551491707;6631.867362652744;6747.05541224229;6555.573887697285;6668.166479190101;6700.93299067008;6733.427658648996;6748.055272261888;6562.5406221564035;6563.4092931635905;6570.474362051073;6575.473962083021;6590.604563726191;19471.610567788706;20187.495312617157;20318.898405508025;21778;21846.453838654;2092.612866619665;2100.5693832764246;2224.9888750556306;2286.531261091489;2294.3461113582716;12437;12703.809442858364;12733.74532509354;13293.401796919163;13943.093698909639;1818.8635852310997;1891.6689579323702;1906.170815695177;1917.2426891377827;1933.4393026022437;56664;58599.41400585982;58635.70682146562;60433.69783151056;60946.69526652383;12458.377081146087;14454.349554270084;14890.883183761262;14917.627059323386;14920.32858521369;22863.19978800734;22877.19929802449;22925.42686432819;23224.30327090156;23263.302100937;8547.188017138486;8641.30869530436;8641.351898607629;8697;8707.519721647335;7403.481756277009;7521.7743467696055;7522.924770752332;7559.962200188964;7572.8485430292185;6224.346443623354;6441.969284914391;7475.476716629783;7488.925110748823;7559.962200189074;2717.2120085175475;2879.424115176973;2938.5885975963383;2997.400519895986;3002;818.423011776696;823.7940514871282;877.863931090682;956.1872408452759;959.9040095990345;13172.60482185536;14992.175430351217;15300.770488442682;16522.42171523991;16891.91554042222;870.4168207300997;905.5879574793453;939.5959737313021;966.8694726212012;1010.9393436393693;7837.451378403457;8440.915590844135;8696.826063478695;9081.54592270384;9083.727488175366;428744;499872;508123;512797;550439;33837.83081084628;33944;67556.32443675598;68396.29009274808;69923.65038174776;1129.5989923577176;1139.8917102875214;1153.7058050197313;1174;1187.4300335838846;13159.342032898508;14293.714125717219;14815.111093334435;14832.555023349316;14842.925785371004;31845.36309273848;32879;33193.336133276716;33314;33360.165995849326;41587.7920610395;41667.7916610415;41677.791611042965;42809.357859631506;43424.56575434205;7036.366726994595;7084.114485689289;7188.712451501881;7193.136823581203;7377.704891804267;10109.747256318651;10928.125749939827;10961.56153753841;11139.387333696724;11158.721031974266;9301.534923253945;9435.858462123211;9580.18568421692;9624.615015399586;9655.179309758749;7101;9164.862527061967;9442.669506567237;9516.762080948032;9594.42433453995;9188;9557.044295570387;9560.952195238979;9725.832900052039;9774.706758796963;6860.691268892812;7062.964685176541;7073.115860517435;7297;7396;8384.329253659815;8991.14584114508;9027.819443611223;9240.306976976619;9475.05249475047 13:15:00 INFO - PROCESS | 9470 | |3;mootools.html;1114.504045699664;1219.9024078073755;1250.106174085529;1258.4525731306883;1780.8041115477295;6530.249021362545;7105.396041336486;7247.2390399008;7261.3101755333155;7265.89101163483;7263.927360726391;7437.851242975137;7439.702411903518;7443.144038435582;7453;1932.7487426634518;1978.3867001229664;2273.7044184256024;2303.332033710222;2331.4987277735286;2315.1086831569883;2354.7174339079247;2355.6113241315247;2355.7408685044716;2356.387339291788;2268.1834539565743;2320.6751054852334;2321.802646775018;2325.7209134903837;2326.6510023496444;2224.1881713174657;2239.1491233331312;2269.6368581026954;2278.1001504405667;2279.2250634784086;2294.816414686821;2298.367948814073;2320.7679232076825;2327.697399338092;2327.976720232793;7057.858842823115;7092.822679433004;7106.822329441754;7110.715571377139;7113.7154513819905;4534.77326133692;4650.51169627191;4651.44182698075;4652.139354219446;4655.185342565072;2954.970450295491;3006.038067818299;3056.0526236866644;3097.0863595239457;3120.3915236528887;1057.5293994172596;1088.4884104470887;1101.7851518953807;1102.393683474086;1121.4504892602647;10051.849222261671;10210.081092701692;10227.89772102277;10229.181665466745;10239.948800255952;4302.806373713191;4376.299792033323;4382.912341753147;4385.758783266887;4402.273624851863;8038.155993620702;8198.139195384518;8202.753917382488;8221.342292616577;8225.83548329042;4727.5745182933015;4737.147313483606;4738.4550776660435;4740.051989602093;4742;4337.544557821447;4339.153864996359;4367.737935723867;4378.08060307339;4378.956210437918;3439.2605589797813;3450.5859296884532;3458.2046129390383;3459.7924124552605;3512.6838584527513;1085.9837102443469;1092.088106431123;1093.5625749700184;1102.6361300770727;1106.8505751723576;1043.8016776812533;1065.0361422912122;1065.2383545764815;1194.970125746846;1318.2156616812981;6922.5154239202775;7022.1924478685605;7059.435245180373;7071.04540886984;7076;506.4960364437408;515.1937218253421;517.2887279990014;517.4256575201523;520.8854052108605;5483.177523371526;5524.944750552443;5549.972250138723;5552.555795536347;5553.666779993133;8457.45026573283;8507.914920850835;8548.444351117158;8620.13798620133;8785.209331160226;8495.532745699045;8574.957125214334;8589.656413743505;8607.440516366416;8619.224269815746;6595.967020164869;6695.799126026227;6758.155230596274;6892.655367231619;7048.118985126859;6761.290064543349;6783.084283621648;6789.660516974231;6823.454123669995;6838.384545390939;7424.925750742424;7576.810579735551;7613.847723045397;7624.27569380908;7625.046869141357;7802.453828231969;7856.764297071097;7882.09355924076;7972.720954766555;7984.960075199587;6017.699115044318;6151.9384806151365;6670.566413183322;6837.897431538431;6844.315568443116;7143.392811611071;7146.964265178641;7165.928340716526;7170.641467926687;7183.20984691673;6466.741330346922;6706.765263215764;6711.530192886451;6761.830954226184;6874.106366172366;6589.242237142598;6614.404703576702;6637.568558043712;6649.168853893263;6809.557378770364;6762.7826991142065;6812.250652428328;6814.250432452327;6817.045613613999;6879.7248110077035;6020.367861374492;6065.787697430569;6103.145559621746;6103.969480152571;6112.32764395925;6379.170707807955;6401.167848179707;6413.8717225656155;6419.711112999833;6431.292557818542 13:15:00 INFO - PROCESS | 9470 | |4;prototype.html;343.13016503164476;346.6637361759094;350.1177033874636;356.12571137856565;359.5918632352281;5732.79935202268;5736.139579063142;5741.253637027191;5770.624909380887;5798.043322851735;5466.316710411199;5572.219889215503;5623.887522249553;5727.856803579913;5727.942720572793;4486.12520558492;4514.27771556551;4524.5249248828895;4532.750698711569;4552.385427967215;4316.158349121923;4479.193745125876;4513.887152821173;4513.977430112861;4514.345419914127;4333;4341.218580655481;4417.182821178077;4434.201843668138;4444.066745983348;4424.225760491901;4430.468343798732;4435.223835828733;4439.178751930903;4442.489113751895;3966.4446977423186;3970.305196590585;4006.599340065999;4011.9799401002806;4013.337799263118;5401.729913504349;5497.862553436156;5502.284702988626;5504.4770746778995;5515.310586176728;5206.062908676437;5244.318238628992;5245.213218017289;5245.816396426107;5250.9474905250845;6091.451769340781;6097.3292937776805;6097.542684298695;6102.145699602059;6103.511719062464;4414.3819865218575;4423.093265880487;4428.2914733642465;4430.556944305576;4435.223835828717;6131.601445906002;6314.8105556833825;6344.206974128234;6370.267419246799;6371.076193951897;4423.646108311327;4446.088551846864;4446.799894004777;4450.666200035011;4454.37638730578;4498.302763071729;4498.977505112453;4502.144592527409;4502.369668246448;4504.054148628825;5151.098557752378;5158.406783219978;5165.66423182492;5167.431582525919;5174.301469301596;6004.459598636144;6063.181470501514;6148.077788331786;6149.600275982047;6163.599366041282;1558.5168597734512;1572.9134897580743;1573.087609186669;1573.2851192200555;1575.4249698859833;1041.770810421706;1071.6410002649045;1072.0512346573182;1074.978500429987;1079.2391364088298;5917.378675239124;5923.674197919156;5925.111233315038;5931.940680593225;5933.584649074523;6249.187605611241;6253.405926436982;6254.812355629248;6258.123862659231;6262.185915830913;5713;5803.506701930299;5833;5995.910061348996;6009.308929473166;3372.5952885653387;3391.5082313064468;3400.2349471368743;3402.319536092791;3402.6086999994823;6689.6989635466525;6693.698783554752;6696.799096027127;6701.798946031627;6702.798916032527;6335.524835637253;6496.7726129585235;6542.672866356663;6544.247411547638;6553;6713.1944166700305;6725.66371681414;6730.461563074942;6730.629815360202;6733.7643182489355;6355.491560675043;6356.9046464304;6359.173307470184;6362.809115726535;6370;6152.446279834837;6308.873822523616;6326.829536535755;6336.1446204763615;6340.207474065742;6663.466922646274;6719.832004199934;6721.260661327348;6722.495812814156;6724.293949135466;4423.469183697976;4465.106978604227;4467.128909862546;4470.2400591900105;4472.150291444746;6222.408871157233;6358.332375100548;6373.458256048288;6379;6383.234011918599;6200.968995154995;6203.286622038343;6203.565750397609;6209.472194863487;6221.502279817513;6193.783217587363;6206.162168107247;6207.193064901715;6209.068639704081;6212.720427580679;5933.821985340447;6085.513158947185;6093;6093.664848433379;6098.11577321277;6224;6224.844378890382;6228.532860035425;6231.719572619152;6234.501239900706;6037.969810150921;6081.604695694766;6084.087386892002;6088.6042407243385;6092.7867524638195 13:15:00 INFO - PROCESS | 9470 | |5;yui.html;728.5847067171711;776.7048521561808;786.5752493653428;801.9117897031325;811.2779626132743;12740.426680799363;12888.74222515549;12909.160904541199;12922.612321630366;12930.74138517229;11937.7612447751;11946.880531194685;11991.820122698167;12027.579034733786;12031.699207519816;465.8765427161805;475.73358919005295;519.3974989012746;532.6963630730485;532.7229840482946;804.1235053791366;808.308895894011;813.9511629302261;814.1004190369615;814.1288820961573;486.9943566535094;489.19283182748393;855.6278019061729;860.3246451535515;860.3418384935529;540.4784383070337;547.2420697334181;547.8301726464808;549.2859282932192;549.9587530935196;414.6454781162093;415.14894466344174;416.385830899424;417.07617626956437;418.8596820065282;12361.876381236252;12394.380280985915;12407.689807754788;12443.191192572454;12471.002319814394;11132.666020019413;11595.478203480863;11775.528978840835;11851.525938962517;11917.702057448547;10177.0525606202;10368.79262414755;10389.9480502597;10481.836779280664;10525.526351314133;3385.8137802420856;3411.337180280747;3441.8451169697173;3450.5859296884532;3458.4985177149424;36010.639893600994;36091.27817443663;36139.45790813114;36264.912052638465;36278.8186059068;8883.555822208864;9030.096990300917;9289.581968811419;9351.859722104175;9552;14997.325120369609;15001.099934003994;15011.099334039993;15046;15069;6558.803235902835;6727.730890764413;6740.865182696319;7347;7363.300486453778;4124.092699606082;4200.831966721297;4237.470316210473;4292.59220374064;4350.0212452198;2875.1087162979543;2894.3343031102963;2901.7098290170816;2915.1400336900665;2923.9707602924123;536.2813829468531;544.5072209650273;546.0362460257649;550.5265471694339;551.9972400137974;527.9889012541017;533.317353787153;544.9782008719686;647.7344288841647;668.488606216245;8053.476524025919;8470;8726.345524085718;8946.284297256203;9224.262059035262;539.8596364945142;551.8289330307617;639.5810743962619;650.6291413894035;657.9835504112435;3829.181138958972;4143.6892233082035;4206.3690446432665;4224.514180869178;4293.978530107392;53768;54344.184837227476;54415.72792136014;54889.451105488435;55025.72487137539;42123.7893810529;42605.36091958623;42698.57301427008;42890.356644650354;43777;41064.794676025835;41151.588484115375;41371.7931410347;41520;41641.167176656294;8023.959880200561;8295.294899933573;8540.829183416172;8807.119288071142;8824.514651694219;30744.538831917092;31055.223619409695;31237.375252494367;31785.841070794497;31913.36173276475;39921.80039099786;40513.59486405216;40644;40739.79630101831;41320.586794131676;9745.733054702843;10159.187265018929;10372.481375931324;10513.369197848153;10528.894711052792;12058.939705301418;12251.203671761306;12262.632121036384;12356.073294502768;12715.046371521988;10465.476726163814;11050.834237486284;11079.612213572485;11117.22179447431;11216.887831121585;11093.50079246452;11258.38078905668;11355.432228388383;11425.600103996652;11565.884341156481;10439.895601044196;10805.675829725122;10942.562297508011;10964.725881853017;11259.324440533594;9495.715128546155;9884.30809843304;9887.110160085627;10087.848682269918;10216.335938163995;8724.993251552052;9236.584353704235;9433.585760324164;9895.307328486937;10065.647702330383 13:15:00 INFO - PROCESS | 9470 | __end_tp_report 13:15:00 INFO - PROCESS | 9470 | __start_cc_report 13:15:00 INFO - PROCESS | 9470 | _x_x_mozilla_cycle_collect,902 13:15:00 INFO - PROCESS | 9470 | __end_cc_report 13:15:00 INFO - PROCESS | 9470 | __startTimestamp1452460500483__endTimestamp 13:15:00 INFO - PROCESS | 9470 | 13:15:00 INFO - PROCESS | 9470 | ------- Summary: start ------- 13:15:00 INFO - PROCESS | 9470 | Number of tests: 6 13:15:00 INFO - PROCESS | 9470 | 13:15:00 INFO - PROCESS | 9470 | [#0] dojo.html Cycles:165 Average:5011.14 Median:5201.12 stddev:524.96 (10.1%) stddev-sans-first:504.65 13:15:00 INFO - PROCESS | 9470 | Values: 3097.5 3110.7 3150.2 3156.2 3169.4 4927.9 4973.0 4985.5 5005.9 5013.9 4800.1 4889.0 4917.9 4919.1 4958.0 4342.7 4537.8 4562.7 4589.7 4666.2 4547.4 4554.2 4596.2 4649.2 4652.9 4565.2 4600.0 4610.1 4611.5 4621.5 4597.4 4615.4 4619.8 4641.7 4653.7 4505.3 4524.0 4604.2 4607.0 4623.5 4777.4 4807.3 4813.3 4822.7 4834.6 5282.3 5286.9 5290.0 5301.5 5340.7 5308.9 5325.6 5350.8 5406.8 5413.2 5100.4 5112.4 5125.3 5125.6 5126.5 5566.4 5588.8 5594.5 5596.7 5598.9 5199.8 5201.1 5311.0 5313.7 5338.1 5253.2 5360.1 5371.9 5376.9 5418.9 5387.6 5418.4 5420.9 5429.1 5429.7 5247.4 5350.4 5371.4 5435.9 5470.6 4222.1 4253.6 4297.8 4319.0 4323.0 3802.8 3845.1 3861.7 3876.9 3880.1 4998.6 5150.8 5152.4 5157.2 5196.1 5211.2 5289.9 5309.3 5389.8 5448.6 5048.9 5048.9 5051.3 5062.6 5132.3 4606.9 4689.7 4699.2 4717.3 4728.6 5618.9 5666.9 5671.7 5769.9 5772.4 5332.4 5332.5 5336.0 5347.0 5348.4 5563.3 5584.0 5584.1 5587.7 5593.4 5176.0 5297.2 5299.0 5304.8 5327.4 5299.8 5328.3 5348.2 5360.8 5385.5 5224.9 5231.1 5232.4 5243.2 5282.4 5167.8 5222.8 5241.5 5286.8 5297.3 5207.4 5214.0 5221.9 5224.9 5236.7 5304.5 5327.5 5328.9 5362.9 5374.9 5116.3 5121.8 5151.3 5196.7 5234.1 13:15:00 INFO - PROCESS | 9470 | 13:15:00 INFO - PROCESS | 9470 | [#1] ext.html Cycles:180 Average:44756.08 Median:179217.67 stddev:77432.29 (43.2%) stddev-sans-first:77578.21 13:15:00 INFO - PROCESS | 9470 | Values: 498.5 531.0 534.8 559.6 571.2 24130.2 24357.1 34673.7 35080.1 35107.0 37332.4 37845.6 37879.0 37888.4 37904.0 7136.4 8132.4 8323.5 8341.3 8342.5 7189.6 7304.2 7309.3 7312.0 9509.1 9842.9 10038.8 10039.5 10046.9 10058.1 9763.8 9908.8 9917.9 9920.8 9926.2 7447.2 7630.7 9712.9 9883.4 10153.2 35302.8 35423.6 35626.8 35678.0 35839.5 3068.3 3185.2 3191.4 3582.7 3614.6 27727.6 27956.3 28161.6 28249.2 28322.3 3895.2 3908.0 3976.5 3990.9 4018.4 266127.0 267805.0 269034.7 269854.0 270039.0 33909.0 57353.0 58253.0 58335.2 58471.4 51190.0 51333.7 51435.7 51438.5 51478.5 15059.5 15076.8 15160.3 15230.6 15238.5 11758.2 11761.6 11870.3 11879.2 11897.6 18596.5 18971.9 19075.3 19086.6 19098.2 7159.9 7360.4 7393.9 7403.4 7406.9 944.6 947.3 958.7 974.9 1057.9 28104.2 28125.4 28542.9 28712.1 28787.7 999.3 1044.6 1068.5 1079.1 1081.3 14865.6 14985.6 14995.3 15012.6 15034.0 318968.0 323505.0 324947.0 325615.0 325699.0 42544.1 42609.0 42611.0 42636.1 42676.0 1169.6 1171.1 1187.4 1188.8 1290.8 27315.7 28209.3 28338.9 28571.6 28630.3 44271.8 44489.8 44570.6 44617.8 44677.1 290269.0 292611.0 292762.0 294463.0 294635.0 23834.5 30542.5 31191.8 31343.2 31660.0 35432.0 36272.5 36408.8 36719.4 36932.0 30591.7 31602.7 32230.2 32346.0 32736.3 26727.2 31552.9 33400.3 34267.0 34891.0 35134.5 35640.8 36066.5 36214.5 36687.8 29263.4 29607.9 30940.4 32020.0 32037.8 24271.0 24349.9 24610.9 24754.6 24894.1 13:15:00 INFO - PROCESS | 9470 | 13:15:00 INFO - PROCESS | 9470 | [#2] jquery.html Cycles:180 Average:26492.77 Median:5229.00 stddev:81738.27 (1563.2%) stddev-sans-first:81943.99 13:15:00 INFO - PROCESS | 9470 | Values: 347.3 356.9 359.4 362.4 364.4 12296.6 12693.5 15751.2 16116.5 17146.1 13303.3 13834.4 14282.7 15264.2 15533.3 5125.9 5215.5 5329.3 5335.1 5402.1 3653.1 3794.1 5242.5 6348.7 6638.6 6453.1 6595.0 6629.9 6631.9 6747.1 6555.6 6668.2 6700.9 6733.4 6748.1 6562.5 6563.4 6570.5 6575.5 6590.6 19471.6 20187.5 20318.9 21778.0 21846.5 2092.6 2100.6 2225.0 2286.5 2294.3 12437.0 12703.8 12733.7 13293.4 13943.1 1818.9 1891.7 1906.2 1917.2 1933.4 56664.0 58599.4 58635.7 60433.7 60946.7 12458.4 14454.3 14890.9 14917.6 14920.3 22863.2 22877.2 22925.4 23224.3 23263.3 8547.2 8641.3 8641.4 8697.0 8707.5 7403.5 7521.8 7522.9 7560.0 7572.8 6224.3 6442.0 7475.5 7488.9 7560.0 2717.2 2879.4 2938.6 2997.4 3002.0 818.4 823.8 877.9 956.2 959.9 13172.6 14992.2 15300.8 16522.4 16891.9 870.4 905.6 939.6 966.9 1010.9 7837.5 8440.9 8696.8 9081.5 9083.7 428744.0 499872.0 508123.0 512797.0 550439.0 33837.8 33944.0 67556.3 68396.3 69923.7 1129.6 1139.9 1153.7 1174.0 1187.4 13159.3 14293.7 14815.1 14832.6 14842.9 31845.4 32879.0 33193.3 33314.0 33360.2 41587.8 41667.8 41677.8 42809.4 43424.6 7036.4 7084.1 7188.7 7193.1 7377.7 10109.7 10928.1 10961.6 11139.4 11158.7 9301.5 9435.9 9580.2 9624.6 9655.2 7101.0 9164.9 9442.7 9516.8 9594.4 9188.0 9557.0 9561.0 9725.8 9774.7 6860.7 7063.0 7073.1 7297.0 7396.0 8384.3 8991.1 9027.8 9240.3 9475.1 13:15:00 INFO - PROCESS | 9470 | 13:15:00 INFO - PROCESS | 9470 | [#3] mootools.html Cycles:180 Average:5173.86 Median:6019.03 stddev:2614.67 (43.4%) stddev-sans-first:2604.19 13:15:00 INFO - PROCESS | 9470 | Values: 1114.5 1219.9 1250.1 1258.5 1780.8 6530.2 7105.4 7247.2 7261.3 7265.9 7263.9 7437.9 7439.7 7443.1 7453.0 1932.7 1978.4 2273.7 2303.3 2331.5 2315.1 2354.7 2355.6 2355.7 2356.4 2268.2 2320.7 2321.8 2325.7 2326.7 2224.2 2239.1 2269.6 2278.1 2279.2 2294.8 2298.4 2320.8 2327.7 2328.0 7057.9 7092.8 7106.8 7110.7 7113.7 4534.8 4650.5 4651.4 4652.1 4655.2 2955.0 3006.0 3056.1 3097.1 3120.4 1057.5 1088.5 1101.8 1102.4 1121.5 10051.8 10210.1 10227.9 10229.2 10239.9 4302.8 4376.3 4382.9 4385.8 4402.3 8038.2 8198.1 8202.8 8221.3 8225.8 4727.6 4737.1 4738.5 4740.1 4742.0 4337.5 4339.2 4367.7 4378.1 4379.0 3439.3 3450.6 3458.2 3459.8 3512.7 1086.0 1092.1 1093.6 1102.6 1106.9 1043.8 1065.0 1065.2 1195.0 1318.2 6922.5 7022.2 7059.4 7071.0 7076.0 506.5 515.2 517.3 517.4 520.9 5483.2 5524.9 5550.0 5552.6 5553.7 8457.5 8507.9 8548.4 8620.1 8785.2 8495.5 8575.0 8589.7 8607.4 8619.2 6596.0 6695.8 6758.2 6892.7 7048.1 6761.3 6783.1 6789.7 6823.5 6838.4 7424.9 7576.8 7613.8 7624.3 7625.0 7802.5 7856.8 7882.1 7972.7 7985.0 6017.7 6151.9 6670.6 6837.9 6844.3 7143.4 7147.0 7165.9 7170.6 7183.2 6466.7 6706.8 6711.5 6761.8 6874.1 6589.2 6614.4 6637.6 6649.2 6809.6 6762.8 6812.3 6814.3 6817.0 6879.7 6020.4 6065.8 6103.1 6104.0 6112.3 6379.2 6401.2 6413.9 6419.7 6431.3 13:15:00 INFO - PROCESS | 9470 | 13:15:00 INFO - PROCESS | 9470 | [#4] prototype.html Cycles:180 Average:5173.92 Median:5800.78 stddev:1544.91 (26.6%) stddev-sans-first:1506.10 13:15:00 INFO - PROCESS | 9470 | Values: 343.1 346.7 350.1 356.1 359.6 5732.8 5736.1 5741.3 5770.6 5798.0 5466.3 5572.2 5623.9 5727.9 5727.9 4486.1 4514.3 4524.5 4532.8 4552.4 4316.2 4479.2 4513.9 4514.0 4514.3 4333.0 4341.2 4417.2 4434.2 4444.1 4424.2 4430.5 4435.2 4439.2 4442.5 3966.4 3970.3 4006.6 4012.0 4013.3 5401.7 5497.9 5502.3 5504.5 5515.3 5206.1 5244.3 5245.2 5245.8 5250.9 6091.5 6097.3 6097.5 6102.1 6103.5 4414.4 4423.1 4428.3 4430.6 4435.2 6131.6 6314.8 6344.2 6370.3 6371.1 4423.6 4446.1 4446.8 4450.7 4454.4 4498.3 4499.0 4502.1 4502.4 4504.1 5151.1 5158.4 5165.7 5167.4 5174.3 6004.5 6063.2 6148.1 6149.6 6163.6 1558.5 1572.9 1573.1 1573.3 1575.4 1041.8 1071.6 1072.1 1075.0 1079.2 5917.4 5923.7 5925.1 5931.9 5933.6 6249.2 6253.4 6254.8 6258.1 6262.2 5713.0 5803.5 5833.0 5995.9 6009.3 3372.6 3391.5 3400.2 3402.3 3402.6 6689.7 6693.7 6696.8 6701.8 6702.8 6335.5 6496.8 6542.7 6544.2 6553.0 6713.2 6725.7 6730.5 6730.6 6733.8 6355.5 6356.9 6359.2 6362.8 6370.0 6152.4 6308.9 6326.8 6336.1 6340.2 6663.5 6719.8 6721.3 6722.5 6724.3 4423.5 4465.1 4467.1 4470.2 4472.2 6222.4 6358.3 6373.5 6379.0 6383.2 6201.0 6203.3 6203.6 6209.5 6221.5 6193.8 6206.2 6207.2 6209.1 6212.7 5933.8 6085.5 6093.0 6093.7 6098.1 6224.0 6224.8 6228.5 6231.7 6234.5 6038.0 6081.6 6084.1 6088.6 6092.8 13:15:00 INFO - PROCESS | 9470 | 13:15:00 INFO - PROCESS | 9470 | [#5] yui.html Cycles:180 Average:12497.18 Median:20968.19 stddev:13901.67 (66.3%) stddev-sans-first:13912.57 13:15:00 INFO - PROCESS | 9470 | Values: 728.6 776.7 786.6 801.9 811.3 12740.4 12888.7 12909.2 12922.6 12930.7 11937.8 11946.9 11991.8 12027.6 12031.7 465.9 475.7 519.4 532.7 532.7 804.1 808.3 814.0 814.1 814.1 487.0 489.2 855.6 860.3 860.3 540.5 547.2 547.8 549.3 550.0 414.6 415.1 416.4 417.1 418.9 12361.9 12394.4 12407.7 12443.2 12471.0 11132.7 11595.5 11775.5 11851.5 11917.7 10177.1 10368.8 10389.9 10481.8 10525.5 3385.8 3411.3 3441.8 3450.6 3458.5 36010.6 36091.3 36139.5 36264.9 36278.8 8883.6 9030.1 9289.6 9351.9 9552.0 14997.3 15001.1 15011.1 15046.0 15069.0 6558.8 6727.7 6740.9 7347.0 7363.3 4124.1 4200.8 4237.5 4292.6 4350.0 2875.1 2894.3 2901.7 2915.1 2924.0 536.3 544.5 546.0 550.5 552.0 528.0 533.3 545.0 647.7 668.5 8053.5 8470.0 8726.3 8946.3 9224.3 539.9 551.8 639.6 650.6 658.0 3829.2 4143.7 4206.4 4224.5 4294.0 53768.0 54344.2 54415.7 54889.5 55025.7 42123.8 42605.4 42698.6 42890.4 43777.0 41064.8 41151.6 41371.8 41520.0 41641.2 8024.0 8295.3 8540.8 8807.1 8824.5 30744.5 31055.2 31237.4 31785.8 31913.4 39921.8 40513.6 40644.0 40739.8 41320.6 9745.7 10159.2 10372.5 10513.4 10528.9 12058.9 12251.2 12262.6 12356.1 12715.0 10465.5 11050.8 11079.6 11117.2 11216.9 11093.5 11258.4 11355.4 11425.6 11565.9 10439.9 10805.7 10942.6 10964.7 11259.3 9495.7 9884.3 9887.1 10087.8 10216.3 8725.0 9236.6 9433.6 9895.3 10065.6 13:15:00 INFO - PROCESS | 9470 | -------- Summary: end -------- 13:15:00 INFO - PROCESS | 9470 | 13:15:01 INFO - TEST-INFO | 9470: exit 0 13:15:01 INFO - TEST-OK | dromaeo_css | took 1113492ms 13:15:01 INFO - TEST-START | kraken 13:15:01 INFO - operating with platform_type : linux_ 13:15:01 INFO - Initialising browser for kraken test... 13:15:01 INFO - TEST-INFO | started process 9662 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTA_B4R/profile http://localhost:39741/getInfo.html) 13:15:05 INFO - PROCESS | 9662 | __metrics Screen width/height:1600/1200 13:15:05 INFO - PROCESS | 9662 | colorDepth:24 13:15:05 INFO - PROCESS | 9662 | Browser inner width/height: 1024/697 13:15:05 INFO - PROCESS | 9662 | __metrics 13:15:05 INFO - PROCESS | 9662 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 13:15:06 INFO - PROCESS | 9662 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:15:06 INFO - TEST-INFO | 9662: exit 0 13:15:06 INFO - Browser initialized. 13:15:06 INFO - Running cycle 1/1 for kraken test... 13:15:06 INFO - TEST-INFO | started process 9748 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTA_B4R/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:15:18 INFO - PROCESS | 9748 | RSS: Main: 149336064 13:15:18 INFO - PROCESS | 9748 | 13:15:21 INFO - PROCESS | 9748 | Cycle 1(1): loaded http://localhost:39741/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39741/tests/kraken/driver.html?testName=audio-beat-detection) 13:15:21 INFO - PROCESS | 9748 | RSS: Main: 185880576 13:15:21 INFO - PROCESS | 9748 | 13:15:24 INFO - PROCESS | 9748 | Cycle 1(1): loaded http://localhost:39741/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39741/tests/kraken/driver.html?testName=audio-dft) 13:15:25 INFO - PROCESS | 9748 | RSS: Main: 175271936 13:15:25 INFO - PROCESS | 9748 | 13:15:27 INFO - PROCESS | 9748 | Cycle 1(1): loaded http://localhost:39741/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39741/tests/kraken/driver.html?testName=audio-fft) 13:15:28 INFO - PROCESS | 9748 | RSS: Main: 160563200 13:15:28 INFO - PROCESS | 9748 | 13:15:30 INFO - PROCESS | 9748 | Cycle 1(1): loaded http://localhost:39741/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39741/tests/kraken/driver.html?testName=audio-oscillator) 13:15:31 INFO - PROCESS | 9748 | RSS: Main: 161648640 13:15:31 INFO - PROCESS | 9748 | 13:15:33 INFO - PROCESS | 9748 | Cycle 1(1): loaded http://localhost:39741/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39741/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:15:33 INFO - PROCESS | 9748 | RSS: Main: 164773888 13:15:33 INFO - PROCESS | 9748 | 13:15:36 INFO - PROCESS | 9748 | Cycle 1(1): loaded http://localhost:39741/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39741/tests/kraken/driver.html?testName=imaging-darkroom) 13:15:37 INFO - PROCESS | 9748 | RSS: Main: 164511744 13:15:37 INFO - PROCESS | 9748 | 13:15:40 INFO - PROCESS | 9748 | Cycle 1(1): loaded http://localhost:39741/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39741/tests/kraken/driver.html?testName=imaging-desaturate) 13:15:41 INFO - PROCESS | 9748 | RSS: Main: 164184064 13:15:41 INFO - PROCESS | 9748 | 13:15:44 INFO - PROCESS | 9748 | Cycle 1(1): loaded http://localhost:39741/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39741/tests/kraken/driver.html?testName=json-parse-financial) 13:15:44 INFO - PROCESS | 9748 | RSS: Main: 164687872 13:15:44 INFO - PROCESS | 9748 | 13:15:46 INFO - PROCESS | 9748 | Cycle 1(1): loaded http://localhost:39741/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39741/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:15:46 INFO - PROCESS | 9748 | RSS: Main: 176427008 13:15:46 INFO - PROCESS | 9748 | 13:15:48 INFO - PROCESS | 9748 | Cycle 1(1): loaded http://localhost:39741/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39741/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:15:49 INFO - PROCESS | 9748 | RSS: Main: 172498944 13:15:49 INFO - PROCESS | 9748 | 13:15:51 INFO - PROCESS | 9748 | Cycle 1(1): loaded http://localhost:39741/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39741/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:15:51 INFO - PROCESS | 9748 | RSS: Main: 183877632 13:15:51 INFO - PROCESS | 9748 | 13:15:54 INFO - PROCESS | 9748 | Cycle 1(1): loaded http://localhost:39741/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39741/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:15:54 INFO - PROCESS | 9748 | RSS: Main: 191016960 13:15:54 INFO - PROCESS | 9748 | 13:15:57 INFO - PROCESS | 9748 | Cycle 1(1): loaded http://localhost:39741/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39741/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:15:57 INFO - PROCESS | 9748 | RSS: Main: 190767104 13:15:57 INFO - PROCESS | 9748 | 13:15:59 INFO - PROCESS | 9748 | Cycle 1(1): loaded http://localhost:39741/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39741/tests/kraken/driver.html?testName=ai-astar) 13:15:59 INFO - PROCESS | 9748 | RSS: Main: 248000512 13:15:59 INFO - PROCESS | 9748 | 13:15:59 INFO - PROCESS | 9748 | __start_tp_report 13:15:59 INFO - PROCESS | 9748 | _x_x_mozilla_page_load 13:15:59 INFO - PROCESS | 9748 | _x_x_mozilla_page_load_details 13:15:59 INFO - PROCESS | 9748 | |i|pagename|runs| 13:15:59 INFO - PROCESS | 9748 | |0;ai-astar;109;111;105;112;112;111;101;110;109;110 13:15:59 INFO - PROCESS | 9748 | |1;audio-beat-detection;146;125;149;163;127;150;156;125;168;127 13:15:59 INFO - PROCESS | 9748 | |2;audio-dft;161;162;160;160;162;163;160;164;163;166 13:15:59 INFO - PROCESS | 9748 | |3;audio-fft;75;75;90;92;74;71;75;105;93;70 13:15:59 INFO - PROCESS | 9748 | |4;audio-oscillator;97;97;142;96;96;96;138;96;96;141 13:15:59 INFO - PROCESS | 9748 | |5;imaging-gaussian-blur;109;109;109;109;110;109;109;109;109;108 13:15:59 INFO - PROCESS | 9748 | |6;imaging-darkroom;146;145;146;146;145;145;145;146;145;145 13:15:59 INFO - PROCESS | 9748 | |7;imaging-desaturate;95;95;96;95;95;96;96;96;96;96 13:15:59 INFO - PROCESS | 9748 | |8;json-parse-financial;57;57;57;55;56;56;55;65;52;54 13:15:59 INFO - PROCESS | 9748 | |9;json-stringify-tinderbox;68;67;68;68;69;68;69;68;69;69 13:15:59 INFO - PROCESS | 9748 | |10;stanford-crypto-aes;86;85;88;88;86;87;86;86;85;86 13:15:59 INFO - PROCESS | 9748 | |11;stanford-crypto-ccm;118;124;117;120;117;113;118;115;122;124 13:15:59 INFO - PROCESS | 9748 | |12;stanford-crypto-pbkdf2;140;151;150;150;151;140;150;152;150;161 13:15:59 INFO - PROCESS | 9748 | |13;stanford-crypto-sha256-iterative;68;72;69;69;70;66;68;68;69;66 13:15:59 INFO - PROCESS | 9748 | __end_tp_report 13:15:59 INFO - PROCESS | 9748 | __start_cc_report 13:15:59 INFO - PROCESS | 9748 | _x_x_mozilla_cycle_collect,979 13:15:59 INFO - PROCESS | 9748 | __end_cc_report 13:15:59 INFO - PROCESS | 9748 | __startTimestamp1452460559864__endTimestamp 13:15:59 INFO - PROCESS | 9748 | 13:15:59 INFO - PROCESS | 9748 | ------- Summary: start ------- 13:15:59 INFO - PROCESS | 9748 | Number of tests: 14 13:15:59 INFO - PROCESS | 9748 | 13:15:59 INFO - PROCESS | 9748 | [#0] ai-astar Cycles:10 Average:109.00 Median:110.50 stddev:3.46 (3.1%) stddev-sans-first:3.67 13:15:59 INFO - PROCESS | 9748 | Values: 109.0 111.0 105.0 112.0 112.0 111.0 101.0 110.0 109.0 110.0 13:15:59 INFO - PROCESS | 9748 | 13:15:59 INFO - PROCESS | 9748 | [#1] audio-beat-detection Cycles:10 Average:143.60 Median:149.50 stddev:16.48 (11.0%) stddev-sans-first:17.46 13:15:59 INFO - PROCESS | 9748 | Values: 146.0 125.0 149.0 163.0 127.0 150.0 156.0 125.0 168.0 127.0 13:15:59 INFO - PROCESS | 9748 | 13:15:59 INFO - PROCESS | 9748 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.50 stddev:1.97 (1.2%) stddev-sans-first:2.05 13:15:59 INFO - PROCESS | 9748 | Values: 161.0 162.0 160.0 160.0 162.0 163.0 160.0 164.0 163.0 166.0 13:15:59 INFO - PROCESS | 9748 | 13:15:59 INFO - PROCESS | 9748 | [#3] audio-fft Cycles:10 Average:82.00 Median:75.00 stddev:11.97 (16.0%) stddev-sans-first:12.43 13:15:59 INFO - PROCESS | 9748 | Values: 75.0 75.0 90.0 92.0 74.0 71.0 75.0 105.0 93.0 70.0 13:15:59 INFO - PROCESS | 9748 | 13:15:59 INFO - PROCESS | 9748 | [#4] audio-oscillator Cycles:10 Average:109.50 Median:96.00 stddev:21.30 (22.2%) stddev-sans-first:22.11 13:15:59 INFO - PROCESS | 9748 | Values: 97.0 97.0 142.0 96.0 96.0 96.0 138.0 96.0 96.0 141.0 13:15:59 INFO - PROCESS | 9748 | 13:15:59 INFO - PROCESS | 9748 | [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 13:15:59 INFO - PROCESS | 9748 | Values: 109.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 108.0 13:15:59 INFO - PROCESS | 9748 | 13:15:59 INFO - PROCESS | 9748 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 13:15:59 INFO - PROCESS | 9748 | Values: 146.0 145.0 146.0 146.0 145.0 145.0 145.0 146.0 145.0 145.0 13:15:59 INFO - PROCESS | 9748 | 13:15:59 INFO - PROCESS | 9748 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 13:15:59 INFO - PROCESS | 9748 | Values: 95.0 95.0 96.0 95.0 95.0 96.0 96.0 96.0 96.0 96.0 13:15:59 INFO - PROCESS | 9748 | 13:15:59 INFO - PROCESS | 9748 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:56.50 stddev:3.41 (6.0%) stddev-sans-first:3.61 13:15:59 INFO - PROCESS | 9748 | Values: 57.0 57.0 57.0 55.0 56.0 56.0 55.0 65.0 52.0 54.0 13:15:59 INFO - PROCESS | 9748 | 13:15:59 INFO - PROCESS | 9748 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:68.50 stddev:0.67 (1.0%) stddev-sans-first:0.71 13:15:59 INFO - PROCESS | 9748 | Values: 68.0 67.0 68.0 68.0 69.0 68.0 69.0 68.0 69.0 69.0 13:15:59 INFO - PROCESS | 9748 | 13:15:59 INFO - PROCESS | 9748 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:86.00 stddev:1.06 (1.2%) stddev-sans-first:1.12 13:15:59 INFO - PROCESS | 9748 | Values: 86.0 85.0 88.0 88.0 86.0 87.0 86.0 86.0 85.0 86.0 13:15:59 INFO - PROCESS | 9748 | 13:15:59 INFO - PROCESS | 9748 | [#11] stanford-crypto-ccm Cycles:10 Average:118.80 Median:119.00 stddev:3.68 (3.1%) stddev-sans-first:3.89 13:15:59 INFO - PROCESS | 9748 | Values: 118.0 124.0 117.0 120.0 117.0 113.0 118.0 115.0 122.0 124.0 13:15:59 INFO - PROCESS | 9748 | 13:15:59 INFO - PROCESS | 9748 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.50 Median:150.50 stddev:6.00 (4.0%) stddev-sans-first:5.29 13:15:59 INFO - PROCESS | 9748 | Values: 140.0 151.0 150.0 150.0 151.0 140.0 150.0 152.0 150.0 161.0 13:15:59 INFO - PROCESS | 9748 | 13:15:59 INFO - PROCESS | 9748 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:1.78 (2.6%) stddev-sans-first:1.88 13:15:59 INFO - PROCESS | 9748 | Values: 68.0 72.0 69.0 69.0 70.0 66.0 68.0 68.0 69.0 66.0 13:15:59 INFO - PROCESS | 9748 | -------- Summary: end -------- 13:15:59 INFO - PROCESS | 9748 | 13:16:00 INFO - TEST-INFO | 9748: exit 0 13:16:00 INFO - TEST-OK | kraken | took 59546ms 13:16:00 INFO - TEST-START | v8_7 13:16:00 INFO - operating with platform_type : linux_ 13:16:00 INFO - Initialising browser for v8_7 test... 13:16:00 INFO - TEST-INFO | started process 9996 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpIn7CG4/profile http://localhost:39741/getInfo.html) 13:16:05 INFO - PROCESS | 9996 | __metrics Screen width/height:1600/1200 13:16:05 INFO - PROCESS | 9996 | colorDepth:24 13:16:05 INFO - PROCESS | 9996 | Browser inner width/height: 1024/697 13:16:05 INFO - PROCESS | 9996 | __metrics 13:16:05 INFO - PROCESS | 9996 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 13:16:05 INFO - PROCESS | 9996 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:16:06 INFO - TEST-INFO | 9996: exit 0 13:16:06 INFO - Browser initialized. 13:16:06 INFO - Running cycle 1/1 for v8_7 test... 13:16:06 INFO - TEST-INFO | started process 10078 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpIn7CG4/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:16:18 INFO - PROCESS | 10078 | RSS: Main: 149188608 13:16:18 INFO - PROCESS | 10078 | 13:16:40 INFO - PROCESS | 10078 | Cycle 1(1): loaded http://localhost:39741/tests/v8_7/run.html (next: http://localhost:39741/tests/v8_7/run.html) 13:16:40 INFO - PROCESS | 10078 | RSS: Main: 547917824 13:16:40 INFO - PROCESS | 10078 | 13:16:40 INFO - PROCESS | 10078 | __start_tp_report 13:16:40 INFO - PROCESS | 10078 | _x_x_mozilla_page_load 13:16:40 INFO - PROCESS | 10078 | _x_x_mozilla_page_load_details 13:16:40 INFO - PROCESS | 10078 | |i|pagename|runs| 13:16:40 INFO - PROCESS | 10078 | |0;Richards;168.09547823163558 13:16:40 INFO - PROCESS | 10078 | |1;DeltaBlue;186.74136321195144 13:16:40 INFO - PROCESS | 10078 | |2;Encrypt;280.50490883590464 13:16:40 INFO - PROCESS | 10078 | |3;Decrypt;5170.103092783505 13:16:40 INFO - PROCESS | 10078 | |4;RayTrace;1945.5252918287938 13:16:40 INFO - PROCESS | 10078 | |5;Earley;789.4321766561515 13:16:40 INFO - PROCESS | 10078 | |6;Boyer;12268.292682926829 13:16:40 INFO - PROCESS | 10078 | |7;RegExp;43340.425531914894 13:16:40 INFO - PROCESS | 10078 | |8;Splay;565.9526493799324 13:16:40 INFO - PROCESS | 10078 | |9;NavierStokes;4826.923076923077 13:16:40 INFO - PROCESS | 10078 | __end_tp_report 13:16:40 INFO - PROCESS | 10078 | __startTimestamp1452460600905__endTimestamp 13:16:40 INFO - PROCESS | 10078 | 13:16:40 INFO - PROCESS | 10078 | ------- Summary: start ------- 13:16:40 INFO - PROCESS | 10078 | Number of tests: 10 13:16:40 INFO - PROCESS | 10078 | 13:16:40 INFO - PROCESS | 10078 | [#0] Richards Cycles:1 Average:168.10 Median:168.10 stddev:0.00 (0.0%) 13:16:40 INFO - PROCESS | 10078 | Values: 168.1 13:16:40 INFO - PROCESS | 10078 | 13:16:40 INFO - PROCESS | 10078 | [#1] DeltaBlue Cycles:1 Average:186.74 Median:186.74 stddev:0.00 (0.0%) 13:16:40 INFO - PROCESS | 10078 | Values: 186.7 13:16:40 INFO - PROCESS | 10078 | 13:16:40 INFO - PROCESS | 10078 | [#2] Encrypt Cycles:1 Average:280.50 Median:280.50 stddev:0.00 (0.0%) 13:16:40 INFO - PROCESS | 10078 | Values: 280.5 13:16:40 INFO - PROCESS | 10078 | 13:16:40 INFO - PROCESS | 10078 | [#3] Decrypt Cycles:1 Average:5170.10 Median:5170.10 stddev:0.00 (0.0%) 13:16:40 INFO - PROCESS | 10078 | Values: 5170.1 13:16:40 INFO - PROCESS | 10078 | 13:16:40 INFO - PROCESS | 10078 | [#4] RayTrace Cycles:1 Average:1945.53 Median:1945.53 stddev:0.00 (0.0%) 13:16:40 INFO - PROCESS | 10078 | Values: 1945.5 13:16:40 INFO - PROCESS | 10078 | 13:16:40 INFO - PROCESS | 10078 | [#5] Earley Cycles:1 Average:789.43 Median:789.43 stddev:0.00 (0.0%) 13:16:40 INFO - PROCESS | 10078 | Values: 789.4 13:16:40 INFO - PROCESS | 10078 | 13:16:40 INFO - PROCESS | 10078 | [#6] Boyer Cycles:1 Average:12268.29 Median:12268.29 stddev:0.00 (0.0%) 13:16:40 INFO - PROCESS | 10078 | Values: 12268.3 13:16:40 INFO - PROCESS | 10078 | 13:16:40 INFO - PROCESS | 10078 | [#7] RegExp Cycles:1 Average:43340.43 Median:43340.43 stddev:0.00 (0.0%) 13:16:40 INFO - PROCESS | 10078 | Values: 43340.4 13:16:40 INFO - PROCESS | 10078 | 13:16:40 INFO - PROCESS | 10078 | [#8] Splay Cycles:1 Average:565.95 Median:565.95 stddev:0.00 (0.0%) 13:16:40 INFO - PROCESS | 10078 | Values: 566.0 13:16:40 INFO - PROCESS | 10078 | 13:16:40 INFO - PROCESS | 10078 | [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 13:16:40 INFO - PROCESS | 10078 | Values: 4826.9 13:16:40 INFO - PROCESS | 10078 | -------- Summary: end -------- 13:16:40 INFO - PROCESS | 10078 | 13:16:41 INFO - TEST-INFO | 10078: exit 0 13:16:41 INFO - TEST-OK | v8_7 | took 41036ms 13:16:41 INFO - SUITE-END | took 1214s 13:16:41 INFO - Completed test suite (00:20:14) 13:16:41 INFO - Working with test: dromaeo_css 13:16:41 INFO - Generating results file: dromaeo_css 13:16:41 INFO - Working with test: kraken 13:16:41 INFO - Generating results file: kraken 13:16:41 INFO - javascript benchmark 13:16:41 INFO - Working with test: v8_7 13:16:41 INFO - Generating results file: v8_7 13:16:41 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:16:41 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,63,35]] 13:16:41 INFO - process_Request line: dromaeo_css 6642.29 graph.html#tests=[[72,63,35]] 13:16:41 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:16:42 INFO - process_Request line: kraken 1504.00 graph.html#tests=[[232,63,35]] 13:16:42 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:16:42 INFO - process_Request line: v8_7 19028.75 graph.html#tests=[[230,63,35]] 13:16:42 INFO - RETURN: dromaeo_css: 6642.29 13:16:42 INFO - RETURN: kraken: 1504.00 13:16:42 INFO - RETURN: v8_7: 19028.75 13:16:42 INFO - javascript benchmark 13:16:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3097.5353696945454, 3110.688931106888, 3150.19669984154, 3156.1793933577264, 3169.413658473182, 4927.852164435064, 4973.0302590994725, 4985.526374994374, 5005.949940500594, 5013.899722005558, 4800.111979283826, 4888.975555122228, 4917.901641967167, 4919.11455937931, 4957.950420495794, 4342.696011279212, 4537.773111344436, 4562.657800664947, 4589.747563883985, 4666.160091183586, 4547.408836851221, 4554.24854898943, 4596.195665758496, 4649.232876575361, 4652.883677908046, 4565.223911934962, 4600.0, 4610.124076425502, 4611.515790841981, 4621.468531118914, 4597.402337696111, 4615.353850460938, 4619.769011549409, 4641.675082744209, 4653.6509761768, 4505.346724724896, 4523.95476045242, 4604.171249175147, 4607.0, 4623.491415944243, 4777.355057067286, 4807.302941073524, 4813.302071199691, 4822.68652537584, 4834.6374021948495, 5282.260483532308, 5286.94261147772, 5289.973550132263, 5301.469853014706, 5340.652857564285, 5308.858595401968, 5325.627206095575, 5350.839474815762, 5406.783728650849, 5413.160960051159, 5100.38795344561, 5112.3865136183895, 5125.282460455502, 5125.564327032207, 5126.487351264881, 5566.443355664482, 5588.776448941997, 5594.496495315441, 5596.664200148004, 5598.888022239614, 5199.766010529535, 5201.115810312256, 5311.0174617695075, 5313.654612450256, 5338.092524270884, 5253.159494480941, 5360.115580929103, 5371.946280537223, 5376.91934620981, 5418.8916221675345, 5387.649802762808, 5418.376886658085, 5420.891582168413, 5429.104197807396, 5429.701366424805, 5247.422783493813, 5350.438203988603, 5371.435999220103, 5435.945640543544, 5470.617056806065, 4222.113356195172, 4253.63844073251, 4297.763623000703, 4318.956810431855, 4322.956770432318, 3802.8478860845808, 3845.0964023454467, 3861.7296789225047, 3876.8643097491454, 3880.1075752577067, 4998.600111991032, 5150.793968241303, 5152.407473140562, 5157.174852023733, 5196.1166601677805, 5211.166213405837, 5289.867753306121, 5309.309789727388, 5389.757460914268, 5448.591355648263, 5048.873778155576, 5048.949510504922, 5051.292819005343, 5062.569681577033, 5132.307138536325, 4606.88482787933, 4689.695169813951, 4699.224627936353, 4717.292406139038, 4728.598069164091, 5618.943810561924, 5666.858328541737, 5671.716414179275, 5769.94230057694, 5772.393898640582, 5332.440093790096, 5332.466753324637, 5336.0395128875625, 5347.0375332440535, 5348.384935732441, 5563.332400111851, 5584.0, 5584.134459158837, 5587.692676902647, 5593.440655934537, 5176.042432150064, 5297.205419187153, 5299.046171689133, 5304.787808487772, 5327.387350454593, 5299.8410047698635, 5328.333958255218, 5348.197770334481, 5360.758765855624, 5385.46145385474, 5224.947750522598, 5231.13686241781, 5232.3982741985155, 5243.187305967581, 5282.418933917343, 5167.793288268577, 5222.764975576184, 5241.475852414728, 5286.762095705625, 5297.258383826191, 5207.4271830099415, 5213.973930130325, 5221.869453263699, 5224.869378265574, 5236.711980841091, 5304.469553044819, 5327.493888080626, 5328.946710533, 5362.900605375967, 5374.892502150014, 5116.258142569232, 5121.7695203715175, 5151.253068305, 5196.740162991911, 5234.084035293732], "unit": "score", "name": "dojo.html", "value": 4979.715645113617}, {"lowerIsBetter": false, "replicates": [498.4940285610106, 530.9707966061865, 534.8342013975667, 559.5691317685383, 571.2003195526263, 24130.155444559445, 24357.14749983751, 34673.65326346736, 35080.12299692503, 35107.0, 37332.440013399755, 37845.62154378455, 37879.0, 37888.431673524916, 37904.0, 7136.393406560449, 8132.430729848898, 8323.458975166624, 8341.332693384546, 8342.457740246864, 7189.640517974107, 7304.196538380774, 7309.269073092675, 7312.0, 9509.09663581962, 9842.901570984306, 10038.799224015514, 10039.548220330104, 10046.949765251164, 10058.094771470565, 9763.755906102333, 9908.80182396348, 9917.851232231522, 9920.801583968352, 9926.2059035277, 7447.2180421055555, 7630.694772209105, 9712.902870971271, 9883.357581757235, 10153.238507111997, 35302.82348588266, 35423.64576354229, 35626.821865890764, 35678.0, 35839.4624080639, 3068.324968506925, 3185.2355434695737, 3191.377681352137, 3582.677559019675, 3614.5843228028853, 27727.555721578883, 27956.301092472648, 28161.57757633637, 28249.152525424066, 28322.29194270139, 3895.182720933473, 3907.9643894368014, 3976.483057202574, 3990.940135897964, 4018.4374187613757, 266126.9999999981, 267805.0, 269034.6548267246, 269854.0, 270039.0, 33909.0, 57353.0, 58253.0, 58335.249942501796, 58471.41528584745, 51190.0, 51333.743331283105, 51435.742821285654, 51438.48561514337, 51478.48521514812, 15059.47291844802, 15076.773848392282, 15160.31778569967, 15230.619234519225, 15238.466653667068, 11758.235714678518, 11761.647150585497, 11870.287782733063, 11879.168458208016, 11897.64307070772, 18596.535086622673, 18971.905140474486, 19075.332363367215, 19086.61826763457, 19098.2360705573, 7159.89260161098, 7360.447966402541, 7393.926060739432, 7403.370713489306, 7406.851862962818, 944.6032666280179, 947.3273975477488, 958.6836344006462, 974.8976357482362, 1057.9050682543684, 28104.156875293775, 28125.43749125006, 28542.85728571344, 28712.13863584054, 28787.712122878922, 999.3304485994402, 1044.556063672936, 1068.5031460370965, 1079.1205167788385, 1081.324172392255, 14865.628359290888, 14985.625359366102, 14995.302724419811, 15012.62468438276, 15034.0, 318968.0, 323505.0, 324947.0, 325615.0, 325699.0, 42544.1074276456, 42609.0, 42611.0, 42636.14727705428, 42676.0, 1169.590643274864, 1171.051448326858, 1187.4181650991125, 1188.8216767484946, 1290.832191815058, 27315.726842732114, 28209.294767630152, 28338.858305708338, 28571.571426429036, 28630.284242894093, 44271.77864110659, 44489.777551112034, 44570.55429445664, 44617.77691111524, 44677.10645787131, 290269.0, 292611.0, 292762.0, 294463.0, 294635.0, 23834.523309533364, 30542.541861871647, 31191.84404077965, 31343.21641958969, 31660.0, 35432.0, 36272.4559131608, 36408.81795591005, 36719.449208262435, 36932.0, 30591.694083058883, 31602.683973159976, 32230.19424514406, 32346.0, 32736.34527309488, 26727.23188659027, 31552.89812324383, 33400.33199335951, 34267.0, 34891.0, 35134.472982904765, 35640.82179589189, 36066.45900311445, 36214.45678314775, 36687.816560917025, 29263.414731705674, 29607.85196074006, 30940.381192376477, 32020.0, 32037.839810800797, 24271.0, 24349.87825060863, 24610.876945615157, 24754.628680570167, 24894.12870549522], "unit": "score", "name": "ext.html", "value": 17589.75267051529}, {"lowerIsBetter": false, "replicates": [347.32792047388307, 356.90252473643534, 359.43927473141895, 362.4490774023483, 364.43330620884524, 12296.5696200633, 12693.492260309577, 15751.212439378029, 16116.516504504869, 17146.056966866832, 13303.334833258323, 13834.377453014613, 14282.714345713079, 15264.236788160575, 15533.301001454933, 5125.948740512603, 5215.530602245797, 5329.280547126127, 5335.1197052486295, 5402.081646120169, 3653.0867283179205, 3794.0894185395446, 5242.475752424765, 6348.746050157988, 6638.634875081869, 6453.128827608283, 6594.967025164868, 6629.900551491707, 6631.867362652744, 6747.05541224229, 6555.573887697285, 6668.166479190101, 6700.93299067008, 6733.427658648996, 6748.055272261888, 6562.5406221564035, 6563.4092931635905, 6570.474362051073, 6575.473962083021, 6590.604563726191, 19471.610567788706, 20187.495312617157, 20318.898405508025, 21778.0, 21846.453838654, 2092.612866619665, 2100.5693832764246, 2224.9888750556306, 2286.531261091489, 2294.3461113582716, 12437.0, 12703.809442858364, 12733.74532509354, 13293.401796919163, 13943.093698909639, 1818.8635852310997, 1891.6689579323702, 1906.170815695177, 1917.2426891377827, 1933.4393026022437, 56664.0, 58599.41400585982, 58635.70682146562, 60433.69783151056, 60946.69526652383, 12458.377081146087, 14454.349554270084, 14890.883183761262, 14917.627059323386, 14920.32858521369, 22863.19978800734, 22877.19929802449, 22925.42686432819, 23224.30327090156, 23263.302100937, 8547.188017138486, 8641.30869530436, 8641.351898607629, 8697.0, 8707.519721647335, 7403.481756277009, 7521.7743467696055, 7522.924770752332, 7559.962200188964, 7572.8485430292185, 6224.346443623354, 6441.969284914391, 7475.476716629783, 7488.925110748823, 7559.962200189074, 2717.2120085175475, 2879.424115176973, 2938.5885975963383, 2997.400519895986, 3002.0, 818.423011776696, 823.7940514871282, 877.863931090682, 956.1872408452759, 959.9040095990345, 13172.60482185536, 14992.175430351217, 15300.770488442682, 16522.42171523991, 16891.91554042222, 870.4168207300997, 905.5879574793453, 939.5959737313021, 966.8694726212012, 1010.9393436393693, 7837.451378403457, 8440.915590844135, 8696.826063478695, 9081.54592270384, 9083.727488175366, 428744.0, 499872.0, 508123.0, 512797.0, 550439.0, 33837.83081084628, 33944.0, 67556.32443675598, 68396.29009274808, 69923.65038174776, 1129.5989923577176, 1139.8917102875214, 1153.7058050197313, 1174.0, 1187.4300335838846, 13159.342032898508, 14293.714125717219, 14815.111093334435, 14832.555023349316, 14842.925785371004, 31845.36309273848, 32879.0, 33193.336133276716, 33314.0, 33360.165995849326, 41587.7920610395, 41667.7916610415, 41677.791611042965, 42809.357859631506, 43424.56575434205, 7036.366726994595, 7084.114485689289, 7188.712451501881, 7193.136823581203, 7377.704891804267, 10109.747256318651, 10928.125749939827, 10961.56153753841, 11139.387333696724, 11158.721031974266, 9301.534923253945, 9435.858462123211, 9580.18568421692, 9624.615015399586, 9655.179309758749, 7101.0, 9164.862527061967, 9442.669506567237, 9516.762080948032, 9594.42433453995, 9188.0, 9557.044295570387, 9560.952195238979, 9725.832900052039, 9774.706758796963, 6860.691268892812, 7062.964685176541, 7073.115860517435, 7297.0, 7396.0, 8384.329253659815, 8991.14584114508, 9027.819443611223, 9240.306976976619, 9475.05249475047], "unit": "score", "name": "jquery.html", "value": 8634.290680777121}, {"lowerIsBetter": false, "replicates": [1114.504045699664, 1219.9024078073755, 1250.106174085529, 1258.4525731306883, 1780.8041115477295, 6530.249021362545, 7105.396041336486, 7247.2390399008, 7261.3101755333155, 7265.89101163483, 7263.927360726391, 7437.851242975137, 7439.702411903518, 7443.144038435582, 7453.0, 1932.7487426634518, 1978.3867001229664, 2273.7044184256024, 2303.332033710222, 2331.4987277735286, 2315.1086831569883, 2354.7174339079247, 2355.6113241315247, 2355.7408685044716, 2356.387339291788, 2268.1834539565743, 2320.6751054852334, 2321.802646775018, 2325.7209134903837, 2326.6510023496444, 2224.1881713174657, 2239.1491233331312, 2269.6368581026954, 2278.1001504405667, 2279.2250634784086, 2294.816414686821, 2298.367948814073, 2320.7679232076825, 2327.697399338092, 2327.976720232793, 7057.858842823115, 7092.822679433004, 7106.822329441754, 7110.715571377139, 7113.7154513819905, 4534.77326133692, 4650.51169627191, 4651.44182698075, 4652.139354219446, 4655.185342565072, 2954.970450295491, 3006.038067818299, 3056.0526236866644, 3097.0863595239457, 3120.3915236528887, 1057.5293994172596, 1088.4884104470887, 1101.7851518953807, 1102.393683474086, 1121.4504892602647, 10051.849222261671, 10210.081092701692, 10227.89772102277, 10229.181665466745, 10239.948800255952, 4302.806373713191, 4376.299792033323, 4382.912341753147, 4385.758783266887, 4402.273624851863, 8038.155993620702, 8198.139195384518, 8202.753917382488, 8221.342292616577, 8225.83548329042, 4727.5745182933015, 4737.147313483606, 4738.4550776660435, 4740.051989602093, 4742.0, 4337.544557821447, 4339.153864996359, 4367.737935723867, 4378.08060307339, 4378.956210437918, 3439.2605589797813, 3450.5859296884532, 3458.2046129390383, 3459.7924124552605, 3512.6838584527513, 1085.9837102443469, 1092.088106431123, 1093.5625749700184, 1102.6361300770727, 1106.8505751723576, 1043.8016776812533, 1065.0361422912122, 1065.2383545764815, 1194.970125746846, 1318.2156616812981, 6922.5154239202775, 7022.1924478685605, 7059.435245180373, 7071.04540886984, 7076.0, 506.4960364437408, 515.1937218253421, 517.2887279990014, 517.4256575201523, 520.8854052108605, 5483.177523371526, 5524.944750552443, 5549.972250138723, 5552.555795536347, 5553.666779993133, 8457.45026573283, 8507.914920850835, 8548.444351117158, 8620.13798620133, 8785.209331160226, 8495.532745699045, 8574.957125214334, 8589.656413743505, 8607.440516366416, 8619.224269815746, 6595.967020164869, 6695.799126026227, 6758.155230596274, 6892.655367231619, 7048.118985126859, 6761.290064543349, 6783.084283621648, 6789.660516974231, 6823.454123669995, 6838.384545390939, 7424.925750742424, 7576.810579735551, 7613.847723045397, 7624.27569380908, 7625.046869141357, 7802.453828231969, 7856.764297071097, 7882.09355924076, 7972.720954766555, 7984.960075199587, 6017.699115044318, 6151.9384806151365, 6670.566413183322, 6837.897431538431, 6844.315568443116, 7143.392811611071, 7146.964265178641, 7165.928340716526, 7170.641467926687, 7183.20984691673, 6466.741330346922, 6706.765263215764, 6711.530192886451, 6761.830954226184, 6874.106366172366, 6589.242237142598, 6614.404703576702, 6637.568558043712, 6649.168853893263, 6809.557378770364, 6762.7826991142065, 6812.250652428328, 6814.250432452327, 6817.045613613999, 6879.7248110077035, 6020.367861374492, 6065.787697430569, 6103.145559621746, 6103.969480152571, 6112.32764395925, 6379.170707807955, 6401.167848179707, 6413.8717225656155, 6419.711112999833, 6431.292557818542], "unit": "score", "name": "mootools.html", "value": 4226.714299334852}, {"lowerIsBetter": false, "replicates": [343.13016503164476, 346.6637361759094, 350.1177033874636, 356.12571137856565, 359.5918632352281, 5732.79935202268, 5736.139579063142, 5741.253637027191, 5770.624909380887, 5798.043322851735, 5466.316710411199, 5572.219889215503, 5623.887522249553, 5727.856803579913, 5727.942720572793, 4486.12520558492, 4514.27771556551, 4524.5249248828895, 4532.750698711569, 4552.385427967215, 4316.158349121923, 4479.193745125876, 4513.887152821173, 4513.977430112861, 4514.345419914127, 4333.0, 4341.218580655481, 4417.182821178077, 4434.201843668138, 4444.066745983348, 4424.225760491901, 4430.468343798732, 4435.223835828733, 4439.178751930903, 4442.489113751895, 3966.4446977423186, 3970.305196590585, 4006.599340065999, 4011.9799401002806, 4013.337799263118, 5401.729913504349, 5497.862553436156, 5502.284702988626, 5504.4770746778995, 5515.310586176728, 5206.062908676437, 5244.318238628992, 5245.213218017289, 5245.816396426107, 5250.9474905250845, 6091.451769340781, 6097.3292937776805, 6097.542684298695, 6102.145699602059, 6103.511719062464, 4414.3819865218575, 4423.093265880487, 4428.2914733642465, 4430.556944305576, 4435.223835828717, 6131.601445906002, 6314.8105556833825, 6344.206974128234, 6370.267419246799, 6371.076193951897, 4423.646108311327, 4446.088551846864, 4446.799894004777, 4450.666200035011, 4454.37638730578, 4498.302763071729, 4498.977505112453, 4502.144592527409, 4502.369668246448, 4504.054148628825, 5151.098557752378, 5158.406783219978, 5165.66423182492, 5167.431582525919, 5174.301469301596, 6004.459598636144, 6063.181470501514, 6148.077788331786, 6149.600275982047, 6163.599366041282, 1558.5168597734512, 1572.9134897580743, 1573.087609186669, 1573.2851192200555, 1575.4249698859833, 1041.770810421706, 1071.6410002649045, 1072.0512346573182, 1074.978500429987, 1079.2391364088298, 5917.378675239124, 5923.674197919156, 5925.111233315038, 5931.940680593225, 5933.584649074523, 6249.187605611241, 6253.405926436982, 6254.812355629248, 6258.123862659231, 6262.185915830913, 5713.0, 5803.506701930299, 5833.0, 5995.910061348996, 6009.308929473166, 3372.5952885653387, 3391.5082313064468, 3400.2349471368743, 3402.319536092791, 3402.6086999994823, 6689.6989635466525, 6693.698783554752, 6696.799096027127, 6701.798946031627, 6702.798916032527, 6335.524835637253, 6496.7726129585235, 6542.672866356663, 6544.247411547638, 6553.0, 6713.1944166700305, 6725.66371681414, 6730.461563074942, 6730.629815360202, 6733.7643182489355, 6355.491560675043, 6356.9046464304, 6359.173307470184, 6362.809115726535, 6370.0, 6152.446279834837, 6308.873822523616, 6326.829536535755, 6336.1446204763615, 6340.207474065742, 6663.466922646274, 6719.832004199934, 6721.260661327348, 6722.495812814156, 6724.293949135466, 4423.469183697976, 4465.106978604227, 4467.128909862546, 4470.2400591900105, 4472.150291444746, 6222.408871157233, 6358.332375100548, 6373.458256048288, 6379.0, 6383.234011918599, 6200.968995154995, 6203.286622038343, 6203.565750397609, 6209.472194863487, 6221.502279817513, 6193.783217587363, 6206.162168107247, 6207.193064901715, 6209.068639704081, 6212.720427580679, 5933.821985340447, 6085.513158947185, 6093.0, 6093.664848433379, 6098.11577321277, 6224.0, 6224.844378890382, 6228.532860035425, 6231.719572619152, 6234.501239900706, 6037.969810150921, 6081.604695694766, 6084.087386892002, 6088.6042407243385, 6092.7867524638195], "unit": "score", "name": "prototype.html", "value": 4679.992667559107}, {"lowerIsBetter": false, "replicates": [728.5847067171711, 776.7048521561808, 786.5752493653428, 801.9117897031325, 811.2779626132743, 12740.426680799363, 12888.74222515549, 12909.160904541199, 12922.612321630366, 12930.74138517229, 11937.7612447751, 11946.880531194685, 11991.820122698167, 12027.579034733786, 12031.699207519816, 465.8765427161805, 475.73358919005295, 519.3974989012746, 532.6963630730485, 532.7229840482946, 804.1235053791366, 808.308895894011, 813.9511629302261, 814.1004190369615, 814.1288820961573, 486.9943566535094, 489.19283182748393, 855.6278019061729, 860.3246451535515, 860.3418384935529, 540.4784383070337, 547.2420697334181, 547.8301726464808, 549.2859282932192, 549.9587530935196, 414.6454781162093, 415.14894466344174, 416.385830899424, 417.07617626956437, 418.8596820065282, 12361.876381236252, 12394.380280985915, 12407.689807754788, 12443.191192572454, 12471.002319814394, 11132.666020019413, 11595.478203480863, 11775.528978840835, 11851.525938962517, 11917.702057448547, 10177.0525606202, 10368.79262414755, 10389.9480502597, 10481.836779280664, 10525.526351314133, 3385.8137802420856, 3411.337180280747, 3441.8451169697173, 3450.5859296884532, 3458.4985177149424, 36010.639893600994, 36091.27817443663, 36139.45790813114, 36264.912052638465, 36278.8186059068, 8883.555822208864, 9030.096990300917, 9289.581968811419, 9351.859722104175, 9552.0, 14997.325120369609, 15001.099934003994, 15011.099334039993, 15046.0, 15069.0, 6558.803235902835, 6727.730890764413, 6740.865182696319, 7347.0, 7363.300486453778, 4124.092699606082, 4200.831966721297, 4237.470316210473, 4292.59220374064, 4350.0212452198, 2875.1087162979543, 2894.3343031102963, 2901.7098290170816, 2915.1400336900665, 2923.9707602924123, 536.2813829468531, 544.5072209650273, 546.0362460257649, 550.5265471694339, 551.9972400137974, 527.9889012541017, 533.317353787153, 544.9782008719686, 647.7344288841647, 668.488606216245, 8053.476524025919, 8470.0, 8726.345524085718, 8946.284297256203, 9224.262059035262, 539.8596364945142, 551.8289330307617, 639.5810743962619, 650.6291413894035, 657.9835504112435, 3829.181138958972, 4143.6892233082035, 4206.3690446432665, 4224.514180869178, 4293.978530107392, 53768.0, 54344.184837227476, 54415.72792136014, 54889.451105488435, 55025.72487137539, 42123.7893810529, 42605.36091958623, 42698.57301427008, 42890.356644650354, 43777.0, 41064.794676025835, 41151.588484115375, 41371.7931410347, 41520.0, 41641.167176656294, 8023.959880200561, 8295.294899933573, 8540.829183416172, 8807.119288071142, 8824.514651694219, 30744.538831917092, 31055.223619409695, 31237.375252494367, 31785.841070794497, 31913.36173276475, 39921.80039099786, 40513.59486405216, 40644.0, 40739.79630101831, 41320.586794131676, 9745.733054702843, 10159.187265018929, 10372.481375931324, 10513.369197848153, 10528.894711052792, 12058.939705301418, 12251.203671761306, 12262.632121036384, 12356.073294502768, 12715.046371521988, 10465.476726163814, 11050.834237486284, 11079.612213572485, 11117.22179447431, 11216.887831121585, 11093.50079246452, 11258.38078905668, 11355.432228388383, 11425.600103996652, 11565.884341156481, 10439.895601044196, 10805.675829725122, 10942.562297508011, 10964.725881853017, 11259.324440533594, 9495.715128546155, 9884.30809843304, 9887.110160085627, 10087.848682269918, 10216.335938163995, 8724.993251552052, 9236.584353704235, 9433.585760324164, 9895.307328486937, 10065.647702330383], "unit": "score", "name": "yui.html", "value": 5740.188007663004}], "name": "dromaeo_css", "value": 6642.288025819677}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 105.0, 112.0, 112.0, 111.0, 101.0, 110.0, 109.0, 110.0], "unit": "score", "name": "ai-astar", "value": 109.0}, {"lowerIsBetter": true, "replicates": [146.0, 125.0, 149.0, 163.0, 127.0, 150.0, 156.0, 125.0, 168.0, 127.0], "unit": "score", "name": "audio-beat-detection", "value": 143.6}, {"lowerIsBetter": true, "replicates": [161.0, 162.0, 160.0, 160.0, 162.0, 163.0, 160.0, 164.0, 163.0, 166.0], "unit": "score", "name": "audio-dft", "value": 162.1}, {"lowerIsBetter": true, "replicates": [75.0, 75.0, 90.0, 92.0, 74.0, 71.0, 75.0, 105.0, 93.0, 70.0], "unit": "score", "name": "audio-fft", "value": 82.0}, {"lowerIsBetter": true, "replicates": [97.0, 97.0, 142.0, 96.0, 96.0, 96.0, 138.0, 96.0, 96.0, 141.0], "unit": "score", "name": "audio-oscillator", "value": 109.5}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 108.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0], "unit": "score", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [57.0, 57.0, 57.0, 55.0, 56.0, 56.0, 55.0, 65.0, 52.0, 54.0], "unit": "score", "name": "json-parse-financial", "value": 56.4}, {"lowerIsBetter": true, "replicates": [68.0, 67.0, 68.0, 68.0, 69.0, 68.0, 69.0, 68.0, 69.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.3}, {"lowerIsBetter": true, "replicates": [86.0, 85.0, 88.0, 88.0, 86.0, 87.0, 86.0, 86.0, 85.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.3}, {"lowerIsBetter": true, "replicates": [118.0, 124.0, 117.0, 120.0, 117.0, 113.0, 118.0, 115.0, 122.0, 124.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 118.8}, {"lowerIsBetter": true, "replicates": [140.0, 151.0, 150.0, 150.0, 151.0, 140.0, 150.0, 152.0, 150.0, 161.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 149.5}, {"lowerIsBetter": true, "replicates": [68.0, 72.0, 69.0, 69.0, 70.0, 66.0, 68.0, 68.0, 69.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.5}], "name": "kraken", "value": 1504.0}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.09547823163558], "unit": "score", "name": "Richards", "value": 210.01159799999994}, {"lowerIsBetter": false, "replicates": [186.74136321195144], "unit": "score", "name": "DeltaBlue", "value": 354.06189}, {"lowerIsBetter": false, "replicates": [280.50490883590464], "unit": "score", "name": "Encrypt", "value": 948.9352650000003}, {"lowerIsBetter": false, "replicates": [5170.103092783505], "unit": "score", "name": "Decrypt", "value": 51.48466001994015}, {"lowerIsBetter": false, "replicates": [1945.5252918287938], "unit": "score", "name": "RayTrace", "value": 380.354346}, {"lowerIsBetter": false, "replicates": [789.4321766561515], "unit": "score", "name": "Earley", "value": 844.2308531468531}, {"lowerIsBetter": false, "replicates": [12268.292682926829], "unit": "score", "name": "Boyer", "value": 54.32402186878729}, {"lowerIsBetter": false, "replicates": [43340.425531914894], "unit": "score", "name": "RegExp", "value": 21.019290623465874}, {"lowerIsBetter": false, "replicates": [565.9526493799324], "unit": "score", "name": "Splay", "value": 143.98907768924306}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 19028.75487059693}]} 13:16:42 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,35]]", "result": "19028.75"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,35]]", "result": "1504.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,35]]", "result": "6642.29"}}} 13:16:42 INFO - Return code: 0 13:16:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 13:16:42 INFO - # TBPL SUCCESS # 13:16:42 INFO - Running post-action listener: _resource_record_post_action 13:16:42 INFO - Running post-run listener: _resource_record_post_run 13:16:43 INFO - Total resource usage - Wall time: 1232s; CPU: 13.0%; Read bytes: 4096; Write bytes: 560594944; Read time: 196; Write time: 562488 13:16:43 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 239087616; Read time: 0; Write time: 262092 13:16:43 INFO - run-tests - Wall time: 1216s; CPU: 13.0%; Read bytes: 4096; Write bytes: 321507328; Read time: 196; Write time: 300396 13:16:43 INFO - Running post-run listener: _upload_blobber_files 13:16:43 INFO - Blob upload gear active. 13:16:43 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 13:16:43 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:16:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 13:16:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Mozilla-Inbound -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 13:16:43 INFO - (blobuploader) - INFO - Open directory for files ... 13:16:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 13:16:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:16:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:16:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:16:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:16:44 INFO - (blobuploader) - INFO - Done attempting. 13:16:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:16:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:16:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:16:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:16:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:16:45 INFO - (blobuploader) - INFO - Done attempting. 13:16:45 INFO - (blobuploader) - INFO - Iteration through files over. 13:16:45 INFO - Return code: 0 13:16:45 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 13:16:45 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 13:16:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/97bb8b0a3fa43b97e036581395ee7c37b09837e47fc1ac862a3e6f2e89fc057988cc619439f9a56056a372c8d006927f11b63ffe1dd48fa00a94702e05dde780", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0f30864b2f44e793239436119d7c6888515410410257d8029c702e0f0efef5f8fb651f2ff5a9be47ed90dfb36fd4d31004aee9b90690063e1aca93c1bf86cd40"} 13:16:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 13:16:45 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 13:16:45 INFO - Contents: 13:16:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/97bb8b0a3fa43b97e036581395ee7c37b09837e47fc1ac862a3e6f2e89fc057988cc619439f9a56056a372c8d006927f11b63ffe1dd48fa00a94702e05dde780", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0f30864b2f44e793239436119d7c6888515410410257d8029c702e0f0efef5f8fb651f2ff5a9be47ed90dfb36fd4d31004aee9b90690063e1aca93c1bf86cd40"} 13:16:45 INFO - Copying logs to upload dir... 13:16:45 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1277.346248 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-01-10 13:16:43.685617) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-10 13:16:43.689298) ========= 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=eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/97bb8b0a3fa43b97e036581395ee7c37b09837e47fc1ac862a3e6f2e89fc057988cc619439f9a56056a372c8d006927f11b63ffe1dd48fa00a94702e05dde780", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0f30864b2f44e793239436119d7c6888515410410257d8029c702e0f0efef5f8fb651f2ff5a9be47ed90dfb36fd4d31004aee9b90690063e1aca93c1bf86cd40"} build_url:https://queue.taskcluster.net/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011358 build_url: 'https://queue.taskcluster.net/v1/task/A1R-PlgwTIaeE5HSswFjcQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/97bb8b0a3fa43b97e036581395ee7c37b09837e47fc1ac862a3e6f2e89fc057988cc619439f9a56056a372c8d006927f11b63ffe1dd48fa00a94702e05dde780", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0f30864b2f44e793239436119d7c6888515410410257d8029c702e0f0efef5f8fb651f2ff5a9be47ed90dfb36fd4d31004aee9b90690063e1aca93c1bf86cd40"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-10 13:16:43.716211) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-10 13:16:43.716565) ========= 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=eca2ab57546de7b41f256b19000001ef-1452453995.432272-106424613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004457 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-10 13:16:43.804028) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-10 13:16:43.804626) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-10 13:16:43.805163) ========= ========= Total master_lag: 0.25 =========