builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-007 starttime: 1453528813.93 results: success (0) buildid: 20160122193307 builduid: 74112e7b856b448cbafce44de131548f revision: c49f59a5b8288c9cbb782b8beedb9b12b7171c67 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:13.933921) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:13.934467) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:13.934803) ========= 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=b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006374 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:13.980595) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:13.980921) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:14.139000) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:14.139329) ========= 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=b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004585 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:14.208434) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:14.208740) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:14.209165) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:14.209502) ========= 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=b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-22 22:00:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.3M=0s 2016-01-22 22:00:14 (35.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097489 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:14.329186) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:14.329529) ========= 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=b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022072 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:14.390640) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:14.391005) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c49f59a5b8288c9cbb782b8beedb9b12b7171c67 --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 c49f59a5b8288c9cbb782b8beedb9b12b7171c67 --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=b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-22 22:00:14,855 truncating revision to first 12 chars 2016-01-22 22:00:14,855 Setting DEBUG logging. 2016-01-22 22:00:14,855 attempt 1/10 2016-01-22 22:00:14,855 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/c49f59a5b828?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-22 22:00:15,101 unpacking tar archive at: mozilla-inbound-c49f59a5b828/testing/mozharness/ program finished with exit code 0 elapsedTime=0.518160 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:14.925844) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:14.926240) ========= echo c49f59a5b8288c9cbb782b8beedb9b12b7171c67 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'c49f59a5b8288c9cbb782b8beedb9b12b7171c67'] 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=b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False c49f59a5b8288c9cbb782b8beedb9b12b7171c67 program finished with exit code 0 elapsedTime=0.004653 script_repo_revision: 'c49f59a5b8288c9cbb782b8beedb9b12b7171c67' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:14.980593) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:14.980974) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-22 22:00:14.995353) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-01-22 22:00:14.995751) ========= /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=b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:00:15 INFO - MultiFileLogger online at 20160122 22:00:15 in /builds/slave/test-pgo 22:00:15 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 22:00:15 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 22:00:15 INFO - {'append_to_log': False, 22:00:15 INFO - 'base_work_dir': '/builds/slave/test-pgo', 22:00:15 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:00:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 22:00:15 INFO - 'branch': 'Mozilla-Inbound', 22:00:15 INFO - 'buildbot_json_path': 'buildprops.json', 22:00:15 INFO - 'config_files': ('talos/linux_config.py',), 22:00:15 INFO - 'default_actions': ('clobber', 22:00:15 INFO - 'read-buildbot-config', 22:00:15 INFO - 'download-and-extract', 22:00:15 INFO - 'populate-webroot', 22:00:15 INFO - 'create-virtualenv', 22:00:15 INFO - 'install', 22:00:15 INFO - 'run-tests'), 22:00:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:00:15 INFO - 'download_minidump_stackwalk': True, 22:00:15 INFO - 'download_symbols': 'ondemand', 22:00:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:00:15 INFO - 'tooltool.py': '/tools/tooltool.py', 22:00:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:00:15 INFO - '/tools/misc-python/virtualenv.py')}, 22:00:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:00:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:00:15 INFO - 'installer_path': 'installer.exe', 22:00:15 INFO - 'log_level': 'info', 22:00:15 INFO - 'log_name': 'talos', 22:00:15 INFO - 'log_to_console': True, 22:00:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:00:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:00:15 INFO - 'opt_config_files': (), 22:00:15 INFO - 'pip_index': False, 22:00:15 INFO - 'sps_profile': False, 22:00:15 INFO - 'sps_profile_interval': 0, 22:00:15 INFO - 'suite': 'dromaeojs', 22:00:15 INFO - 'system_bits': '32', 22:00:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:00:15 INFO - 'title': 'talos-linux64-ix-007', 22:00:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:00:15 INFO - 'use_talos_json': True, 22:00:15 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 22:00:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:00:15 INFO - 'work_dir': 'build'} 22:00:15 INFO - ##### 22:00:15 INFO - ##### Running clobber step. 22:00:15 INFO - ##### 22:00:15 INFO - Running pre-action listener: _resource_record_pre_action 22:00:15 INFO - Running main action method: clobber 22:00:15 INFO - rmtree: /builds/slave/test-pgo/build 22:00:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 22:00:16 INFO - Running post-action listener: _resource_record_post_action 22:00:16 INFO - ##### 22:00:16 INFO - ##### Running read-buildbot-config step. 22:00:16 INFO - ##### 22:00:16 INFO - Running pre-action listener: _resource_record_pre_action 22:00:16 INFO - Running main action method: read_buildbot_config 22:00:16 INFO - Using buildbot properties: 22:00:16 INFO - { 22:00:16 INFO - "project": "", 22:00:16 INFO - "product": "firefox", 22:00:16 INFO - "script_repo_revision": "production", 22:00:16 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:00:16 INFO - "repository": "", 22:00:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:00:16 INFO - "buildid": "20160122193307", 22:00:16 INFO - "pgo_build": "True", 22:00:16 INFO - "basedir": "/builds/slave/test-pgo", 22:00:16 INFO - "buildnumber": 654, 22:00:16 INFO - "slavename": "talos-linux64-ix-007", 22:00:16 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:00:16 INFO - "platform": "ubuntu64_hw", 22:00:16 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:00:16 INFO - "branch": "mozilla-inbound", 22:00:16 INFO - "repo_path": "integration/mozilla-inbound", 22:00:16 INFO - "revision": "c49f59a5b8288c9cbb782b8beedb9b12b7171c67", 22:00:16 INFO - "stage_platform": "linux64-pgo", 22:00:16 INFO - "builduid": "74112e7b856b448cbafce44de131548f", 22:00:16 INFO - "slavebuilddir": "test" 22:00:16 INFO - } 22:00:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 22:00:16 INFO - Running post-action listener: _resource_record_post_action 22:00:16 INFO - ##### 22:00:16 INFO - ##### Running download-and-extract step. 22:00:16 INFO - ##### 22:00:16 INFO - Running pre-action listener: _resource_record_pre_action 22:00:16 INFO - Running main action method: download_and_extract 22:00:16 INFO - mkdir: /builds/slave/test-pgo/build/tests 22:00:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:16 INFO - https://queue.taskcluster.net/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 22:00:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/test_packages.json 22:00:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/test_packages.json 22:00:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 22:00:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 22:00:20 INFO - Downloaded 1448 bytes. 22:00:20 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 22:00:20 INFO - Using the following test package requirements: 22:00:20 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:20 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:20 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:00:20 INFO - u'gtest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:20 INFO - u'firefox-46.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:00:20 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:20 INFO - u'jsshell-linux-x86_64.zip'], 22:00:20 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:20 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:00:20 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:20 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:20 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:00:20 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:20 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:00:20 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:20 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:00:20 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:20 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:20 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:00:20 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:20 INFO - https://queue.taskcluster.net/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:00:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:00:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:00:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/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 22:00:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/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 22:00:31 INFO - Downloaded 22490737 bytes. 22:00:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 22:00:31 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 22:00:32 INFO - Return code: 0 22:00:32 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 22:00: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')]} 22:00:32 INFO - https://queue.taskcluster.net/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:00:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:00:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:00:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/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 22:00:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/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 22:00:33 INFO - Downloaded 22490737 bytes. 22:00:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 22:00:33 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 22:00:35 INFO - Return code: 0 22:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:35 INFO - https://queue.taskcluster.net/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:00:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:00:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:00:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/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 22:00:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/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 22:00:36 INFO - Downloaded 10706572 bytes. 22:00:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 22:00:36 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test-pgo/build/tests 22:00:36 INFO - Return code: 0 22:00: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')]} 22:00:36 INFO - https://queue.taskcluster.net/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:00:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:00:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:00:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/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 22:00:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FJdtlG-vSTaaiACdrffsgA/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 22:00:37 INFO - Downloaded 55968903 bytes. 22:00:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:00:37 INFO - mkdir: /builds/slave/test-pgo/properties 22:00:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 22:00:37 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 22:00:37 INFO - Contents: 22:00:37 INFO - build_url:https://queue.taskcluster.net/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:00:37 INFO - Running post-action listener: _resource_record_post_action 22:00:37 INFO - Running post-action listener: set_extra_try_arguments 22:00:37 INFO - ##### 22:00:37 INFO - ##### Running populate-webroot step. 22:00:37 INFO - ##### 22:00:37 INFO - Running pre-action listener: _resource_record_pre_action 22:00:37 INFO - Running main action method: populate_webroot 22:00:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:00:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:00:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:00:37 INFO - '--tppagecycles', 22:00:37 INFO - '7'], 22:00:37 INFO - 'tests': ['tsvgm']}}, 22:00:37 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:00:37 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:00:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:00:37 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:00:37 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:00:37 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:00:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:00:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:37 INFO - 'talos_options': ['--e10s'], 22:00:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:00:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:37 INFO - 'tests': ['damp', 'tps']}, 22:00:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:37 INFO - 'talos_options': ['--e10s'], 22:00:37 INFO - 'tests': ['damp', 'tps']}, 22:00:37 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:00:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:00:37 INFO - 'other': {'tests': ['a11yr', 22:00:37 INFO - 'ts_paint', 22:00:37 INFO - 'tpaint', 22:00:37 INFO - 'sessionrestore', 22:00:37 INFO - 'sessionrestore_no_auto_restore']}, 22:00:37 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:00:37 INFO - 'tests': ['a11yr', 22:00:37 INFO - 'ts_paint', 22:00:37 INFO - 'tpaint', 22:00:37 INFO - 'sessionrestore', 22:00:37 INFO - 'sessionrestore_no_auto_restore']}, 22:00:37 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:00:37 INFO - 'tests': ['a11yr', 22:00:37 INFO - 'ts_paint', 22:00:37 INFO - 'tpaint', 22:00:37 INFO - 'sessionrestore', 22:00:37 INFO - 'sessionrestore_no_auto_restore']}, 22:00:37 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:00:37 INFO - 'tests': ['a11yr', 22:00:37 INFO - 'ts_paint', 22:00:37 INFO - 'tpaint', 22:00:37 INFO - 'sessionrestore', 22:00:37 INFO - 'sessionrestore_no_auto_restore']}, 22:00:37 INFO - 'other_l64': {'tests': ['a11yr', 22:00:37 INFO - 'ts_paint', 22:00:37 INFO - 'tpaint', 22:00:37 INFO - 'sessionrestore', 22:00:37 INFO - 'sessionrestore_no_auto_restore']}, 22:00:37 INFO - 'other_nol64': {'tests': ['a11yr', 22:00:37 INFO - 'ts_paint', 22:00:37 INFO - 'tpaint', 22:00:37 INFO - 'sessionrestore', 22:00:37 INFO - 'sessionrestore_no_auto_restore']}, 22:00:37 INFO - 'svgr': {'tests': ['tsvgx', 22:00:37 INFO - 'tsvgr_opacity', 22:00:37 INFO - 'tart', 22:00:37 INFO - 'tscrollx', 22:00:37 INFO - 'cart']}, 22:00:37 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:00:37 INFO - 'tests': ['tsvgx', 22:00:37 INFO - 'tsvgr_opacity', 22:00:37 INFO - 'tart', 22:00:37 INFO - 'tscrollx', 22:00:37 INFO - 'cart']}, 22:00:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:37 INFO - 'tests': ['tp5o']}, 22:00:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:37 INFO - 'talos_options': ['--e10s'], 22:00:37 INFO - 'tests': ['tp5o']}, 22:00:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:37 INFO - 'talos_options': ['--xperf_path', 22:00:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:00:37 INFO - 'tests': ['tp5n']}, 22:00:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:37 INFO - 'talos_options': ['--e10s', 22:00:37 INFO - '--xperf_path', 22:00:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:00:37 INFO - 'tests': ['tp5n']}}, 22:00:37 INFO - 'talos.zip': {'path': '', 22:00:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:00:37 INFO - Running post-action listener: _resource_record_post_action 22:00:37 INFO - ##### 22:00:37 INFO - ##### Running create-virtualenv step. 22:00:37 INFO - ##### 22:00:37 INFO - Running pre-action listener: _resource_record_pre_action 22:00:37 INFO - Running main action method: create_virtualenv 22:00:37 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 22:00:37 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 22:00:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 22:00:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:00:37 INFO - Using real prefix '/usr' 22:00:37 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 22:00:38 INFO - Installing distribute.............................................................................................................................................................................................done. 22:00:40 INFO - Installing pip.................done. 22:00:40 INFO - Return code: 0 22:00:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 22:00: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')]} 22:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00: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')]} 22:00:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:40 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6a57ed3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b49480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8660>, '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 0x2bd9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd9a10>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', '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 22:00:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 22:00:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:00:40 INFO - Using env: {'DISPLAY': ':0', 22:00:40 INFO - 'HOME': '/home/cltbld', 22:00:40 INFO - 'LANG': 'en_US.UTF-8', 22:00:40 INFO - 'LANGUAGE': 'en_US:en', 22:00:40 INFO - 'LOGNAME': 'cltbld', 22:00:40 INFO - 'MAIL': '/var/mail/cltbld', 22:00:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:40 INFO - 'MOZ_NO_REMOTE': '1', 22:00:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:40 INFO - 'NO_EM_RESTART': '1', 22:00:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:00:40 INFO - 'PWD': '/builds/slave/test-pgo', 22:00:40 INFO - 'SHELL': '/bin/bash', 22:00:40 INFO - 'SHLVL': '1', 22:00:40 INFO - 'TERM': 'linux', 22:00:40 INFO - 'TMOUT': '86400', 22:00:40 INFO - 'USER': 'cltbld', 22:00:40 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', 22:00:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:40 INFO - '_': '/tools/buildbot/bin/python'} 22:00:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:40 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 22:00:40 INFO - Cleaning up... 22:00:40 INFO - Return code: 0 22:00:40 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 22:00: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')]} 22:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00: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')]} 22:00:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:40 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6a57ed3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b49480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8660>, '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 0x2bd9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd9a10>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', '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 22:00:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 22:00:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 22:00:40 INFO - Using env: {'DISPLAY': ':0', 22:00:40 INFO - 'HOME': '/home/cltbld', 22:00:40 INFO - 'LANG': 'en_US.UTF-8', 22:00:40 INFO - 'LANGUAGE': 'en_US:en', 22:00:40 INFO - 'LOGNAME': 'cltbld', 22:00:40 INFO - 'MAIL': '/var/mail/cltbld', 22:00:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:40 INFO - 'MOZ_NO_REMOTE': '1', 22:00:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:40 INFO - 'NO_EM_RESTART': '1', 22:00:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:00:40 INFO - 'PWD': '/builds/slave/test-pgo', 22:00:40 INFO - 'SHELL': '/bin/bash', 22:00:40 INFO - 'SHLVL': '1', 22:00:40 INFO - 'TERM': 'linux', 22:00:40 INFO - 'TMOUT': '86400', 22:00:40 INFO - 'USER': 'cltbld', 22:00:40 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', 22:00:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:40 INFO - '_': '/tools/buildbot/bin/python'} 22:00:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:40 INFO - Downloading/unpacking psutil>=0.7.1 22:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:42 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 22:00:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:00:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:00:42 INFO - Installing collected packages: psutil 22:00:42 INFO - Running setup.py install for psutil 22:00:42 INFO - building 'psutil._psutil_linux' extension 22:00:42 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 22:00:42 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 22:00:42 INFO - building 'psutil._psutil_posix' extension 22:00:42 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 22:00:42 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 22:00:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:00:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:00:43 INFO - Successfully installed psutil 22:00:43 INFO - Cleaning up... 22:00:43 INFO - Return code: 0 22:00:43 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 22:00: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')]} 22:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00: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')]} 22:00:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:43 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6a57ed3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b49480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8660>, '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 0x2bd9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd9a10>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', '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 22:00:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 22:00:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 22:00:43 INFO - Using env: {'DISPLAY': ':0', 22:00:43 INFO - 'HOME': '/home/cltbld', 22:00:43 INFO - 'LANG': 'en_US.UTF-8', 22:00:43 INFO - 'LANGUAGE': 'en_US:en', 22:00:43 INFO - 'LOGNAME': 'cltbld', 22:00:43 INFO - 'MAIL': '/var/mail/cltbld', 22:00:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:43 INFO - 'MOZ_NO_REMOTE': '1', 22:00:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:43 INFO - 'NO_EM_RESTART': '1', 22:00:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:00:43 INFO - 'PWD': '/builds/slave/test-pgo', 22:00:43 INFO - 'SHELL': '/bin/bash', 22:00:43 INFO - 'SHLVL': '1', 22:00:43 INFO - 'TERM': 'linux', 22:00:43 INFO - 'TMOUT': '86400', 22:00:43 INFO - 'USER': 'cltbld', 22:00:43 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', 22:00:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:43 INFO - '_': '/tools/buildbot/bin/python'} 22:00:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:43 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:00:43 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 22:00:43 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 22:00:43 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 22:00:43 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 22:00:43 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 22:00:43 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 22:00:45 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:00:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:00:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:00:45 INFO - Installing collected packages: mozsystemmonitor 22:00:45 INFO - Running setup.py install for mozsystemmonitor 22:00:45 INFO - Successfully installed mozsystemmonitor 22:00:45 INFO - Cleaning up... 22:00:45 INFO - Return code: 0 22:00:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 22:00:45 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')]} 22:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:45 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')]} 22:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6a57ed3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b49480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8660>, '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 0x2bd9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd9a10>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', '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 22:00:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 22:00:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:00:45 INFO - Using env: {'DISPLAY': ':0', 22:00:45 INFO - 'HOME': '/home/cltbld', 22:00:45 INFO - 'LANG': 'en_US.UTF-8', 22:00:45 INFO - 'LANGUAGE': 'en_US:en', 22:00:45 INFO - 'LOGNAME': 'cltbld', 22:00:45 INFO - 'MAIL': '/var/mail/cltbld', 22:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:45 INFO - 'MOZ_NO_REMOTE': '1', 22:00:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:45 INFO - 'NO_EM_RESTART': '1', 22:00:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:00:45 INFO - 'PWD': '/builds/slave/test-pgo', 22:00:45 INFO - 'SHELL': '/bin/bash', 22:00:45 INFO - 'SHLVL': '1', 22:00:45 INFO - 'TERM': 'linux', 22:00:45 INFO - 'TMOUT': '86400', 22:00:45 INFO - 'USER': 'cltbld', 22:00:45 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', 22:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:45 INFO - '_': '/tools/buildbot/bin/python'} 22:00:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:45 INFO - Downloading/unpacking blobuploader==1.2.4 22:00:45 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 22:00:45 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 22:00:45 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 22:00:45 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 22:00:45 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 22:00:45 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 22:00:47 INFO - Downloading blobuploader-1.2.4.tar.gz 22:00:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:00:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:00:47 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 22:00:47 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 22:00:47 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 22:00:47 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 22:00:47 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 22:00:47 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 22:00:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 22:00:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:00:47 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 22:00:47 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 22:00:47 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 22:00:47 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 22:00:47 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 22:00:47 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 22:00:47 INFO - Downloading docopt-0.6.1.tar.gz 22:00:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 22:00:48 INFO - Installing collected packages: blobuploader, requests, docopt 22:00:48 INFO - Running setup.py install for blobuploader 22:00:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:00:48 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 22:00:48 INFO - Running setup.py install for requests 22:00:48 INFO - Running setup.py install for docopt 22:00:48 INFO - Successfully installed blobuploader requests docopt 22:00:48 INFO - Cleaning up... 22:00:48 INFO - Return code: 0 22:00:48 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:00:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6a57ed3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b49480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8660>, '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 0x2bd9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd9a10>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', '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 22:00:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 22:00:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:00:48 INFO - Using env: {'DISPLAY': ':0', 22:00:48 INFO - 'HOME': '/home/cltbld', 22:00:48 INFO - 'LANG': 'en_US.UTF-8', 22:00:48 INFO - 'LANGUAGE': 'en_US:en', 22:00:48 INFO - 'LOGNAME': 'cltbld', 22:00:48 INFO - 'MAIL': '/var/mail/cltbld', 22:00:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:48 INFO - 'MOZ_NO_REMOTE': '1', 22:00:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:48 INFO - 'NO_EM_RESTART': '1', 22:00:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:00:48 INFO - 'PWD': '/builds/slave/test-pgo', 22:00:48 INFO - 'SHELL': '/bin/bash', 22:00:48 INFO - 'SHLVL': '1', 22:00:48 INFO - 'TERM': 'linux', 22:00:48 INFO - 'TMOUT': '86400', 22:00:48 INFO - 'USER': 'cltbld', 22:00:48 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', 22:00:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:48 INFO - '_': '/tools/buildbot/bin/python'} 22:00:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:00:48 INFO - Running setup.py (path:/tmp/pip-HWxMcw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:00:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:00:48 INFO - Running setup.py (path:/tmp/pip-PV1B88-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:00:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:00:48 INFO - Running setup.py (path:/tmp/pip-3l41eD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:00:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:00:49 INFO - Running setup.py (path:/tmp/pip-WSaBZ4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:00:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:00:49 INFO - Running setup.py (path:/tmp/pip-YN6fnm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:00:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:00:49 INFO - Running setup.py (path:/tmp/pip-1cN9Rk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:00:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:00:49 INFO - Running setup.py (path:/tmp/pip-AXlirC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:00:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:00:49 INFO - Running setup.py (path:/tmp/pip-cH6lPT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:00:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:00:49 INFO - Running setup.py (path:/tmp/pip-kr0LYb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:00:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:00:49 INFO - Running setup.py (path:/tmp/pip-I7DlkY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:00:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:00:49 INFO - Running setup.py (path:/tmp/pip-VJTVM5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:00:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:00:49 INFO - Running setup.py (path:/tmp/pip-u2p4_b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:00:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:00:49 INFO - Running setup.py (path:/tmp/pip-GS4d93-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:00:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:00:49 INFO - Running setup.py (path:/tmp/pip-Dyon3D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:00:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:00:49 INFO - Running setup.py (path:/tmp/pip-6grvil-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:00:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:00:49 INFO - Running setup.py (path:/tmp/pip-sG1J96-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:00:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:00:50 INFO - Running setup.py (path:/tmp/pip-9e9w5A-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:00:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:00:50 INFO - Running setup.py install for manifestparser 22:00:50 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 22:00:50 INFO - Running setup.py install for mozcrash 22:00:50 INFO - Running setup.py install for mozdebug 22:00:50 INFO - Running setup.py install for mozdevice 22:00:50 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 22:00:50 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 22:00:50 INFO - Running setup.py install for mozfile 22:00:50 INFO - Running setup.py install for mozhttpd 22:00:50 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 22:00:50 INFO - Running setup.py install for mozinfo 22:00:50 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 22:00:50 INFO - Running setup.py install for mozInstall 22:00:50 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 22:00:50 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 22:00:50 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 22:00:50 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 22:00:50 INFO - Running setup.py install for mozleak 22:00:50 INFO - Running setup.py install for mozlog 22:00:51 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 22:00:51 INFO - Running setup.py install for moznetwork 22:00:51 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 22:00:51 INFO - Running setup.py install for mozprocess 22:00:51 INFO - Running setup.py install for mozprofile 22:00:51 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 22:00:51 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 22:00:51 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 22:00:51 INFO - Running setup.py install for mozrunner 22:00:51 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 22:00:51 INFO - Running setup.py install for mozscreenshot 22:00:51 INFO - Running setup.py install for moztest 22:00:51 INFO - Running setup.py install for mozversion 22:00:51 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 22:00:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:00:51 INFO - Cleaning up... 22:00:51 INFO - Return code: 0 22:00:51 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:00: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')]} 22:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00: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')]} 22:00:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:51 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6a57ed3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b49480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8660>, '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 0x2bd9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd9a10>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', '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 22:00:51 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 22:00:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:00:51 INFO - Using env: {'DISPLAY': ':0', 22:00:51 INFO - 'HOME': '/home/cltbld', 22:00:51 INFO - 'LANG': 'en_US.UTF-8', 22:00:51 INFO - 'LANGUAGE': 'en_US:en', 22:00:51 INFO - 'LOGNAME': 'cltbld', 22:00:51 INFO - 'MAIL': '/var/mail/cltbld', 22:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:51 INFO - 'MOZ_NO_REMOTE': '1', 22:00:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:51 INFO - 'NO_EM_RESTART': '1', 22:00:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:00:51 INFO - 'PWD': '/builds/slave/test-pgo', 22:00:51 INFO - 'SHELL': '/bin/bash', 22:00:51 INFO - 'SHLVL': '1', 22:00:51 INFO - 'TERM': 'linux', 22:00:51 INFO - 'TMOUT': '86400', 22:00:51 INFO - 'USER': 'cltbld', 22:00:51 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', 22:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:51 INFO - '_': '/tools/buildbot/bin/python'} 22:00:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:00:52 INFO - Running setup.py (path:/tmp/pip-DdAqlS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:00:52 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)) 22:00:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:00:52 INFO - Running setup.py (path:/tmp/pip-bJiLQs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:00:52 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)) 22:00:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:00:52 INFO - Running setup.py (path:/tmp/pip-1sjOq9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:00:52 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)) 22:00:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:00:52 INFO - Running setup.py (path:/tmp/pip-v78cDg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:00:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 22:00:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:00:52 INFO - Running setup.py (path:/tmp/pip-7d7ifB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:00:52 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)) 22:00:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:00:52 INFO - Running setup.py (path:/tmp/pip-BWHAdq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:00:52 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)) 22:00:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:00:52 INFO - Running setup.py (path:/tmp/pip-6f3jpF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:00:52 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)) 22:00:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:00:52 INFO - Running setup.py (path:/tmp/pip-332Y1h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:00:52 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)) 22:00:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:00:52 INFO - Running setup.py (path:/tmp/pip-_T9sF3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:00:52 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)) 22:00:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:00:52 INFO - Running setup.py (path:/tmp/pip-VEOHos-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:00:52 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)) 22:00:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:00:52 INFO - Running setup.py (path:/tmp/pip-3j370v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:00:52 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)) 22:00:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:00:52 INFO - Running setup.py (path:/tmp/pip-G1WiCW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:00:52 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)) 22:00:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:00:52 INFO - Running setup.py (path:/tmp/pip-1GUZIA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:00:52 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)) 22:00:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:00:52 INFO - Running setup.py (path:/tmp/pip-u70cHt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:00:53 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)) 22:00:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:00:53 INFO - Running setup.py (path:/tmp/pip-qQJoJ_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:00:53 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)) 22:00:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:00:53 INFO - Running setup.py (path:/tmp/pip-7PsE5q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:00:53 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)) 22:00:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:00:53 INFO - Running setup.py (path:/tmp/pip-Ju2hR8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:00:53 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)) 22:00:53 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)) 22:00:53 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)) 22:00:53 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)) 22:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 22:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 22:00:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 22:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:55 INFO - Downloading blessings-1.6.tar.gz 22:00:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 22:00:55 INFO - Installing collected packages: blessings 22:00:55 INFO - Running setup.py install for blessings 22:00:55 INFO - Successfully installed blessings 22:00:55 INFO - Cleaning up... 22:00:55 INFO - Return code: 0 22:00:55 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 22:00:55 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:00:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:00:55 INFO - Reading from file tmpfile_stdout 22:00:55 INFO - Current package versions: 22:00:55 INFO - argparse == 1.2.1 22:00:55 INFO - blessings == 1.6 22:00:55 INFO - blobuploader == 1.2.4 22:00:55 INFO - docopt == 0.6.1 22:00:55 INFO - manifestparser == 1.1 22:00:55 INFO - mozInstall == 1.12 22:00:55 INFO - mozcrash == 0.16 22:00:55 INFO - mozdebug == 0.1 22:00:55 INFO - mozdevice == 0.48 22:00:55 INFO - mozfile == 1.2 22:00:55 INFO - mozhttpd == 0.7 22:00:55 INFO - mozinfo == 0.9 22:00:55 INFO - mozleak == 0.1 22:00:55 INFO - mozlog == 3.1 22:00:55 INFO - moznetwork == 0.27 22:00:55 INFO - mozprocess == 0.22 22:00:55 INFO - mozprofile == 0.28 22:00:55 INFO - mozrunner == 6.11 22:00:55 INFO - mozscreenshot == 0.1 22:00:55 INFO - mozsystemmonitor == 0.0 22:00:55 INFO - moztest == 0.7 22:00:55 INFO - mozversion == 1.4 22:00:55 INFO - psutil == 3.1.1 22:00:55 INFO - requests == 1.2.3 22:00:55 INFO - wsgiref == 0.1.2 22:00:55 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:55 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6a57ed3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b49480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8660>, '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 0x2bd9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd9a10>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', '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 22:00:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 22:00:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:00:55 INFO - Using env: {'DISPLAY': ':0', 22:00:55 INFO - 'HOME': '/home/cltbld', 22:00:55 INFO - 'LANG': 'en_US.UTF-8', 22:00:55 INFO - 'LANGUAGE': 'en_US:en', 22:00:55 INFO - 'LOGNAME': 'cltbld', 22:00:55 INFO - 'MAIL': '/var/mail/cltbld', 22:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:55 INFO - 'MOZ_NO_REMOTE': '1', 22:00:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:55 INFO - 'NO_EM_RESTART': '1', 22:00:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:00:55 INFO - 'PWD': '/builds/slave/test-pgo', 22:00:55 INFO - 'SHELL': '/bin/bash', 22:00:55 INFO - 'SHLVL': '1', 22:00:55 INFO - 'TERM': 'linux', 22:00:55 INFO - 'TMOUT': '86400', 22:00:55 INFO - 'USER': 'cltbld', 22:00:55 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', 22:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:55 INFO - '_': '/tools/buildbot/bin/python'} 22:00:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:55 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)) 22:00:55 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)) 22:00:55 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)) 22:00:55 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)) 22:00:55 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)) 22:00:55 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)) 22:00:55 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)) 22:00:55 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)) 22:00:55 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)) 22:00:55 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)) 22:00:55 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)) 22:00:55 INFO - Cleaning up... 22:00:55 INFO - Return code: 0 22:00:55 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 22:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:55 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6a57ed3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b49480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8660>, '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 0x2bd9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd9a10>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', '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 22:00:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 22:00:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:00:55 INFO - Using env: {'DISPLAY': ':0', 22:00:55 INFO - 'HOME': '/home/cltbld', 22:00:55 INFO - 'LANG': 'en_US.UTF-8', 22:00:55 INFO - 'LANGUAGE': 'en_US:en', 22:00:55 INFO - 'LOGNAME': 'cltbld', 22:00:55 INFO - 'MAIL': '/var/mail/cltbld', 22:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:55 INFO - 'MOZ_NO_REMOTE': '1', 22:00:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:55 INFO - 'NO_EM_RESTART': '1', 22:00:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:00:55 INFO - 'PWD': '/builds/slave/test-pgo', 22:00:55 INFO - 'SHELL': '/bin/bash', 22:00:55 INFO - 'SHLVL': '1', 22:00:55 INFO - 'TERM': 'linux', 22:00:55 INFO - 'TMOUT': '86400', 22:00:55 INFO - 'USER': 'cltbld', 22:00:55 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', 22:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:55 INFO - '_': '/tools/buildbot/bin/python'} 22:00:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:55 INFO - Downloading/unpacking jsonschema 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:00:57 INFO - Downloading/unpacking functools32 (from jsonschema) 22:00:57 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 22:00:57 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 22:00:57 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 22:00:57 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 22:00:57 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 22:00:57 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 22:00:57 INFO - Downloading functools32-3.2.3-2.tar.gz 22:00:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 22:00:57 INFO - warning: no files found matching '*.txt' 22:00:57 INFO - no previously-included directories found matching 'build' 22:00:57 INFO - no previously-included directories found matching 'dist' 22:00:57 INFO - no previously-included directories found matching '.git*' 22:00:57 INFO - Installing collected packages: jsonschema, functools32 22:00:58 INFO - Running setup.py install for functools32 22:00:58 INFO - warning: no files found matching '*.txt' 22:00:58 INFO - no previously-included directories found matching 'build' 22:00:58 INFO - no previously-included directories found matching 'dist' 22:00:58 INFO - no previously-included directories found matching '.git*' 22:00:58 INFO - Successfully installed jsonschema functools32 22:00:58 INFO - Cleaning up... 22:00:58 INFO - Return code: 0 22:00:58 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 22:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:58 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6a57ed3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b49480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8660>, '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 0x2bd9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd9a10>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', '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 22:00:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 22:00:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:00:58 INFO - Using env: {'DISPLAY': ':0', 22:00:58 INFO - 'HOME': '/home/cltbld', 22:00:58 INFO - 'LANG': 'en_US.UTF-8', 22:00:58 INFO - 'LANGUAGE': 'en_US:en', 22:00:58 INFO - 'LOGNAME': 'cltbld', 22:00:58 INFO - 'MAIL': '/var/mail/cltbld', 22:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:58 INFO - 'MOZ_NO_REMOTE': '1', 22:00:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:58 INFO - 'NO_EM_RESTART': '1', 22:00:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:00:58 INFO - 'PWD': '/builds/slave/test-pgo', 22:00:58 INFO - 'SHELL': '/bin/bash', 22:00:58 INFO - 'SHLVL': '1', 22:00:58 INFO - 'TERM': 'linux', 22:00:58 INFO - 'TMOUT': '86400', 22:00:58 INFO - 'USER': 'cltbld', 22:00:58 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', 22:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:58 INFO - '_': '/tools/buildbot/bin/python'} 22:00:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:58 INFO - Downloading/unpacking flake8 22:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:00 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:01:00 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:00 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:01:00 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 22:01:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:01:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:01:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:01:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:01:00 INFO - no previously-included directories found matching 'docs/_build' 22:01:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:01:00 INFO - Running setup.py install for pep8 22:01:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:01:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:01:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:01:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:01:00 INFO - no previously-included directories found matching 'docs/_build' 22:01:01 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 22:01:01 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:01:01 INFO - Cleaning up... 22:01:01 INFO - Return code: 0 22:01:01 INFO - Running post-action listener: _resource_record_post_action 22:01:01 INFO - Running post-action listener: _start_resource_monitoring 22:01:01 INFO - Starting resource monitoring. 22:01:01 INFO - ##### 22:01:01 INFO - ##### Running install step. 22:01:01 INFO - ##### 22:01:01 INFO - Running pre-action listener: _resource_record_pre_action 22:01:01 INFO - Running main action method: install 22:01:01 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:01:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:01:01 INFO - Reading from file tmpfile_stdout 22:01:01 INFO - Detecting whether we're running mozinstall >=1.0... 22:01:01 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 22:01:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 22:01:01 INFO - Reading from file tmpfile_stdout 22:01:01 INFO - Output received: 22:01:01 INFO - Usage: mozinstall [options] installer 22:01:01 INFO - Options: 22:01:01 INFO - -h, --help show this help message and exit 22:01:01 INFO - -d DEST, --destination=DEST 22:01:01 INFO - Directory to install application into. [default: 22:01:01 INFO - "/builds/slave/test-pgo"] 22:01:01 INFO - --app=APP Application being installed. [default: firefox] 22:01:01 INFO - mkdir: /builds/slave/test-pgo/build/application 22:01:01 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'] 22:01:01 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 22:01:17 INFO - Reading from file tmpfile_stdout 22:01:17 INFO - Output received: 22:01:17 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 22:01:17 INFO - Running post-action listener: _resource_record_post_action 22:01:17 INFO - ##### 22:01:17 INFO - ##### Running run-tests step. 22:01:17 INFO - ##### 22:01:17 INFO - Running pre-action listener: _resource_record_pre_action 22:01:17 INFO - Running main action method: run_tests 22:01:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 22:01:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 22:01:17 INFO - Python 2.7.3 22:01:17 INFO - Return code: 0 22:01:17 INFO - grabbing minidump binary from tooltool 22:01:17 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')]} 22:01:17 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 0x2bd8660>, '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 0x2bd9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd9a10>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 22:01:17 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 22:01:17 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 22:01:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:01:17 INFO - Return code: 0 22:01:17 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 22:01:17 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 22:01:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 22:01:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 22:01:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 22:01:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 22:01:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 22:01:18 INFO - Return code: 0 22:01:18 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/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 22:01:18 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/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-007 --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 22:01:18 INFO - Using env: {'DISPLAY': ':0', 22:01:18 INFO - 'HOME': '/home/cltbld', 22:01:18 INFO - 'LANG': 'en_US.UTF-8', 22:01:18 INFO - 'LANGUAGE': 'en_US:en', 22:01:18 INFO - 'LOGNAME': 'cltbld', 22:01:18 INFO - 'MAIL': '/var/mail/cltbld', 22:01:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:01:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 22:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:18 INFO - 'MOZ_NO_REMOTE': '1', 22:01:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:01:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:18 INFO - 'NO_EM_RESTART': '1', 22:01:18 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:01:18 INFO - 'PWD': '/builds/slave/test-pgo', 22:01:18 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 22:01:18 INFO - 'SHELL': '/bin/bash', 22:01:18 INFO - 'SHLVL': '1', 22:01:18 INFO - 'TERM': 'linux', 22:01:18 INFO - 'TMOUT': '86400', 22:01:18 INFO - 'USER': 'cltbld', 22:01:18 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085', 22:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:18 INFO - '_': '/tools/buildbot/bin/python'} 22:01:18 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/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 22:01:18 INFO - mozversion application_buildid: 20160122193307 22:01:18 INFO - mozversion application_changeset: c49f59a5b8288c9cbb782b8beedb9b12b7171c67 22:01:18 INFO - mozversion application_display_name: Nightly 22:01:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:01:18 INFO - mozversion application_name: Firefox 22:01:18 INFO - mozversion application_remotingname: firefox 22:01:18 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:01:18 INFO - mozversion application_vendor: Mozilla 22:01:18 INFO - mozversion application_version: 46.0a1 22:01:18 INFO - mozversion platform_buildid: 20160122193307 22:01:18 INFO - mozversion platform_changeset: c49f59a5b8288c9cbb782b8beedb9b12b7171c67 22:01:18 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:01:18 INFO - mozversion platform_version: 46.0a1 22:01:18 INFO - using testdate: 1453528878 22:01:18 INFO - actual date: 1453528878 22:01:18 INFO - starting webserver on 'localhost:48553' 22:01:18 INFO - SUITE-START | Running 3 tests 22:01:18 INFO - TEST-START | dromaeo_css 22:01:18 INFO - operating with platform_type : linux_ 22:01:18 INFO - Initialising browser for dromaeo_css test... 22:01:18 INFO - TEST-INFO | started process 10849 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpboiiNt/profile http://localhost:48553/getInfo.html) 22:01:23 INFO - PROCESS | 10849 | __metrics Screen width/height:1600/1200 22:01:23 INFO - PROCESS | 10849 | colorDepth:24 22:01:23 INFO - PROCESS | 10849 | Browser inner width/height: 1024/697 22:01:23 INFO - PROCESS | 10849 | __metrics 22:01:23 INFO - PROCESS | 10849 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 22:01:23 INFO - PROCESS | 10849 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:01:24 INFO - TEST-INFO | 10849: exit 0 22:01:24 INFO - Browser initialized. 22:01:24 INFO - Running cycle 1/1 for dromaeo_css test... 22:01:24 INFO - TEST-INFO | started process 10948 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpboiiNt/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:01:36 INFO - PROCESS | 10948 | RSS: Main: 147542016 22:01:36 INFO - PROCESS | 10948 | 22:02:27 INFO - PROCESS | 10948 | 1453528947943 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] 22:04:36 INFO - PROCESS | 10948 | Cycle 1(1): loaded http://localhost:48553/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48553/tests/dromaeo/cssquery-ext.html) 22:04:37 INFO - PROCESS | 10948 | RSS: Main: 268226560 22:04:37 INFO - PROCESS | 10948 | 22:07:39 INFO - PROCESS | 10948 | Cycle 1(1): loaded http://localhost:48553/tests/dromaeo/cssquery-ext.html (next: http://localhost:48553/tests/dromaeo/cssquery-jquery.html) 22:07:40 INFO - PROCESS | 10948 | RSS: Main: 236994560 22:07:40 INFO - PROCESS | 10948 | 22:10:42 INFO - PROCESS | 10948 | Cycle 1(1): loaded http://localhost:48553/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48553/tests/dromaeo/cssquery-mootools.html) 22:10:42 INFO - PROCESS | 10948 | RSS: Main: 221286400 22:10:42 INFO - PROCESS | 10948 | 22:13:44 INFO - PROCESS | 10948 | Cycle 1(1): loaded http://localhost:48553/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48553/tests/dromaeo/cssquery-prototype.html) 22:13:45 INFO - PROCESS | 10948 | RSS: Main: 210100224 22:13:45 INFO - PROCESS | 10948 | 22:16:47 INFO - PROCESS | 10948 | Cycle 1(1): loaded http://localhost:48553/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48553/tests/dromaeo/cssquery-yui.html) 22:16:48 INFO - PROCESS | 10948 | RSS: Main: 215261184 22:16:48 INFO - PROCESS | 10948 | 22:19:51 INFO - PROCESS | 10948 | Cycle 1(1): loaded http://localhost:48553/tests/dromaeo/cssquery-yui.html (next: http://localhost:48553/tests/dromaeo/cssquery-dojo.html) 22:19:51 INFO - PROCESS | 10948 | RSS: Main: 372170752 22:19:51 INFO - PROCESS | 10948 | 22:19:51 INFO - PROCESS | 10948 | __start_tp_report 22:19:51 INFO - PROCESS | 10948 | _x_x_mozilla_page_load 22:19:51 INFO - PROCESS | 10948 | _x_x_mozilla_page_load_details 22:19:51 INFO - PROCESS | 10948 | |i|pagename|runs| 22:19:51 INFO - PROCESS | 10948 | |0;dojo.html;3085.8919937802175;3150.9054728358155;3197.5683282756836;3199.8560064797066;3223.210313473199;5114.8465546033585;5119.590432765374;5137.691738495688;5152.74236288186;5187.714675692835;5095.3376061112;5144.794208231676;5213.817516386927;5217.113090774568;5230.555402790767;4762.309465127554;4764.7379394133395;4780.37855078841;4789.425268967729;4888.437829649582;4802.711837289757;4829.106615276168;4838.298446725223;4853.053654537367;4890.462049174588;4653.395058642389;4676.111538807616;4715.1512727709;4774.140654682155;4820.035992801454;4771;4772.856814295576;4785.784639691222;4789.425268967711;4809.7114173149375;4757.00102978375;4760.721068278782;4761.8095276188915;4767.690100143514;4786.473487916326;5017.974910125426;5029.7736601853;5061.848144555669;5072.0616685912855;5091.618128640367;5526.696031718254;5529.778808847641;5530.695811730353;5533;5554.555635549146;5573.832785016456;5580.776768929279;5581.8604534886545;5583.916241256385;5585.385607583163;5100.522888173559;5171.250168725552;5200.063988482034;5207.921881171747;5254.369475662945;5571.0807716727095;5582.413846546055;5583.637063590853;5590.329160500766;5593.776248949996;5397.568194544427;5398.595105367034;5398.676079435247;5405.378381486179;5447.700376479332;5480.890382192334;5502.669839809624;5572.080606699849;5573.275474188411;5591.6365436246515;5461.15352120422;5465.180222966586;5467.835964921059;5486.561075113981;5512.834614961557;5445.210444485528;5464.699441530674;5474.644148130359;5514.889702206014;5517.337919449692;4255.361695745601;4256.914861702749;4260.765657888784;4350.825966961349;4364.258076127066;3891.5135608048995;3896.844126234919;3901.395283731026;3931.017245688578;3938.1926705025403;5165.431802501722;5169.069567477815;5172.637915345889;5177.7152256625495;5178.171492561172;5479.698616576126;5491;5546.667199968015;5560.582956278296;5561.888762224733;5172.948270517247;5201.401838788512;5214.165733482625;5215.269862219292;5229.47705229474;4784;4791.712497250176;4795.736234507136;4802.399700037496;4822.855314340575;5689.146628005832;5780.393058728857;5787.71061446926;5793.536517078708;5866.208061911672;5581.665100094007;5582.995060889079;5598.636088654224;5628.099504079328;5653;5763.423657634203;5766.711664416846;5767.307923049261;5769.53843692512;5782.508486778671;5429.864253393539;5471.316085489314;5475;5522.972385138049;5529.612927095065;5530.806421775219;5562.972185139049;5572.665640061609;5584.274044374205;5592;5447.0467668157125;5449.972750136224;5453.072977593739;5466.070767969534;5511;5294.576433885203;5374.355077390738;5392.191171324333;5420.810271640474;5435.374931882884;5356.57147428197;5360.865978350573;5369.248305237192;5375.623706340519;5416.593755468277;5455.290812194389;5456.536194423518;5460;5463.453654634504;5463.48096930791;5321.600879933943;5353.197020446964;5355.062863998863;5360.3299587551555;5378.408375078817 22:19:51 INFO - PROCESS | 10948 | |1;ext.html;506.43785398208007;544.8120398462529;547.7069767674294;550.2159422822477;560.6636018388966;27397.58903616445;37360;38107.23785524288;38305.80847095764;38318.42522362167;41997.37003944943;42497;42499.57500424995;42525;42538.936526586775;8479.745607631765;8730.301575873946;8734.912650873504;8750.512412889824;8752.168543988311;8462.323014158883;8546.914530854705;8572.442791218551;8581.270591999688;8584.613692383826;8564.357673174505;8579;8656.783580410509;8674.392792504526;8676.8698469523;8541.957290213571;8559.572021398904;8577.356698247593;8588.312934965188;8601.139886011411;8346.749597512084;8438.240558349778;8455.57722113898;8467.983841938945;8474.110218427038;36735.63264367349;37134;37183.44224836629;37260;37316.44025339622;3120.2667373167287;3184.9044528664176;3259.1526203187104;3515.6210997174107;3535.186907011376;29143.70856291431;29565;29721.25696857574;30160.396792064035;30532.236694082603;3521.753477256593;3540.5751309842726;3545.94681079784;3578.7494875358734;3664.633536646341;258691;260455;260773;261095;262743;49299.75350123226;49894.75052624786;53361.466385336425;53759.19361209585;56018.43981560132;50430.49569504258;50442.495575044515;50665.24002139971;50716.23925641119;50802.98394032173;12774.872251277367;12880.549180778627;12881.162724422882;12900.80648790269;12929.935350323376;8981.236594889508;9100.362974591848;9142.862857057149;9177.90822091784;9287.210587100037;17539.47381578529;17710.734338984927;17731.645367092842;17747.378841740476;17789.911050444665;7321.926780732125;7384;7456.701731930662;7503.62481875904;7534.472586918972;1006.5830331635822;1057.4501259345097;1065.63235683689;1083.4366129612558;1085.0343194556851;27935.88388012726;28111;28116;28356.291092722433;28399.71600284012;1012.2003617142523;1038.2420832791954;1051.4059556350637;1053.5153829238636;1102.0522350778324;14654.302359277786;14874.92562537202;14916.254187290591;14962.92518537422;15324.023630784002;325706;335609;335682;337148;337243;51004;51395.229071563954;51435.48564514382;51535.742321288155;51541;1312.1143228320846;1316.374722007039;1326.157889740022;1332.68015676237;1339.2634051271762;27628;28432.85783571069;28698.42603147967;28726.712732872405;28835;42755.786221068694;42994.35508467438;43105;43267.56732432761;43356.78321608372;305957;306741;308182;308551;309983;29150.7084929148;30014.84992575023;30137.547936780527;30173.849130754206;30255;40460;40756.1848763029;41020.17959640731;41500.79249603733;41569;34476.31047379089;34785.8260708705;34895.47656785201;34929.30141397208;35653.465198021535;35086.82456587701;35352.292954140255;35438.46842297419;35638;35741.642583574874;34695;35087.298254034264;35230.823845881634;35579.82210088933;35585;27149.72850271551;29181.854090729412;29801;31934.201644959063;32833;22685.319440416813;22985.384871676368;23271.301860944197;24171.274861754177;24432.755672443047 22:19:51 INFO - PROCESS | 10948 | |2;jquery.html;347.6905554056888;367.5130452150899;370.4702275745684;372.0624027450824;372.33352299384086;12662.746745065093;16002;17016.234269457887;17987.64024719505;17994.730079048826;14862.851371486282;15056.021358611682;15183.089014659128;15460.458883939064;15606.141662208574;3980.661643760284;4135.77253251071;4142.565030671766;5227.3465816772905;5424.430434804348;5657.462541058613;5830.96200888623;5916.674582897939;5928.6739229342365;5953.2856057272975;5886.705664716747;5987.640741555499;5992.250968628921;5996.16053752475;5996.790112346069;5774.422557744234;5944.7027648617395;5959.046552551593;5991.700414979234;6009.429104235091;5134.589232861362;5334.59990500714;5335.1997200419855;5337.5196232338885;5339.332583427072;19815.40553783389;19822.50443738918;19990.300339488193;19990.400287991382;20160;638.3967151042267;638.5147288061061;2108.5677436125557;2395.6105458833836;2424.2969538833718;12457.1902826316;12585.496580136783;12752.617421477371;12890.54883079097;12949;1762.480068379831;1784.1703607822426;1802.7115661494097;1814.845738112262;1827.9543011424687;55265;55680.44319556793;55954.40455954413;56765.71617141929;57346;9736.318457708034;9882.901170988198;9962.153216976638;9964.451955142536;10015.599376024877;13071.15037522558;13113.606591802261;13130.146540474838;13139.67150821218;13153;7082.433405327562;7093.538919970289;7146.821329466701;7150.070490836158;7267.055282813306;6332.353588067136;6393.2328120625825;6417.454516366161;6423.036544518266;6630.734770609218;5370.335196089225;5649.322081350264;5659.660420374791;5665.575081868877;5859.296884373903;1728.4900954218535;1764.6647137043922;1775.697203202043;1783.2599471219557;1784.857211423083;923.5197697197419;927.1284992107533;951.2722767083187;997.7356000659686;1007.8941711120374;12229.93885030569;15200;15271.770923436157;16252.431164909407;16561.83438165627;885.1856292211181;897.3140004754043;932.4172392254841;932.9253659707207;991.4101109839634;9351.298652600946;9506.47714375716;9507.524623768923;9511.620814981788;9852.310338276251;467215.29492935166;476141;487300;497226;498756;44853.77573112114;62702.37297626965;66064.33935660678;69881.3011869885;71267.28732712606;1109.6005438042298;1113.9220254582262;1136.931784092957;1170.449888552379;1183.7040739815047;14591.489297874523;14628.26858657084;14807.407703692163;15170.696586067996;15851.207439627742;31636.892708756004;32199;32367;32507.512387313735;33696.989090327326;35393.82303088468;36198;36728.816355919116;36782.63217367792;37037.9998700172;7133.429325653831;7477.88783168242;7600.961995189989;7616.20029896875;7635.847283054197;10425.478726063515;11104;11119;11343.829842552203;11620.128490363426;7063.046488723957;7350.196818066344;7415.145681296902;7495.025646665898;7641.847163056596;7105.75129870452;7182.569045857265;7356.264373562601;7593.468457207942;7655;7253.782386528413;7278.708851646086;7626.733064342721;7724.497907635986;7838.451308408585;9814.116729494379;10106.747331316776;10216.284860059724;10378.481075946022;10405.635802746867;7151.070360853056;7195.244499327495;7238.0952380952385;7310.122785265589;7329.52358096722 22:19:51 INFO - PROCESS | 10948 | |3;mootools.html;1333.6532501549593;1430.6995530938502;1440.3806363263795;1601.4715144002473;1793.3902473159133;7138.286171382865;7693.038370203724;7857.174996625344;7876.488028278158;7880.881786773203;7770.57261850598;7926.801829954254;7932.206779322065;7933.92066079339;7943.523388596688;2042.387283814857;2094.413564202026;2239.74242962059;2303.781140791622;2328.3364241191266;2261.457250259934;2266.7959883610556;2269.307861102363;2270.3075561953597;2270.3529494094187;2176.4994051368194;2265.762094980028;2266.331432227497;2269.6822444857735;2271.8068964137988;2255.8872056397277;2271.6251818449937;2277.4420267034598;2310.2491690200586;2312.3987763181526;2304.9078036878504;2322.500662357584;2338.988305058464;2339.1111377676375;2346.366481050124;8012.238837310446;8066.677332906676;8072.354211663052;8077.596120193967;8082.989626296713;4637.095766325568;4717.646176536773;4722.244440889442;4740;4757.857264282077;3090.0884239149505;3094.5667606535103;3115.3146307812244;3135.2475405902646;3226.483762597973;1032.4682788363998;1034.384541197986;1036.8133735927606;1079.7354648111184;1086.869575650919;10787.137029037658;10861.13110951122;10882.23824332297;10915.727106822314;10952.4523773811;4132.40080188371;4212.515560710496;4264.424302719155;4354.063876266618;4359.17175736609;8858.911410885938;9060.954695226614;9082.772930676654;9102.954485227532;9129.132732390412;4774.856754297377;4794.041191761592;4820.517948205222;4828.348172996671;4832.275158726219;4408.625266852354;4411.801468933906;4414.867553973386;4417.050334178167;4423.248047831877;3383.6278009418943;3435.484677298375;3521.190126270972;3625.7099432045375;3690.040589446763;1125.178619607691;1128.8758236593967;1141.1612464838338;1141.8629764428156;1145.587588468151;1275.3113318807739;1307.4770091963292;1328.4752522753652;1349.730053989206;1350.3248375812093;7313.195548489662;7417.703291868264;7527.134379546423;7553.773386798405;7555.320021198118;1093.8960798724108;1099.3239157917824;1112.4548971004149;1112.4826955465746;1134.4044376702298;5902.4097590240635;5943.70281485924;5981.760729570856;5990.460858522754;6020.4280593343565;9143.177114059767;9419.481928493999;9516.95241523788;9541.18899893503;9559.235261179088;9657.758556036015;9740.220782337396;9772.51137443125;9781.608735650494;9782.706518804447;7394.223606521237;7497.700091996368;7538.509996850187;7592.316691497792;7607.885881711668;7395.926040739524;7410.740624078131;7508.399328053633;7538.057742782153;7569.6593653284635;8644.178803013703;8756.124387561193;8870.73387798367;8955.41789783662;9002.729918102468;8615.784605384664;9003.369764116449;9022.819543609223;9233.492157931378;9242.907570924204;6982.476314276551;7080.504364694422;7086.326798954091;7088.113985751781;7090.581883623193;6729.158855143107;6929.549579277331;7257.49197556166;7449.255074492507;7451.478396512409;6997.8250543734775;7082.964585177041;7205.495615307088;7206.56760594366;7275.163356214103;6957.965210173917;6988.440924726111;7110.964445177741;7131.393831524379;7159.212486626571;7083.397911177608;7138.821529461805;7179;7181.569105853666;7182.245864184186;6641.402273795382;6643.70103345341;6657.435502656968;6690.83272918181;6706.966465167643;6695.899561506484;6698.263191049078;6743.089682892943;6744;6753.088333074972 22:19:51 INFO - PROCESS | 10948 | |4;prototype.html;353.7594435783667;355.0909671241623;356.09196548800554;357.8461261657486;361.5426485495847;5639.125935479997;5818.185454036438;5873.295204575452;5894.675792831398;5899.380565040673;5633.661980281176;5738.56960727946;5743.138529220609;5801.854953626162;5831.825045248639;4547;4547.24970379887;4574.908501829961;4585.770711464422;4586.3579098926175;4384.956150438503;4493.910121797562;4584.129015487063;4589.426321709786;4594.816207351702;4428.623566996809;4480.327950807372;4501.707389019703;4501.932471012921;4517.616002639763;4480.507144214133;4492.3710680504755;4493.7977790999485;4499.707519011254;4505.211587972124;4003.939940900889;4009.639132478091;4014.7791871447057;4019.9950012496874;4034.576369481221;5484.314460692413;5518.641288316247;5525.364583072957;5533.944660553383;5580.665160090408;5273.367195936474;5274.736263186825;5277.261183434322;5282.973585132088;5286.629935904488;6143.784967526115;6151.784687535959;6153.538484613627;6155.969220153916;6156.815295541141;4244.3209086546;4304.526502084768;4330;4351.369051487548;4482.9551704482865;6390.488760899117;6431.678416079224;6439.93560064398;6440.4203621673805;6475.449586785082;4515.412996310524;4523;4524.683272170916;4525.796339164745;4529.909401812011;4517.616002639812;4580.908381832344;4582.335561343587;4582.770861456914;4586.289125185602;5214.661047031931;5272.156454967187;5272.92090618641;5279.1289437243195;5281.102212623863;6199;6216.720247588869;6226.62640241587;6290.528210384239;6294.842628934313;1632.314427940268;1651.1909164509304;1652.6777278430832;1653.3469279634478;1654.3713388912136;1081.3403823667556;1099.9890001100046;1107.7064577886867;1110.2394859521253;1127.7406196574834;5881.941180588225;5884.6469211847425;5886.293644762656;5886.646801191942;5897.498712609477;6340.365963403623;6363.395477429637;6368.904466433008;6378.585391949508;6381.936180638134;5836;5838.065909454467;5857.7949771757785;5863.413658634103;5878.911816322759;3335.866565337408;3432.2105915639045;3433.5021421894185;3433.53647257622;3436.8281585920604;6662.233843108104;6689.464842812563;6710.261871194165;6713.1272934519175;6724.125863637696;6527.184101987252;6576.934230657728;6581.40767330933;6593.307702691244;6596.142501474874;6549.312322206195;6683.766068187688;6692.196936367667;6697.7655782047395;6707.4969377297875;6404.231492220777;6411.455026322815;6418.550701450854;6424.710888009958;6428.260750013808;6116.847078823065;6142.324344322032;6273.059041143682;6352.237731472068;6370.140031095742;6688.866222675617;6690.364415380531;6695.0626912231355;6705.597664140168;6721.462283017445;4411.22803509391;4438.755868427267;4441.644668426454;4441.71128876622;4445.222086134849;6287.088372185916;6449.193850768654;6450.580712253689;6454.741810327534;6456.451201647913;6290;6296.370362963667;6302.810915672537;6304.369563043659;6308.747650093945;6278.780242691484;6279.152314437492;6283.654399008098;6285.277193122849;6285.465735412541;6061.454469097803;6174.66039367839;6181.258249010148;6182.814515564541;6187.226596675416;6306.400891915261;6309.779157729641;6315.621062736251;6318.210223722035;6324.17785687876;6136.417040381157;6159.106929495288;6161.815145545641;6168.136460895388;6170.50635949114 22:19:51 INFO - PROCESS | 10948 | |5;yui.html;752.5484709174494;846.9026062002868;848.4716632220166;852.9573521323932;864.3646919514152;11886.702832429193;12111.454984525695;12164.93917530411;12181.878181218186;12191.08566857487;10449.529771160296;11206.103511719064;11274.210805243636;11277.37974411407;11284.210105292612;648.32897950621;784.4900814470583;800.1078797141204;800.1238643685156;800.3237264511507;787.2049230277432;787.5747096567846;787.6613056385752;787.9763607091796;790.1624278265028;820.2125959079261;822.5229366967145;824.9917500824974;825.793551612097;825.8430898129366;820.8440396324679;821.338822248093;822.4571782623469;822.6544851162527;824.5094168969454;500.32955839175315;505.0983993571494;610.2585358789048;612.5099920063975;624.6782906802955;12062.939685301517;12063.336516491589;12073;12080.335581543099;12081.93959030208;12013.639590812203;12352.62942111738;12462.881310177292;12633.49466021358;12719.682007949783;10042.4476653784;10108.191344692481;10271.383716977005;10278;10457.163426925828;3418.453047512386;3489.6956625928447;3492.2142517933517;3502.702270307027;3590.461430785377;47508.11132385785;47589;47772;47778.52221477775;47886.521134788556;9634.71095867125;9844.901550984541;10034;10174.473828925573;10308.072273495422;18461.7230741539;18464.446066618024;18516.814831851778;18542.81457185438;18569.350072747387;6848.315168483112;7053.188883278386;7071.222165561784;7080;7150.928490715026;3912;3924.5682974872743;3942.0539070623418;3957.52509698838;4020.618041286073;2932.2669332666833;2936.119164250716;2954.7931644784658;2956.9704302957125;2965.303153758865;537.40348213483;559.4265877475539;563.788579282769;563.8815848671825;567.1464446008813;448.2491826191117;452.615277014535;459.0612198054986;472.40005193404187;570.0736303506801;8152.836943261102;8274.503529788231;8341.95829020851;8342.87485687703;8476.27951624119;507.43927959604844;509.17005281391096;517.8731210853365;610.1579819848669;626.614632001325;3845.576986531479;3971.702122340836;3973.8609148679657;3987.8205480752854;4075.938860917029;54530.45469545333;55375;55475.16787248194;55591.44408555863;55886;49185.754071229414;55929.44070559324;56211;57138.7143064282;57153;44856.3271550927;45819.54180458219;46171.76914115408;46253.76873115613;46884;8285.95857020711;8304.899311088931;8442.240198382175;8528.189821966904;8570.700025499078;33376.83311583524;33392.833035834665;33427.665723342456;33661.32677346488;33714;40525.79737101414;40710.389344159266;41110.1777964445;44431;44612.10775784531;10086.394816311045;10373;10423.114035307084;10462.63380781669;10486.213533984828;11701.648950531498;11717.589884354007;11788.233764805604;11818.172727909308;11854.881451185724;10597.41714205726;10636.627718029831;10638.255322127687;10680.732981675521;10966.067884229611;10984.560617575207;11009.339439633648;11175.21773475849;11298.322100673986;11606.767864642828;7927.663905657777;9752.317337786288;11347.20569560123;11358.829617555908;11804.055675546108;8469.322454203526;8798.780030499289;8849.20357167858;8950.14973577509;9172.312076594415;8190.344772418312;9027.909720902708;9307.11582399671;9530.95234523823;9532.332736708364 22:19:51 INFO - PROCESS | 10948 | __end_tp_report 22:19:51 INFO - PROCESS | 10948 | __start_cc_report 22:19:51 INFO - PROCESS | 10948 | _x_x_mozilla_cycle_collect,913 22:19:51 INFO - PROCESS | 10948 | __end_cc_report 22:19:51 INFO - PROCESS | 10948 | __startTimestamp1453529991573__endTimestamp 22:19:51 INFO - PROCESS | 10948 | 22:19:51 INFO - PROCESS | 10948 | ------- Summary: start ------- 22:19:51 INFO - PROCESS | 10948 | Number of tests: 6 22:19:51 INFO - PROCESS | 10948 | 22:19:51 INFO - PROCESS | 10948 | [#0] dojo.html Cycles:165 Average:5164.93 Median:5369.25 stddev:545.20 (10.2%) stddev-sans-first:521.91 22:19:51 INFO - PROCESS | 10948 | Values: 3085.9 3150.9 3197.6 3199.9 3223.2 5114.8 5119.6 5137.7 5152.7 5187.7 5095.3 5144.8 5213.8 5217.1 5230.6 4762.3 4764.7 4780.4 4789.4 4888.4 4802.7 4829.1 4838.3 4853.1 4890.5 4653.4 4676.1 4715.2 4774.1 4820.0 4771.0 4772.9 4785.8 4789.4 4809.7 4757.0 4760.7 4761.8 4767.7 4786.5 5018.0 5029.8 5061.8 5072.1 5091.6 5526.7 5529.8 5530.7 5533.0 5554.6 5573.8 5580.8 5581.9 5583.9 5585.4 5100.5 5171.3 5200.1 5207.9 5254.4 5571.1 5582.4 5583.6 5590.3 5593.8 5397.6 5398.6 5398.7 5405.4 5447.7 5480.9 5502.7 5572.1 5573.3 5591.6 5461.2 5465.2 5467.8 5486.6 5512.8 5445.2 5464.7 5474.6 5514.9 5517.3 4255.4 4256.9 4260.8 4350.8 4364.3 3891.5 3896.8 3901.4 3931.0 3938.2 5165.4 5169.1 5172.6 5177.7 5178.2 5479.7 5491.0 5546.7 5560.6 5561.9 5172.9 5201.4 5214.2 5215.3 5229.5 4784.0 4791.7 4795.7 4802.4 4822.9 5689.1 5780.4 5787.7 5793.5 5866.2 5581.7 5583.0 5598.6 5628.1 5653.0 5763.4 5766.7 5767.3 5769.5 5782.5 5429.9 5471.3 5475.0 5523.0 5529.6 5530.8 5563.0 5572.7 5584.3 5592.0 5447.0 5450.0 5453.1 5466.1 5511.0 5294.6 5374.4 5392.2 5420.8 5435.4 5356.6 5360.9 5369.2 5375.6 5416.6 5455.3 5456.5 5460.0 5463.5 5463.5 5321.6 5353.2 5355.1 5360.3 5378.4 22:19:51 INFO - PROCESS | 10948 | 22:19:51 INFO - PROCESS | 10948 | [#1] ext.html Cycles:180 Average:45793.36 Median:19446.43 stddev:79252.66 (407.5%) stddev-sans-first:79402.04 22:19:51 INFO - PROCESS | 10948 | Values: 506.4 544.8 547.7 550.2 560.7 27397.6 37360.0 38107.2 38305.8 38318.4 41997.4 42497.0 42499.6 42525.0 42538.9 8479.7 8730.3 8734.9 8750.5 8752.2 8462.3 8546.9 8572.4 8581.3 8584.6 8564.4 8579.0 8656.8 8674.4 8676.9 8542.0 8559.6 8577.4 8588.3 8601.1 8346.7 8438.2 8455.6 8468.0 8474.1 36735.6 37134.0 37183.4 37260.0 37316.4 3120.3 3184.9 3259.2 3515.6 3535.2 29143.7 29565.0 29721.3 30160.4 30532.2 3521.8 3540.6 3545.9 3578.7 3664.6 258691.0 260455.0 260773.0 261095.0 262743.0 49299.8 49894.8 53361.5 53759.2 56018.4 50430.5 50442.5 50665.2 50716.2 50803.0 12774.9 12880.5 12881.2 12900.8 12929.9 8981.2 9100.4 9142.9 9177.9 9287.2 17539.5 17710.7 17731.6 17747.4 17789.9 7321.9 7384.0 7456.7 7503.6 7534.5 1006.6 1057.5 1065.6 1083.4 1085.0 27935.9 28111.0 28116.0 28356.3 28399.7 1012.2 1038.2 1051.4 1053.5 1102.1 14654.3 14874.9 14916.3 14962.9 15324.0 325706.0 335609.0 335682.0 337148.0 337243.0 51004.0 51395.2 51435.5 51535.7 51541.0 1312.1 1316.4 1326.2 1332.7 1339.3 27628.0 28432.9 28698.4 28726.7 28835.0 42755.8 42994.4 43105.0 43267.6 43356.8 305957.0 306741.0 308182.0 308551.0 309983.0 29150.7 30014.8 30137.5 30173.8 30255.0 40460.0 40756.2 41020.2 41500.8 41569.0 34476.3 34785.8 34895.5 34929.3 35653.5 35086.8 35352.3 35438.5 35638.0 35741.6 34695.0 35087.3 35230.8 35579.8 35585.0 27149.7 29181.9 29801.0 31934.2 32833.0 22685.3 22985.4 23271.3 24171.3 24432.8 22:19:51 INFO - PROCESS | 10948 | 22:19:51 INFO - PROCESS | 10948 | [#2] jquery.html Cycles:180 Average:25393.69 Median:5334.90 stddev:79233.00 (1485.2%) stddev-sans-first:79432.95 22:19:51 INFO - PROCESS | 10948 | Values: 347.7 367.5 370.5 372.1 372.3 12662.7 16002.0 17016.2 17987.6 17994.7 14862.9 15056.0 15183.1 15460.5 15606.1 3980.7 4135.8 4142.6 5227.3 5424.4 5657.5 5831.0 5916.7 5928.7 5953.3 5886.7 5987.6 5992.3 5996.2 5996.8 5774.4 5944.7 5959.0 5991.7 6009.4 5134.6 5334.6 5335.2 5337.5 5339.3 19815.4 19822.5 19990.3 19990.4 20160.0 638.4 638.5 2108.6 2395.6 2424.3 12457.2 12585.5 12752.6 12890.5 12949.0 1762.5 1784.2 1802.7 1814.8 1828.0 55265.0 55680.4 55954.4 56765.7 57346.0 9736.3 9882.9 9962.2 9964.5 10015.6 13071.2 13113.6 13130.1 13139.7 13153.0 7082.4 7093.5 7146.8 7150.1 7267.1 6332.4 6393.2 6417.5 6423.0 6630.7 5370.3 5649.3 5659.7 5665.6 5859.3 1728.5 1764.7 1775.7 1783.3 1784.9 923.5 927.1 951.3 997.7 1007.9 12229.9 15200.0 15271.8 16252.4 16561.8 885.2 897.3 932.4 932.9 991.4 9351.3 9506.5 9507.5 9511.6 9852.3 467215.3 476141.0 487300.0 497226.0 498756.0 44853.8 62702.4 66064.3 69881.3 71267.3 1109.6 1113.9 1136.9 1170.4 1183.7 14591.5 14628.3 14807.4 15170.7 15851.2 31636.9 32199.0 32367.0 32507.5 33697.0 35393.8 36198.0 36728.8 36782.6 37038.0 7133.4 7477.9 7601.0 7616.2 7635.8 10425.5 11104.0 11119.0 11343.8 11620.1 7063.0 7350.2 7415.1 7495.0 7641.8 7105.8 7182.6 7356.3 7593.5 7655.0 7253.8 7278.7 7626.7 7724.5 7838.5 9814.1 10106.7 10216.3 10378.5 10405.6 7151.1 7195.2 7238.1 7310.1 7329.5 22:19:51 INFO - PROCESS | 10948 | 22:19:51 INFO - PROCESS | 10948 | [#3] mootools.html Cycles:180 Average:5567.52 Median:6642.55 stddev:2900.10 (43.7%) stddev-sans-first:2890.77 22:19:51 INFO - PROCESS | 10948 | Values: 1333.7 1430.7 1440.4 1601.5 1793.4 7138.3 7693.0 7857.2 7876.5 7880.9 7770.6 7926.8 7932.2 7933.9 7943.5 2042.4 2094.4 2239.7 2303.8 2328.3 2261.5 2266.8 2269.3 2270.3 2270.4 2176.5 2265.8 2266.3 2269.7 2271.8 2255.9 2271.6 2277.4 2310.2 2312.4 2304.9 2322.5 2339.0 2339.1 2346.4 8012.2 8066.7 8072.4 8077.6 8083.0 4637.1 4717.6 4722.2 4740.0 4757.9 3090.1 3094.6 3115.3 3135.2 3226.5 1032.5 1034.4 1036.8 1079.7 1086.9 10787.1 10861.1 10882.2 10915.7 10952.5 4132.4 4212.5 4264.4 4354.1 4359.2 8858.9 9061.0 9082.8 9103.0 9129.1 4774.9 4794.0 4820.5 4828.3 4832.3 4408.6 4411.8 4414.9 4417.1 4423.2 3383.6 3435.5 3521.2 3625.7 3690.0 1125.2 1128.9 1141.2 1141.9 1145.6 1275.3 1307.5 1328.5 1349.7 1350.3 7313.2 7417.7 7527.1 7553.8 7555.3 1093.9 1099.3 1112.5 1112.5 1134.4 5902.4 5943.7 5981.8 5990.5 6020.4 9143.2 9419.5 9517.0 9541.2 9559.2 9657.8 9740.2 9772.5 9781.6 9782.7 7394.2 7497.7 7538.5 7592.3 7607.9 7395.9 7410.7 7508.4 7538.1 7569.7 8644.2 8756.1 8870.7 8955.4 9002.7 8615.8 9003.4 9022.8 9233.5 9242.9 6982.5 7080.5 7086.3 7088.1 7090.6 6729.2 6929.5 7257.5 7449.3 7451.5 6997.8 7083.0 7205.5 7206.6 7275.2 6958.0 6988.4 7111.0 7131.4 7159.2 7083.4 7138.8 7179.0 7181.6 7182.2 6641.4 6643.7 6657.4 6690.8 6707.0 6695.9 6698.3 6743.1 6744.0 6753.1 22:19:51 INFO - PROCESS | 10948 | 22:19:51 INFO - PROCESS | 10948 | [#4] prototype.html Cycles:180 Average:5222.90 Median:5868.35 stddev:1551.43 (26.4%) stddev-sans-first:1512.12 22:19:51 INFO - PROCESS | 10948 | Values: 353.8 355.1 356.1 357.8 361.5 5639.1 5818.2 5873.3 5894.7 5899.4 5633.7 5738.6 5743.1 5801.9 5831.8 4547.0 4547.2 4574.9 4585.8 4586.4 4385.0 4493.9 4584.1 4589.4 4594.8 4428.6 4480.3 4501.7 4501.9 4517.6 4480.5 4492.4 4493.8 4499.7 4505.2 4003.9 4009.6 4014.8 4020.0 4034.6 5484.3 5518.6 5525.4 5533.9 5580.7 5273.4 5274.7 5277.3 5283.0 5286.6 6143.8 6151.8 6153.5 6156.0 6156.8 4244.3 4304.5 4330.0 4351.4 4483.0 6390.5 6431.7 6439.9 6440.4 6475.4 4515.4 4523.0 4524.7 4525.8 4529.9 4517.6 4580.9 4582.3 4582.8 4586.3 5214.7 5272.2 5272.9 5279.1 5281.1 6199.0 6216.7 6226.6 6290.5 6294.8 1632.3 1651.2 1652.7 1653.3 1654.4 1081.3 1100.0 1107.7 1110.2 1127.7 5881.9 5884.6 5886.3 5886.6 5897.5 6340.4 6363.4 6368.9 6378.6 6381.9 5836.0 5838.1 5857.8 5863.4 5878.9 3335.9 3432.2 3433.5 3433.5 3436.8 6662.2 6689.5 6710.3 6713.1 6724.1 6527.2 6576.9 6581.4 6593.3 6596.1 6549.3 6683.8 6692.2 6697.8 6707.5 6404.2 6411.5 6418.6 6424.7 6428.3 6116.8 6142.3 6273.1 6352.2 6370.1 6688.9 6690.4 6695.1 6705.6 6721.5 4411.2 4438.8 4441.6 4441.7 4445.2 6287.1 6449.2 6450.6 6454.7 6456.5 6290.0 6296.4 6302.8 6304.4 6308.7 6278.8 6279.2 6283.7 6285.3 6285.5 6061.5 6174.7 6181.3 6182.8 6187.2 6306.4 6309.8 6315.6 6318.2 6324.2 6136.4 6159.1 6161.8 6168.1 6170.5 22:19:51 INFO - PROCESS | 10948 | 22:19:51 INFO - PROCESS | 10948 | [#5] yui.html Cycles:180 Average:13424.03 Median:23315.42 stddev:15905.05 (68.2%) stddev-sans-first:15921.20 22:19:51 INFO - PROCESS | 10948 | Values: 752.5 846.9 848.5 853.0 864.4 11886.7 12111.5 12164.9 12181.9 12191.1 10449.5 11206.1 11274.2 11277.4 11284.2 648.3 784.5 800.1 800.1 800.3 787.2 787.6 787.7 788.0 790.2 820.2 822.5 825.0 825.8 825.8 820.8 821.3 822.5 822.7 824.5 500.3 505.1 610.3 612.5 624.7 12062.9 12063.3 12073.0 12080.3 12081.9 12013.6 12352.6 12462.9 12633.5 12719.7 10042.4 10108.2 10271.4 10278.0 10457.2 3418.5 3489.7 3492.2 3502.7 3590.5 47508.1 47589.0 47772.0 47778.5 47886.5 9634.7 9844.9 10034.0 10174.5 10308.1 18461.7 18464.4 18516.8 18542.8 18569.4 6848.3 7053.2 7071.2 7080.0 7150.9 3912.0 3924.6 3942.1 3957.5 4020.6 2932.3 2936.1 2954.8 2957.0 2965.3 537.4 559.4 563.8 563.9 567.1 448.2 452.6 459.1 472.4 570.1 8152.8 8274.5 8342.0 8342.9 8476.3 507.4 509.2 517.9 610.2 626.6 3845.6 3971.7 3973.9 3987.8 4075.9 54530.5 55375.0 55475.2 55591.4 55886.0 49185.8 55929.4 56211.0 57138.7 57153.0 44856.3 45819.5 46171.8 46253.8 46884.0 8286.0 8304.9 8442.2 8528.2 8570.7 33376.8 33392.8 33427.7 33661.3 33714.0 40525.8 40710.4 41110.2 44431.0 44612.1 10086.4 10373.0 10423.1 10462.6 10486.2 11701.6 11717.6 11788.2 11818.2 11854.9 10597.4 10636.6 10638.3 10680.7 10966.1 10984.6 11009.3 11175.2 11298.3 11606.8 7927.7 9752.3 11347.2 11358.8 11804.1 8469.3 8798.8 8849.2 8950.1 9172.3 8190.3 9027.9 9307.1 9531.0 9532.3 22:19:51 INFO - PROCESS | 10948 | -------- Summary: end -------- 22:19:51 INFO - PROCESS | 10948 | 22:19:52 INFO - TEST-INFO | 10948: exit 0 22:19:52 INFO - TEST-OK | dromaeo_css | took 1113493ms 22:19:52 INFO - TEST-START | kraken 22:19:52 INFO - operating with platform_type : linux_ 22:19:52 INFO - Initialising browser for kraken test... 22:19:52 INFO - TEST-INFO | started process 11149 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGDqwTu/profile http://localhost:48553/getInfo.html) 22:19:56 INFO - PROCESS | 11149 | __metrics Screen width/height:1600/1200 22:19:56 INFO - PROCESS | 11149 | colorDepth:24 22:19:56 INFO - PROCESS | 11149 | Browser inner width/height: 1024/697 22:19:56 INFO - PROCESS | 11149 | __metrics 22:19:56 INFO - PROCESS | 11149 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 22:19:56 INFO - PROCESS | 11149 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:19:57 INFO - TEST-INFO | 11149: exit 0 22:19:57 INFO - Browser initialized. 22:19:57 INFO - Running cycle 1/1 for kraken test... 22:19:57 INFO - TEST-INFO | started process 11229 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGDqwTu/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:20:09 INFO - PROCESS | 11229 | RSS: Main: 148197376 22:20:09 INFO - PROCESS | 11229 | 22:20:11 INFO - PROCESS | 11229 | Cycle 1(1): loaded http://localhost:48553/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48553/tests/kraken/driver.html?testName=audio-beat-detection) 22:20:12 INFO - PROCESS | 11229 | RSS: Main: 187392000 22:20:12 INFO - PROCESS | 11229 | 22:20:15 INFO - PROCESS | 11229 | Cycle 1(1): loaded http://localhost:48553/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48553/tests/kraken/driver.html?testName=audio-dft) 22:20:15 INFO - PROCESS | 11229 | RSS: Main: 178843648 22:20:15 INFO - PROCESS | 11229 | 22:20:18 INFO - PROCESS | 11229 | Cycle 1(1): loaded http://localhost:48553/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48553/tests/kraken/driver.html?testName=audio-fft) 22:20:19 INFO - PROCESS | 11229 | RSS: Main: 162758656 22:20:19 INFO - PROCESS | 11229 | 22:20:21 INFO - PROCESS | 11229 | Cycle 1(1): loaded http://localhost:48553/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48553/tests/kraken/driver.html?testName=audio-oscillator) 22:20:21 INFO - PROCESS | 11229 | RSS: Main: 165261312 22:20:21 INFO - PROCESS | 11229 | 22:20:24 INFO - PROCESS | 11229 | Cycle 1(1): loaded http://localhost:48553/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48553/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:20:24 INFO - PROCESS | 11229 | RSS: Main: 167727104 22:20:24 INFO - PROCESS | 11229 | 22:20:27 INFO - PROCESS | 11229 | Cycle 1(1): loaded http://localhost:48553/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48553/tests/kraken/driver.html?testName=imaging-darkroom) 22:20:28 INFO - PROCESS | 11229 | RSS: Main: 167882752 22:20:28 INFO - PROCESS | 11229 | 22:20:31 INFO - PROCESS | 11229 | Cycle 1(1): loaded http://localhost:48553/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48553/tests/kraken/driver.html?testName=imaging-desaturate) 22:20:32 INFO - PROCESS | 11229 | RSS: Main: 167534592 22:20:32 INFO - PROCESS | 11229 | 22:20:34 INFO - PROCESS | 11229 | Cycle 1(1): loaded http://localhost:48553/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48553/tests/kraken/driver.html?testName=json-parse-financial) 22:20:35 INFO - PROCESS | 11229 | RSS: Main: 168914944 22:20:35 INFO - PROCESS | 11229 | 22:20:37 INFO - PROCESS | 11229 | Cycle 1(1): loaded http://localhost:48553/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48553/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:20:37 INFO - PROCESS | 11229 | RSS: Main: 178503680 22:20:37 INFO - PROCESS | 11229 | 22:20:39 INFO - PROCESS | 11229 | Cycle 1(1): loaded http://localhost:48553/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48553/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:20:40 INFO - PROCESS | 11229 | RSS: Main: 176738304 22:20:40 INFO - PROCESS | 11229 | 22:20:42 INFO - PROCESS | 11229 | Cycle 1(1): loaded http://localhost:48553/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48553/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:20:42 INFO - PROCESS | 11229 | RSS: Main: 187707392 22:20:42 INFO - PROCESS | 11229 | 22:20:45 INFO - PROCESS | 11229 | Cycle 1(1): loaded http://localhost:48553/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48553/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:20:45 INFO - PROCESS | 11229 | RSS: Main: 194670592 22:20:45 INFO - PROCESS | 11229 | 22:20:48 INFO - PROCESS | 11229 | Cycle 1(1): loaded http://localhost:48553/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48553/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:20:48 INFO - PROCESS | 11229 | RSS: Main: 192942080 22:20:48 INFO - PROCESS | 11229 | 22:20:50 INFO - PROCESS | 11229 | Cycle 1(1): loaded http://localhost:48553/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48553/tests/kraken/driver.html?testName=ai-astar) 22:20:50 INFO - PROCESS | 11229 | RSS: Main: 252743680 22:20:50 INFO - PROCESS | 11229 | 22:20:50 INFO - PROCESS | 11229 | __start_tp_report 22:20:50 INFO - PROCESS | 11229 | _x_x_mozilla_page_load 22:20:50 INFO - PROCESS | 11229 | _x_x_mozilla_page_load_details 22:20:50 INFO - PROCESS | 11229 | |i|pagename|runs| 22:20:50 INFO - PROCESS | 11229 | |0;ai-astar;112;111;106;109;109;109;110;109;103;110 22:20:50 INFO - PROCESS | 11229 | |1;audio-beat-detection;167;125;164;165;127;150;163;127;173;125 22:20:50 INFO - PROCESS | 11229 | |2;audio-dft;160;161;161;159;161;160;159;159;162;161 22:20:50 INFO - PROCESS | 11229 | |3;audio-fft;73;74;109;98;73;75;75;115;97;76 22:20:50 INFO - PROCESS | 11229 | |4;audio-oscillator;96;97;147;97;96;95;144;96;97;136 22:20:50 INFO - PROCESS | 11229 | |5;imaging-gaussian-blur;109;110;110;109;109;109;110;109;109;109 22:20:50 INFO - PROCESS | 11229 | |6;imaging-darkroom;146;147;147;147;147;145;146;146;145;146 22:20:50 INFO - PROCESS | 11229 | |7;imaging-desaturate;96;96;95;96;96;95;96;95;96;96 22:20:50 INFO - PROCESS | 11229 | |8;json-parse-financial;56;55;55;54;55;56;55;55;56;54 22:20:50 INFO - PROCESS | 11229 | |9;json-stringify-tinderbox;67;68;68;67;67;68;67;68;68;69 22:20:50 INFO - PROCESS | 11229 | |10;stanford-crypto-aes;81;82;82;82;85;83;81;83;83;83 22:20:50 INFO - PROCESS | 11229 | |11;stanford-crypto-ccm;117;115;116;113;112;116;123;121;118;117 22:20:50 INFO - PROCESS | 11229 | |12;stanford-crypto-pbkdf2;147;138;142;143;144;133;145;141;142;144 22:20:50 INFO - PROCESS | 11229 | |13;stanford-crypto-sha256-iterative;65;68;67;66;66;65;67;67;66;66 22:20:50 INFO - PROCESS | 11229 | __end_tp_report 22:20:50 INFO - PROCESS | 11229 | __start_cc_report 22:20:50 INFO - PROCESS | 11229 | _x_x_mozilla_cycle_collect,968 22:20:50 INFO - PROCESS | 11229 | __end_cc_report 22:20:50 INFO - PROCESS | 11229 | __startTimestamp1453530050590__endTimestamp 22:20:50 INFO - PROCESS | 11229 | 22:20:50 INFO - PROCESS | 11229 | ------- Summary: start ------- 22:20:50 INFO - PROCESS | 11229 | Number of tests: 14 22:20:50 INFO - PROCESS | 11229 | 22:20:50 INFO - PROCESS | 11229 | [#0] ai-astar Cycles:10 Average:108.80 Median:109.50 stddev:2.57 (2.4%) stddev-sans-first:2.46 22:20:50 INFO - PROCESS | 11229 | Values: 112.0 111.0 106.0 109.0 109.0 109.0 110.0 109.0 103.0 110.0 22:20:50 INFO - PROCESS | 11229 | 22:20:50 INFO - PROCESS | 11229 | [#1] audio-beat-detection Cycles:10 Average:148.60 Median:163.50 stddev:20.27 (12.4%) stddev-sans-first:20.37 22:20:50 INFO - PROCESS | 11229 | Values: 167.0 125.0 164.0 165.0 127.0 150.0 163.0 127.0 173.0 125.0 22:20:50 INFO - PROCESS | 11229 | 22:20:50 INFO - PROCESS | 11229 | [#2] audio-dft Cycles:10 Average:160.30 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 22:20:50 INFO - PROCESS | 11229 | Values: 160.0 161.0 161.0 159.0 161.0 160.0 159.0 159.0 162.0 161.0 22:20:50 INFO - PROCESS | 11229 | 22:20:50 INFO - PROCESS | 11229 | [#3] audio-fft Cycles:10 Average:86.50 Median:75.00 stddev:16.52 (22.0%) stddev-sans-first:16.79 22:20:50 INFO - PROCESS | 11229 | Values: 73.0 74.0 109.0 98.0 73.0 75.0 75.0 115.0 97.0 76.0 22:20:50 INFO - PROCESS | 11229 | 22:20:50 INFO - PROCESS | 11229 | [#4] audio-oscillator Cycles:10 Average:110.10 Median:96.00 stddev:22.41 (23.3%) stddev-sans-first:23.18 22:20:50 INFO - PROCESS | 11229 | Values: 96.0 97.0 147.0 97.0 96.0 95.0 144.0 96.0 97.0 136.0 22:20:50 INFO - PROCESS | 11229 | 22:20:50 INFO - PROCESS | 11229 | [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 22:20:50 INFO - PROCESS | 11229 | Values: 109.0 110.0 110.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 22:20:50 INFO - PROCESS | 11229 | 22:20:50 INFO - PROCESS | 11229 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.50 stddev:0.79 (0.5%) stddev-sans-first:0.83 22:20:50 INFO - PROCESS | 11229 | Values: 146.0 147.0 147.0 147.0 147.0 145.0 146.0 146.0 145.0 146.0 22:20:50 INFO - PROCESS | 11229 | 22:20:50 INFO - PROCESS | 11229 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 22:20:50 INFO - PROCESS | 11229 | Values: 96.0 96.0 95.0 96.0 96.0 95.0 96.0 95.0 96.0 96.0 22:20:50 INFO - PROCESS | 11229 | 22:20:50 INFO - PROCESS | 11229 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:0.74 (1.3%) stddev-sans-first:0.71 22:20:50 INFO - PROCESS | 11229 | Values: 56.0 55.0 55.0 54.0 55.0 56.0 55.0 55.0 56.0 54.0 22:20:50 INFO - PROCESS | 11229 | 22:20:50 INFO - PROCESS | 11229 | [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:68.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 22:20:50 INFO - PROCESS | 11229 | Values: 67.0 68.0 68.0 67.0 67.0 68.0 67.0 68.0 68.0 69.0 22:20:50 INFO - PROCESS | 11229 | 22:20:50 INFO - PROCESS | 11229 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:83.00 stddev:1.18 (1.4%) stddev-sans-first:1.12 22:20:50 INFO - PROCESS | 11229 | Values: 81.0 82.0 82.0 82.0 85.0 83.0 81.0 83.0 83.0 83.0 22:20:50 INFO - PROCESS | 11229 | 22:20:50 INFO - PROCESS | 11229 | [#11] stanford-crypto-ccm Cycles:10 Average:116.80 Median:117.00 stddev:3.33 (2.8%) stddev-sans-first:3.53 22:20:50 INFO - PROCESS | 11229 | Values: 117.0 115.0 116.0 113.0 112.0 116.0 123.0 121.0 118.0 117.0 22:20:50 INFO - PROCESS | 11229 | 22:20:50 INFO - PROCESS | 11229 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:141.90 Median:143.50 stddev:3.96 (2.8%) stddev-sans-first:3.74 22:20:50 INFO - PROCESS | 11229 | Values: 147.0 138.0 142.0 143.0 144.0 133.0 145.0 141.0 142.0 144.0 22:20:50 INFO - PROCESS | 11229 | 22:20:50 INFO - PROCESS | 11229 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:0.95 (1.4%) stddev-sans-first:0.88 22:20:50 INFO - PROCESS | 11229 | Values: 65.0 68.0 67.0 66.0 66.0 65.0 67.0 67.0 66.0 66.0 22:20:50 INFO - PROCESS | 11229 | -------- Summary: end -------- 22:20:50 INFO - PROCESS | 11229 | 22:20:51 INFO - TEST-INFO | 11229: exit 0 22:20:51 INFO - TEST-OK | kraken | took 59255ms 22:20:51 INFO - TEST-START | v8_7 22:20:51 INFO - operating with platform_type : linux_ 22:20:51 INFO - Initialising browser for v8_7 test... 22:20:51 INFO - TEST-INFO | started process 11483 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpt1or4Y/profile http://localhost:48553/getInfo.html) 22:20:55 INFO - PROCESS | 11483 | __metrics Screen width/height:1600/1200 22:20:55 INFO - PROCESS | 11483 | colorDepth:24 22:20:55 INFO - PROCESS | 11483 | Browser inner width/height: 1024/697 22:20:55 INFO - PROCESS | 11483 | __metrics 22:20:55 INFO - PROCESS | 11483 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 22:20:55 INFO - PROCESS | 11483 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:20:56 INFO - TEST-INFO | 11483: exit 0 22:20:56 INFO - Browser initialized. 22:20:56 INFO - Running cycle 1/1 for v8_7 test... 22:20:56 INFO - TEST-INFO | started process 11566 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpt1or4Y/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:21:08 INFO - PROCESS | 11566 | RSS: Main: 147947520 22:21:08 INFO - PROCESS | 11566 | 22:21:31 INFO - PROCESS | 11566 | Cycle 1(1): loaded http://localhost:48553/tests/v8_7/run.html (next: http://localhost:48553/tests/v8_7/run.html) 22:21:31 INFO - PROCESS | 11566 | RSS: Main: 544460800 22:21:31 INFO - PROCESS | 11566 | 22:21:31 INFO - PROCESS | 11566 | __start_tp_report 22:21:31 INFO - PROCESS | 11566 | _x_x_mozilla_page_load 22:21:31 INFO - PROCESS | 11566 | _x_x_mozilla_page_load_details 22:21:31 INFO - PROCESS | 11566 | |i|pagename|runs| 22:21:31 INFO - PROCESS | 11566 | |0;Richards;167.02856188408217 22:21:31 INFO - PROCESS | 11566 | |1;DeltaBlue;191.46084625694044 22:21:31 INFO - PROCESS | 11566 | |2;Encrypt;279.9552071668533 22:21:31 INFO - PROCESS | 11566 | |3;Decrypt;5175.257731958763 22:21:31 INFO - PROCESS | 11566 | |4;RayTrace;1955.078125 22:21:31 INFO - PROCESS | 11566 | |5;Earley;794.4444444444445 22:21:31 INFO - PROCESS | 11566 | |6;Boyer;12370.37037037037 22:21:31 INFO - PROCESS | 11566 | |7;RegExp;42583.333333333336 22:21:31 INFO - PROCESS | 11566 | |8;Splay;586.5102639296188 22:21:31 INFO - PROCESS | 11566 | |9;NavierStokes;4822.115384615385 22:21:31 INFO - PROCESS | 11566 | __end_tp_report 22:21:31 INFO - PROCESS | 11566 | __startTimestamp1453530091312__endTimestamp 22:21:31 INFO - PROCESS | 11566 | 22:21:31 INFO - PROCESS | 11566 | ------- Summary: start ------- 22:21:31 INFO - PROCESS | 11566 | Number of tests: 10 22:21:31 INFO - PROCESS | 11566 | 22:21:31 INFO - PROCESS | 11566 | [#0] Richards Cycles:1 Average:167.03 Median:167.03 stddev:0.00 (0.0%) 22:21:31 INFO - PROCESS | 11566 | Values: 167.0 22:21:31 INFO - PROCESS | 11566 | 22:21:31 INFO - PROCESS | 11566 | [#1] DeltaBlue Cycles:1 Average:191.46 Median:191.46 stddev:0.00 (0.0%) 22:21:31 INFO - PROCESS | 11566 | Values: 191.5 22:21:31 INFO - PROCESS | 11566 | 22:21:31 INFO - PROCESS | 11566 | [#2] Encrypt Cycles:1 Average:279.96 Median:279.96 stddev:0.00 (0.0%) 22:21:31 INFO - PROCESS | 11566 | Values: 280.0 22:21:31 INFO - PROCESS | 11566 | 22:21:31 INFO - PROCESS | 11566 | [#3] Decrypt Cycles:1 Average:5175.26 Median:5175.26 stddev:0.00 (0.0%) 22:21:31 INFO - PROCESS | 11566 | Values: 5175.3 22:21:31 INFO - PROCESS | 11566 | 22:21:31 INFO - PROCESS | 11566 | [#4] RayTrace Cycles:1 Average:1955.08 Median:1955.08 stddev:0.00 (0.0%) 22:21:31 INFO - PROCESS | 11566 | Values: 1955.1 22:21:31 INFO - PROCESS | 11566 | 22:21:31 INFO - PROCESS | 11566 | [#5] Earley Cycles:1 Average:794.44 Median:794.44 stddev:0.00 (0.0%) 22:21:31 INFO - PROCESS | 11566 | Values: 794.4 22:21:31 INFO - PROCESS | 11566 | 22:21:31 INFO - PROCESS | 11566 | [#6] Boyer Cycles:1 Average:12370.37 Median:12370.37 stddev:0.00 (0.0%) 22:21:31 INFO - PROCESS | 11566 | Values: 12370.4 22:21:31 INFO - PROCESS | 11566 | 22:21:31 INFO - PROCESS | 11566 | [#7] RegExp Cycles:1 Average:42583.33 Median:42583.33 stddev:0.00 (0.0%) 22:21:31 INFO - PROCESS | 11566 | Values: 42583.3 22:21:31 INFO - PROCESS | 11566 | 22:21:31 INFO - PROCESS | 11566 | [#8] Splay Cycles:1 Average:586.51 Median:586.51 stddev:0.00 (0.0%) 22:21:31 INFO - PROCESS | 11566 | Values: 586.5 22:21:31 INFO - PROCESS | 11566 | 22:21:31 INFO - PROCESS | 11566 | [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 22:21:31 INFO - PROCESS | 11566 | Values: 4822.1 22:21:31 INFO - PROCESS | 11566 | -------- Summary: end -------- 22:21:31 INFO - PROCESS | 11566 | 22:21:32 INFO - TEST-INFO | 11566: exit 0 22:21:32 INFO - TEST-OK | v8_7 | took 40629ms 22:21:32 INFO - SUITE-END | took 1213s 22:21:32 INFO - Completed test suite (00:20:13) 22:21:32 INFO - Working with test: dromaeo_css 22:21:32 INFO - Generating results file: dromaeo_css 22:21:32 INFO - Working with test: kraken 22:21:32 INFO - Generating results file: kraken 22:21:32 INFO - javascript benchmark 22:21:32 INFO - Working with test: v8_7 22:21:32 INFO - Generating results file: v8_7 22:21:32 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:21:32 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,63,35]] 22:21:32 INFO - process_Request line: dromaeo_css 6735.04 graph.html#tests=[[72,63,35]] 22:21:32 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:21:32 INFO - process_Request line: kraken 1495.80 graph.html#tests=[[232,63,35]] 22:21:32 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:21:32 INFO - process_Request line: v8_7 18924.75 graph.html#tests=[[230,63,35]] 22:21:32 INFO - RETURN: dromaeo_css: 6735.04 22:21:32 INFO - RETURN: kraken: 1495.80 22:21:32 INFO - RETURN: v8_7: 18924.75 22:21:32 INFO - javascript benchmark 22:21:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3085.8919937802175, 3150.9054728358155, 3197.5683282756836, 3199.8560064797066, 3223.210313473199, 5114.8465546033585, 5119.590432765374, 5137.691738495688, 5152.74236288186, 5187.714675692835, 5095.3376061112, 5144.794208231676, 5213.817516386927, 5217.113090774568, 5230.555402790767, 4762.309465127554, 4764.7379394133395, 4780.37855078841, 4789.425268967729, 4888.437829649582, 4802.711837289757, 4829.106615276168, 4838.298446725223, 4853.053654537367, 4890.462049174588, 4653.395058642389, 4676.111538807616, 4715.1512727709, 4774.140654682155, 4820.035992801454, 4771.0, 4772.856814295576, 4785.784639691222, 4789.425268967711, 4809.7114173149375, 4757.00102978375, 4760.721068278782, 4761.8095276188915, 4767.690100143514, 4786.473487916326, 5017.974910125426, 5029.7736601853, 5061.848144555669, 5072.0616685912855, 5091.618128640367, 5526.696031718254, 5529.778808847641, 5530.695811730353, 5533.0, 5554.555635549146, 5573.832785016456, 5580.776768929279, 5581.8604534886545, 5583.916241256385, 5585.385607583163, 5100.522888173559, 5171.250168725552, 5200.063988482034, 5207.921881171747, 5254.369475662945, 5571.0807716727095, 5582.413846546055, 5583.637063590853, 5590.329160500766, 5593.776248949996, 5397.568194544427, 5398.595105367034, 5398.676079435247, 5405.378381486179, 5447.700376479332, 5480.890382192334, 5502.669839809624, 5572.080606699849, 5573.275474188411, 5591.6365436246515, 5461.15352120422, 5465.180222966586, 5467.835964921059, 5486.561075113981, 5512.834614961557, 5445.210444485528, 5464.699441530674, 5474.644148130359, 5514.889702206014, 5517.337919449692, 4255.361695745601, 4256.914861702749, 4260.765657888784, 4350.825966961349, 4364.258076127066, 3891.5135608048995, 3896.844126234919, 3901.395283731026, 3931.017245688578, 3938.1926705025403, 5165.431802501722, 5169.069567477815, 5172.637915345889, 5177.7152256625495, 5178.171492561172, 5479.698616576126, 5491.0, 5546.667199968015, 5560.582956278296, 5561.888762224733, 5172.948270517247, 5201.401838788512, 5214.165733482625, 5215.269862219292, 5229.47705229474, 4784.0, 4791.712497250176, 4795.736234507136, 4802.399700037496, 4822.855314340575, 5689.146628005832, 5780.393058728857, 5787.71061446926, 5793.536517078708, 5866.208061911672, 5581.665100094007, 5582.995060889079, 5598.636088654224, 5628.099504079328, 5653.0, 5763.423657634203, 5766.711664416846, 5767.307923049261, 5769.53843692512, 5782.508486778671, 5429.864253393539, 5471.316085489314, 5475.0, 5522.972385138049, 5529.612927095065, 5530.806421775219, 5562.972185139049, 5572.665640061609, 5584.274044374205, 5592.0, 5447.0467668157125, 5449.972750136224, 5453.072977593739, 5466.070767969534, 5511.0, 5294.576433885203, 5374.355077390738, 5392.191171324333, 5420.810271640474, 5435.374931882884, 5356.57147428197, 5360.865978350573, 5369.248305237192, 5375.623706340519, 5416.593755468277, 5455.290812194389, 5456.536194423518, 5460.0, 5463.453654634504, 5463.48096930791, 5321.600879933943, 5353.197020446964, 5355.062863998863, 5360.3299587551555, 5378.408375078817], "unit": "score", "name": "dojo.html", "value": 5131.336804274318}, {"lowerIsBetter": false, "replicates": [506.43785398208007, 544.8120398462529, 547.7069767674294, 550.2159422822477, 560.6636018388966, 27397.58903616445, 37360.0, 38107.23785524288, 38305.80847095764, 38318.42522362167, 41997.37003944943, 42497.0, 42499.57500424995, 42525.0, 42538.936526586775, 8479.745607631765, 8730.301575873946, 8734.912650873504, 8750.512412889824, 8752.168543988311, 8462.323014158883, 8546.914530854705, 8572.442791218551, 8581.270591999688, 8584.613692383826, 8564.357673174505, 8579.0, 8656.783580410509, 8674.392792504526, 8676.8698469523, 8541.957290213571, 8559.572021398904, 8577.356698247593, 8588.312934965188, 8601.139886011411, 8346.749597512084, 8438.240558349778, 8455.57722113898, 8467.983841938945, 8474.110218427038, 36735.63264367349, 37134.0, 37183.44224836629, 37260.0, 37316.44025339622, 3120.2667373167287, 3184.9044528664176, 3259.1526203187104, 3515.6210997174107, 3535.186907011376, 29143.70856291431, 29565.0, 29721.25696857574, 30160.396792064035, 30532.236694082603, 3521.753477256593, 3540.5751309842726, 3545.94681079784, 3578.7494875358734, 3664.633536646341, 258691.0, 260455.0, 260773.0, 261095.0, 262743.0, 49299.75350123226, 49894.75052624786, 53361.466385336425, 53759.19361209585, 56018.43981560132, 50430.49569504258, 50442.495575044515, 50665.24002139971, 50716.23925641119, 50802.98394032173, 12774.872251277367, 12880.549180778627, 12881.162724422882, 12900.80648790269, 12929.935350323376, 8981.236594889508, 9100.362974591848, 9142.862857057149, 9177.90822091784, 9287.210587100037, 17539.47381578529, 17710.734338984927, 17731.645367092842, 17747.378841740476, 17789.911050444665, 7321.926780732125, 7384.0, 7456.701731930662, 7503.62481875904, 7534.472586918972, 1006.5830331635822, 1057.4501259345097, 1065.63235683689, 1083.4366129612558, 1085.0343194556851, 27935.88388012726, 28111.0, 28116.0, 28356.291092722433, 28399.71600284012, 1012.2003617142523, 1038.2420832791954, 1051.4059556350637, 1053.5153829238636, 1102.0522350778324, 14654.302359277786, 14874.92562537202, 14916.254187290591, 14962.92518537422, 15324.023630784002, 325706.0, 335609.0, 335682.0, 337148.0, 337243.0, 51004.0, 51395.229071563954, 51435.48564514382, 51535.742321288155, 51541.0, 1312.1143228320846, 1316.374722007039, 1326.157889740022, 1332.68015676237, 1339.2634051271762, 27628.0, 28432.85783571069, 28698.42603147967, 28726.712732872405, 28835.0, 42755.786221068694, 42994.35508467438, 43105.0, 43267.56732432761, 43356.78321608372, 305957.0, 306741.0, 308182.0, 308551.0, 309983.0, 29150.7084929148, 30014.84992575023, 30137.547936780527, 30173.849130754206, 30255.0, 40460.0, 40756.1848763029, 41020.17959640731, 41500.79249603733, 41569.0, 34476.31047379089, 34785.8260708705, 34895.47656785201, 34929.30141397208, 35653.465198021535, 35086.82456587701, 35352.292954140255, 35438.46842297419, 35638.0, 35741.642583574874, 34695.0, 35087.298254034264, 35230.823845881634, 35579.82210088933, 35585.0, 27149.72850271551, 29181.854090729412, 29801.0, 31934.201644959063, 32833.0, 22685.319440416813, 22985.384871676368, 23271.301860944197, 24171.274861754177, 24432.755672443047], "unit": "score", "name": "ext.html", "value": 17692.565093737223}, {"lowerIsBetter": false, "replicates": [347.6905554056888, 367.5130452150899, 370.4702275745684, 372.0624027450824, 372.33352299384086, 12662.746745065093, 16002.0, 17016.234269457887, 17987.64024719505, 17994.730079048826, 14862.851371486282, 15056.021358611682, 15183.089014659128, 15460.458883939064, 15606.141662208574, 3980.661643760284, 4135.77253251071, 4142.565030671766, 5227.3465816772905, 5424.430434804348, 5657.462541058613, 5830.96200888623, 5916.674582897939, 5928.6739229342365, 5953.2856057272975, 5886.705664716747, 5987.640741555499, 5992.250968628921, 5996.16053752475, 5996.790112346069, 5774.422557744234, 5944.7027648617395, 5959.046552551593, 5991.700414979234, 6009.429104235091, 5134.589232861362, 5334.59990500714, 5335.1997200419855, 5337.5196232338885, 5339.332583427072, 19815.40553783389, 19822.50443738918, 19990.300339488193, 19990.400287991382, 20160.0, 638.3967151042267, 638.5147288061061, 2108.5677436125557, 2395.6105458833836, 2424.2969538833718, 12457.1902826316, 12585.496580136783, 12752.617421477371, 12890.54883079097, 12949.0, 1762.480068379831, 1784.1703607822426, 1802.7115661494097, 1814.845738112262, 1827.9543011424687, 55265.0, 55680.44319556793, 55954.40455954413, 56765.71617141929, 57346.0, 9736.318457708034, 9882.901170988198, 9962.153216976638, 9964.451955142536, 10015.599376024877, 13071.15037522558, 13113.606591802261, 13130.146540474838, 13139.67150821218, 13153.0, 7082.433405327562, 7093.538919970289, 7146.821329466701, 7150.070490836158, 7267.055282813306, 6332.353588067136, 6393.2328120625825, 6417.454516366161, 6423.036544518266, 6630.734770609218, 5370.335196089225, 5649.322081350264, 5659.660420374791, 5665.575081868877, 5859.296884373903, 1728.4900954218535, 1764.6647137043922, 1775.697203202043, 1783.2599471219557, 1784.857211423083, 923.5197697197419, 927.1284992107533, 951.2722767083187, 997.7356000659686, 1007.8941711120374, 12229.93885030569, 15200.0, 15271.770923436157, 16252.431164909407, 16561.83438165627, 885.1856292211181, 897.3140004754043, 932.4172392254841, 932.9253659707207, 991.4101109839634, 9351.298652600946, 9506.47714375716, 9507.524623768923, 9511.620814981788, 9852.310338276251, 467215.29492935166, 476141.0, 487300.0, 497226.0, 498756.0, 44853.77573112114, 62702.37297626965, 66064.33935660678, 69881.3011869885, 71267.28732712606, 1109.6005438042298, 1113.9220254582262, 1136.931784092957, 1170.449888552379, 1183.7040739815047, 14591.489297874523, 14628.26858657084, 14807.407703692163, 15170.696586067996, 15851.207439627742, 31636.892708756004, 32199.0, 32367.0, 32507.512387313735, 33696.989090327326, 35393.82303088468, 36198.0, 36728.816355919116, 36782.63217367792, 37037.9998700172, 7133.429325653831, 7477.88783168242, 7600.961995189989, 7616.20029896875, 7635.847283054197, 10425.478726063515, 11104.0, 11119.0, 11343.829842552203, 11620.128490363426, 7063.046488723957, 7350.196818066344, 7415.145681296902, 7495.025646665898, 7641.847163056596, 7105.75129870452, 7182.569045857265, 7356.264373562601, 7593.468457207942, 7655.0, 7253.782386528413, 7278.708851646086, 7626.733064342721, 7724.497907635986, 7838.451308408585, 9814.116729494379, 10106.747331316776, 10216.284860059724, 10378.481075946022, 10405.635802746867, 7151.070360853056, 7195.244499327495, 7238.0952380952385, 7310.122785265589, 7329.52358096722], "unit": "score", "name": "jquery.html", "value": 7927.940588355116}, {"lowerIsBetter": false, "replicates": [1333.6532501549593, 1430.6995530938502, 1440.3806363263795, 1601.4715144002473, 1793.3902473159133, 7138.286171382865, 7693.038370203724, 7857.174996625344, 7876.488028278158, 7880.881786773203, 7770.57261850598, 7926.801829954254, 7932.206779322065, 7933.92066079339, 7943.523388596688, 2042.387283814857, 2094.413564202026, 2239.74242962059, 2303.781140791622, 2328.3364241191266, 2261.457250259934, 2266.7959883610556, 2269.307861102363, 2270.3075561953597, 2270.3529494094187, 2176.4994051368194, 2265.762094980028, 2266.331432227497, 2269.6822444857735, 2271.8068964137988, 2255.8872056397277, 2271.6251818449937, 2277.4420267034598, 2310.2491690200586, 2312.3987763181526, 2304.9078036878504, 2322.500662357584, 2338.988305058464, 2339.1111377676375, 2346.366481050124, 8012.238837310446, 8066.677332906676, 8072.354211663052, 8077.596120193967, 8082.989626296713, 4637.095766325568, 4717.646176536773, 4722.244440889442, 4740.0, 4757.857264282077, 3090.0884239149505, 3094.5667606535103, 3115.3146307812244, 3135.2475405902646, 3226.483762597973, 1032.4682788363998, 1034.384541197986, 1036.8133735927606, 1079.7354648111184, 1086.869575650919, 10787.137029037658, 10861.13110951122, 10882.23824332297, 10915.727106822314, 10952.4523773811, 4132.40080188371, 4212.515560710496, 4264.424302719155, 4354.063876266618, 4359.17175736609, 8858.911410885938, 9060.954695226614, 9082.772930676654, 9102.954485227532, 9129.132732390412, 4774.856754297377, 4794.041191761592, 4820.517948205222, 4828.348172996671, 4832.275158726219, 4408.625266852354, 4411.801468933906, 4414.867553973386, 4417.050334178167, 4423.248047831877, 3383.6278009418943, 3435.484677298375, 3521.190126270972, 3625.7099432045375, 3690.040589446763, 1125.178619607691, 1128.8758236593967, 1141.1612464838338, 1141.8629764428156, 1145.587588468151, 1275.3113318807739, 1307.4770091963292, 1328.4752522753652, 1349.730053989206, 1350.3248375812093, 7313.195548489662, 7417.703291868264, 7527.134379546423, 7553.773386798405, 7555.320021198118, 1093.8960798724108, 1099.3239157917824, 1112.4548971004149, 1112.4826955465746, 1134.4044376702298, 5902.4097590240635, 5943.70281485924, 5981.760729570856, 5990.460858522754, 6020.4280593343565, 9143.177114059767, 9419.481928493999, 9516.95241523788, 9541.18899893503, 9559.235261179088, 9657.758556036015, 9740.220782337396, 9772.51137443125, 9781.608735650494, 9782.706518804447, 7394.223606521237, 7497.700091996368, 7538.509996850187, 7592.316691497792, 7607.885881711668, 7395.926040739524, 7410.740624078131, 7508.399328053633, 7538.057742782153, 7569.6593653284635, 8644.178803013703, 8756.124387561193, 8870.73387798367, 8955.41789783662, 9002.729918102468, 8615.784605384664, 9003.369764116449, 9022.819543609223, 9233.492157931378, 9242.907570924204, 6982.476314276551, 7080.504364694422, 7086.326798954091, 7088.113985751781, 7090.581883623193, 6729.158855143107, 6929.549579277331, 7257.49197556166, 7449.255074492507, 7451.478396512409, 6997.8250543734775, 7082.964585177041, 7205.495615307088, 7206.56760594366, 7275.163356214103, 6957.965210173917, 6988.440924726111, 7110.964445177741, 7131.393831524379, 7159.212486626571, 7083.397911177608, 7138.821529461805, 7179.0, 7181.569105853666, 7182.245864184186, 6641.402273795382, 6643.70103345341, 6657.435502656968, 6690.83272918181, 6706.966465167643, 6695.899561506484, 6698.263191049078, 6743.089682892943, 6744.0, 6753.088333074972], "unit": "score", "name": "mootools.html", "value": 4565.878028818477}, {"lowerIsBetter": false, "replicates": [353.7594435783667, 355.0909671241623, 356.09196548800554, 357.8461261657486, 361.5426485495847, 5639.125935479997, 5818.185454036438, 5873.295204575452, 5894.675792831398, 5899.380565040673, 5633.661980281176, 5738.56960727946, 5743.138529220609, 5801.854953626162, 5831.825045248639, 4547.0, 4547.24970379887, 4574.908501829961, 4585.770711464422, 4586.3579098926175, 4384.956150438503, 4493.910121797562, 4584.129015487063, 4589.426321709786, 4594.816207351702, 4428.623566996809, 4480.327950807372, 4501.707389019703, 4501.932471012921, 4517.616002639763, 4480.507144214133, 4492.3710680504755, 4493.7977790999485, 4499.707519011254, 4505.211587972124, 4003.939940900889, 4009.639132478091, 4014.7791871447057, 4019.9950012496874, 4034.576369481221, 5484.314460692413, 5518.641288316247, 5525.364583072957, 5533.944660553383, 5580.665160090408, 5273.367195936474, 5274.736263186825, 5277.261183434322, 5282.973585132088, 5286.629935904488, 6143.784967526115, 6151.784687535959, 6153.538484613627, 6155.969220153916, 6156.815295541141, 4244.3209086546, 4304.526502084768, 4330.0, 4351.369051487548, 4482.9551704482865, 6390.488760899117, 6431.678416079224, 6439.93560064398, 6440.4203621673805, 6475.449586785082, 4515.412996310524, 4523.0, 4524.683272170916, 4525.796339164745, 4529.909401812011, 4517.616002639812, 4580.908381832344, 4582.335561343587, 4582.770861456914, 4586.289125185602, 5214.661047031931, 5272.156454967187, 5272.92090618641, 5279.1289437243195, 5281.102212623863, 6199.0, 6216.720247588869, 6226.62640241587, 6290.528210384239, 6294.842628934313, 1632.314427940268, 1651.1909164509304, 1652.6777278430832, 1653.3469279634478, 1654.3713388912136, 1081.3403823667556, 1099.9890001100046, 1107.7064577886867, 1110.2394859521253, 1127.7406196574834, 5881.941180588225, 5884.6469211847425, 5886.293644762656, 5886.646801191942, 5897.498712609477, 6340.365963403623, 6363.395477429637, 6368.904466433008, 6378.585391949508, 6381.936180638134, 5836.0, 5838.065909454467, 5857.7949771757785, 5863.413658634103, 5878.911816322759, 3335.866565337408, 3432.2105915639045, 3433.5021421894185, 3433.53647257622, 3436.8281585920604, 6662.233843108104, 6689.464842812563, 6710.261871194165, 6713.1272934519175, 6724.125863637696, 6527.184101987252, 6576.934230657728, 6581.40767330933, 6593.307702691244, 6596.142501474874, 6549.312322206195, 6683.766068187688, 6692.196936367667, 6697.7655782047395, 6707.4969377297875, 6404.231492220777, 6411.455026322815, 6418.550701450854, 6424.710888009958, 6428.260750013808, 6116.847078823065, 6142.324344322032, 6273.059041143682, 6352.237731472068, 6370.140031095742, 6688.866222675617, 6690.364415380531, 6695.0626912231355, 6705.597664140168, 6721.462283017445, 4411.22803509391, 4438.755868427267, 4441.644668426454, 4441.71128876622, 4445.222086134849, 6287.088372185916, 6449.193850768654, 6450.580712253689, 6454.741810327534, 6456.451201647913, 6290.0, 6296.370362963667, 6302.810915672537, 6304.369563043659, 6308.747650093945, 6278.780242691484, 6279.152314437492, 6283.654399008098, 6285.277193122849, 6285.465735412541, 6061.454469097803, 6174.66039367839, 6181.258249010148, 6182.814515564541, 6187.226596675416, 6306.400891915261, 6309.779157729641, 6315.621062736251, 6318.210223722035, 6324.17785687876, 6136.417040381157, 6159.106929495288, 6161.815145545641, 6168.136460895388, 6170.50635949114], "unit": "score", "name": "prototype.html", "value": 4731.369236249599}, {"lowerIsBetter": false, "replicates": [752.5484709174494, 846.9026062002868, 848.4716632220166, 852.9573521323932, 864.3646919514152, 11886.702832429193, 12111.454984525695, 12164.93917530411, 12181.878181218186, 12191.08566857487, 10449.529771160296, 11206.103511719064, 11274.210805243636, 11277.37974411407, 11284.210105292612, 648.32897950621, 784.4900814470583, 800.1078797141204, 800.1238643685156, 800.3237264511507, 787.2049230277432, 787.5747096567846, 787.6613056385752, 787.9763607091796, 790.1624278265028, 820.2125959079261, 822.5229366967145, 824.9917500824974, 825.793551612097, 825.8430898129366, 820.8440396324679, 821.338822248093, 822.4571782623469, 822.6544851162527, 824.5094168969454, 500.32955839175315, 505.0983993571494, 610.2585358789048, 612.5099920063975, 624.6782906802955, 12062.939685301517, 12063.336516491589, 12073.0, 12080.335581543099, 12081.93959030208, 12013.639590812203, 12352.62942111738, 12462.881310177292, 12633.49466021358, 12719.682007949783, 10042.4476653784, 10108.191344692481, 10271.383716977005, 10278.0, 10457.163426925828, 3418.453047512386, 3489.6956625928447, 3492.2142517933517, 3502.702270307027, 3590.461430785377, 47508.11132385785, 47589.0, 47772.0, 47778.52221477775, 47886.521134788556, 9634.71095867125, 9844.901550984541, 10034.0, 10174.473828925573, 10308.072273495422, 18461.7230741539, 18464.446066618024, 18516.814831851778, 18542.81457185438, 18569.350072747387, 6848.315168483112, 7053.188883278386, 7071.222165561784, 7080.0, 7150.928490715026, 3912.0, 3924.5682974872743, 3942.0539070623418, 3957.52509698838, 4020.618041286073, 2932.2669332666833, 2936.119164250716, 2954.7931644784658, 2956.9704302957125, 2965.303153758865, 537.40348213483, 559.4265877475539, 563.788579282769, 563.8815848671825, 567.1464446008813, 448.2491826191117, 452.615277014535, 459.0612198054986, 472.40005193404187, 570.0736303506801, 8152.836943261102, 8274.503529788231, 8341.95829020851, 8342.87485687703, 8476.27951624119, 507.43927959604844, 509.17005281391096, 517.8731210853365, 610.1579819848669, 626.614632001325, 3845.576986531479, 3971.702122340836, 3973.8609148679657, 3987.8205480752854, 4075.938860917029, 54530.45469545333, 55375.0, 55475.16787248194, 55591.44408555863, 55886.0, 49185.754071229414, 55929.44070559324, 56211.0, 57138.7143064282, 57153.0, 44856.3271550927, 45819.54180458219, 46171.76914115408, 46253.76873115613, 46884.0, 8285.95857020711, 8304.899311088931, 8442.240198382175, 8528.189821966904, 8570.700025499078, 33376.83311583524, 33392.833035834665, 33427.665723342456, 33661.32677346488, 33714.0, 40525.79737101414, 40710.389344159266, 41110.1777964445, 44431.0, 44612.10775784531, 10086.394816311045, 10373.0, 10423.114035307084, 10462.63380781669, 10486.213533984828, 11701.648950531498, 11717.589884354007, 11788.233764805604, 11818.172727909308, 11854.881451185724, 10597.41714205726, 10636.627718029831, 10638.255322127687, 10680.732981675521, 10966.067884229611, 10984.560617575207, 11009.339439633648, 11175.21773475849, 11298.322100673986, 11606.767864642828, 7927.663905657777, 9752.317337786288, 11347.20569560123, 11358.829617555908, 11804.055675546108, 8469.322454203526, 8798.780030499289, 8849.20357167858, 8950.14973577509, 9172.312076594415, 8190.344772418312, 9027.909720902708, 9307.11582399671, 9530.95234523823, 9532.332736708364], "unit": "score", "name": "yui.html", "value": 6002.195186985333}], "name": "dromaeo_css", "value": 6735.035386684398}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 111.0, 106.0, 109.0, 109.0, 109.0, 110.0, 109.0, 103.0, 110.0], "unit": "score", "name": "ai-astar", "value": 108.8}, {"lowerIsBetter": true, "replicates": [167.0, 125.0, 164.0, 165.0, 127.0, 150.0, 163.0, 127.0, 173.0, 125.0], "unit": "score", "name": "audio-beat-detection", "value": 148.6}, {"lowerIsBetter": true, "replicates": [160.0, 161.0, 161.0, 159.0, 161.0, 160.0, 159.0, 159.0, 162.0, 161.0], "unit": "score", "name": "audio-dft", "value": 160.3}, {"lowerIsBetter": true, "replicates": [73.0, 74.0, 109.0, 98.0, 73.0, 75.0, 75.0, 115.0, 97.0, 76.0], "unit": "score", "name": "audio-fft", "value": 86.5}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 147.0, 97.0, 96.0, 95.0, 144.0, 96.0, 97.0, 136.0], "unit": "score", "name": "audio-oscillator", "value": 110.1}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [146.0, 147.0, 147.0, 147.0, 147.0, 145.0, 146.0, 146.0, 145.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 146.2}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.7}, {"lowerIsBetter": true, "replicates": [56.0, 55.0, 55.0, 54.0, 55.0, 56.0, 55.0, 55.0, 56.0, 54.0], "unit": "score", "name": "json-parse-financial", "value": 55.1}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 68.0, 67.0, 67.0, 68.0, 67.0, 68.0, 68.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.7}, {"lowerIsBetter": true, "replicates": [81.0, 82.0, 82.0, 82.0, 85.0, 83.0, 81.0, 83.0, 83.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.5}, {"lowerIsBetter": true, "replicates": [117.0, 115.0, 116.0, 113.0, 112.0, 116.0, 123.0, 121.0, 118.0, 117.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 116.8}, {"lowerIsBetter": true, "replicates": [147.0, 138.0, 142.0, 143.0, 144.0, 133.0, 145.0, 141.0, 142.0, 144.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 141.9}, {"lowerIsBetter": true, "replicates": [65.0, 68.0, 67.0, 66.0, 66.0, 65.0, 67.0, 67.0, 66.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.3}], "name": "kraken", "value": 1495.8}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.02856188408217], "unit": "score", "name": "Richards", "value": 211.35307400000002}, {"lowerIsBetter": false, "replicates": [191.46084625694044], "unit": "score", "name": "DeltaBlue", "value": 345.33431399999995}, {"lowerIsBetter": false, "replicates": [279.9552071668533], "unit": "score", "name": "Encrypt", "value": 950.7985320000004}, {"lowerIsBetter": false, "replicates": [5175.257731958763], "unit": "score", "name": "Decrypt", "value": 51.433380478087685}, {"lowerIsBetter": false, "replicates": [1955.078125], "unit": "score", "name": "RayTrace", "value": 378.49587212787225}, {"lowerIsBetter": false, "replicates": [794.4444444444445], "unit": "score", "name": "Earley", "value": 838.9044755244754}, {"lowerIsBetter": false, "replicates": [12370.37037037037], "unit": "score", "name": "Boyer", "value": 53.87575149700595}, {"lowerIsBetter": false, "replicates": [42583.333333333336], "unit": "score", "name": "RegExp", "value": 21.392994129158513}, {"lowerIsBetter": false, "replicates": [586.5102639296188], "unit": "score", "name": "Splay", "value": 138.94215499999996}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 18924.75327700069}]} 22:21:32 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,35]]", "result": "18924.75"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,35]]", "result": "1495.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,35]]", "result": "6735.04"}}} 22:21:32 INFO - Return code: 0 22:21:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 22:21:32 INFO - # TBPL SUCCESS # 22:21:32 INFO - Running post-action listener: _resource_record_post_action 22:21:32 INFO - Running post-run listener: _resource_record_post_run 22:21:33 INFO - Total resource usage - Wall time: 1231s; CPU: 13.0%; Read bytes: 0; Write bytes: 549838848; Read time: 0; Write time: 491424 22:21:33 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 236290048; Read time: 0; Write time: 252472 22:21:33 INFO - run-tests - Wall time: 1215s; CPU: 13.0%; Read bytes: 0; Write bytes: 313548800; Read time: 0; Write time: 238952 22:21:33 INFO - Running post-run listener: _upload_blobber_files 22:21:33 INFO - Blob upload gear active. 22:21:33 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 22:21:33 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 22:21:33 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'] 22:21:33 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 22:21:34 INFO - (blobuploader) - INFO - Open directory for files ... 22:21:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:21:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:21:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:34 INFO - (blobuploader) - INFO - Done attempting. 22:21:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 22:21:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:21:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:35 INFO - (blobuploader) - INFO - Done attempting. 22:21:35 INFO - (blobuploader) - INFO - Iteration through files over. 22:21:35 INFO - Return code: 0 22:21:35 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 22:21:35 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 22:21:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/586aa4ef798178101ee47d4fa793a59deb665daa7fc0914af383955a8ba0c1481719996a5e314476ac42287bae3d5ed63170fd253a875641d406525415cf4729", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c7614bbfb1794790ae09f157640af6c66fb2fd6665deda4a04a508d3c037e68ce31ba6cc5036c19721961b779bdba1d3eaba2cc65f2837ddd45a9f5cff16919"} 22:21:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 22:21:35 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 22:21:35 INFO - Contents: 22:21:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/586aa4ef798178101ee47d4fa793a59deb665daa7fc0914af383955a8ba0c1481719996a5e314476ac42287bae3d5ed63170fd253a875641d406525415cf4729", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c7614bbfb1794790ae09f157640af6c66fb2fd6665deda4a04a508d3c037e68ce31ba6cc5036c19721961b779bdba1d3eaba2cc65f2837ddd45a9f5cff16919"} 22:21:35 INFO - Running post-run listener: copy_logs_to_upload_dir 22:21:35 INFO - Copying logs to upload dir... 22:21:35 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 22:21:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.459730 ========= master_lag: 1.51 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-01-22 22:21:36.966990) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-22 22:21:36.973701) ========= 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=b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085 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/586aa4ef798178101ee47d4fa793a59deb665daa7fc0914af383955a8ba0c1481719996a5e314476ac42287bae3d5ed63170fd253a875641d406525415cf4729", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c7614bbfb1794790ae09f157640af6c66fb2fd6665deda4a04a508d3c037e68ce31ba6cc5036c19721961b779bdba1d3eaba2cc65f2837ddd45a9f5cff16919"} build_url:https://queue.taskcluster.net/v1/task/FJdtlG-vSTaaiACdrffsgA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010449 build_url: 'https://queue.taskcluster.net/v1/task/FJdtlG-vSTaaiACdrffsgA/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/586aa4ef798178101ee47d4fa793a59deb665daa7fc0914af383955a8ba0c1481719996a5e314476ac42287bae3d5ed63170fd253a875641d406525415cf4729", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c7614bbfb1794790ae09f157640af6c66fb2fd6665deda4a04a508d3c037e68ce31ba6cc5036c19721961b779bdba1d3eaba2cc65f2837ddd45a9f5cff16919"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-22 22:21:37.002818) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 22:21:37.003270) ========= 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=b5f16d05e5ea93ddf675e67e000001bf-1453526995.618581-2007025085 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004644 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 22:21:37.053686) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-22 22:21:37.054104) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-22 22:21:37.057514) ========= ========= Total master_lag: 1.81 =========