builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-012 starttime: 1448371294.85 results: success (0) buildid: 20151124030138 builduid: b56bb530e8484b2a98a3b37474e7220a revision: c216ff19d690 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:34.848008) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:34.851885) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:34.852532) ========= 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=928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006497 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.07 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:34.928547) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:34.929081) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:35.072977) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:35.073560) ========= 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=928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.032418 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:35.133527) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:35.134078) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:35.137790) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:35.138243) ========= 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=928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-24 05:21:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.2M=0s 2015-11-24 05:21:35 (43.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.187735 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:35.345971) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:35.346554) ========= 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=928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.055452 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:35.414707) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-24 05:21:35.415298) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c216ff19d690 --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 c216ff19d690 --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=928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-24 05:21:35,477 Setting DEBUG logging. 2015-11-24 05:21:35,477 attempt 1/10 2015-11-24 05:21:35,477 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/c216ff19d690?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-24 05:21:36,600 unpacking tar archive at: mozilla-inbound-c216ff19d690/testing/mozharness/ program finished with exit code 0 elapsedTime=1.427701 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-24 05:21:36.859730) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:36.860329) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:36.871073) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:36.871528) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 05:21:36.878264) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2015-11-24 05:21:36.878698) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --system-bits 64 --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', '--system-bits', '64', '--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=928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:21:37 INFO - MultiFileLogger online at 20151124 05:21:37 in /builds/slave/test-pgo 05:21:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 05:21:37 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:21:37 INFO - {'append_to_log': False, 05:21:37 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:21:37 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 05:21:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:21:37 INFO - 'branch': 'Mozilla-Inbound', 05:21:37 INFO - 'buildbot_json_path': 'buildprops.json', 05:21:37 INFO - 'config_files': ('talos/linux_config.py',), 05:21:37 INFO - 'default_actions': ('clobber', 05:21:37 INFO - 'read-buildbot-config', 05:21:37 INFO - 'download-and-extract', 05:21:37 INFO - 'populate-webroot', 05:21:37 INFO - 'create-virtualenv', 05:21:37 INFO - 'install', 05:21:37 INFO - 'run-tests'), 05:21:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:21:37 INFO - 'download_minidump_stackwalk': True, 05:21:37 INFO - 'download_symbols': 'ondemand', 05:21:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:21:37 INFO - 'tooltool.py': '/tools/tooltool.py', 05:21:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:21:37 INFO - '/tools/misc-python/virtualenv.py')}, 05:21:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:21:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:21:37 INFO - 'installer_path': 'installer.exe', 05:21:37 INFO - 'log_level': 'info', 05:21:37 INFO - 'log_name': 'talos', 05:21:37 INFO - 'log_to_console': True, 05:21:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:21:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:21:37 INFO - 'opt_config_files': (), 05:21:37 INFO - 'pip_index': False, 05:21:37 INFO - 'sps_profile': False, 05:21:37 INFO - 'sps_profile_interval': 0, 05:21:37 INFO - 'suite': 'dromaeojs', 05:21:37 INFO - 'system_bits': '64', 05:21:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:21:37 INFO - 'title': 'talos-linux64-ix-012', 05:21:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:21:37 INFO - 'use_talos_json': True, 05:21:37 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:21:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:21:37 INFO - 'work_dir': 'build'} 05:21:37 INFO - ##### 05:21:37 INFO - ##### Running clobber step. 05:21:37 INFO - ##### 05:21:37 INFO - Running pre-action listener: _resource_record_pre_action 05:21:37 INFO - Running main action method: clobber 05:21:37 INFO - rmtree: /builds/slave/test-pgo/build 05:21:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:21:41 INFO - Running post-action listener: _resource_record_post_action 05:21:41 INFO - ##### 05:21:41 INFO - ##### Running read-buildbot-config step. 05:21:41 INFO - ##### 05:21:41 INFO - Running pre-action listener: _resource_record_pre_action 05:21:41 INFO - Running main action method: read_buildbot_config 05:21:41 INFO - Using buildbot properties: 05:21:41 INFO - { 05:21:41 INFO - "properties": { 05:21:41 INFO - "buildnumber": 477, 05:21:41 INFO - "product": "firefox", 05:21:41 INFO - "script_repo_revision": "production", 05:21:41 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 05:21:41 INFO - "repository": "", 05:21:41 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 05:21:41 INFO - "buildid": "20151124030138", 05:21:41 INFO - "slavename": "talos-linux64-ix-012", 05:21:41 INFO - "pgo_build": "True", 05:21:41 INFO - "basedir": "/builds/slave/test-pgo", 05:21:41 INFO - "project": "", 05:21:41 INFO - "platform": "ubuntu64_hw", 05:21:41 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:21:41 INFO - "slavebuilddir": "test", 05:21:41 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 05:21:41 INFO - "branch": "mozilla-inbound", 05:21:41 INFO - "repo_path": "integration/mozilla-inbound", 05:21:41 INFO - "stage_platform": "linux64-pgo", 05:21:41 INFO - "builduid": "b56bb530e8484b2a98a3b37474e7220a", 05:21:41 INFO - "revision": "c216ff19d690" 05:21:41 INFO - }, 05:21:41 INFO - "sourcestamp": { 05:21:41 INFO - "repository": "", 05:21:41 INFO - "hasPatch": false, 05:21:41 INFO - "project": "", 05:21:41 INFO - "branch": "mozilla-inbound-linux64-pgo-talos", 05:21:41 INFO - "changes": [ 05:21:41 INFO - { 05:21:41 INFO - "category": null, 05:21:41 INFO - "files": [ 05:21:41 INFO - { 05:21:41 INFO - "url": null, 05:21:41 INFO - "name": "https://queue.taskcluster.net/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 05:21:41 INFO - } 05:21:41 INFO - ], 05:21:41 INFO - "repository": "", 05:21:41 INFO - "rev": "c216ff19d690", 05:21:41 INFO - "who": "sendchange", 05:21:41 INFO - "when": 1448371237, 05:21:41 INFO - "number": 6700093, 05:21:41 INFO - "comments": "", 05:21:41 INFO - "project": "", 05:21:41 INFO - "at": "Tue 24 Nov 2015 05:20:37", 05:21:41 INFO - "branch": "mozilla-inbound-linux64-pgo-talos", 05:21:41 INFO - "revlink": "", 05:21:41 INFO - "properties": [ 05:21:41 INFO - [ 05:21:41 INFO - "buildid", 05:21:41 INFO - "20151124030138", 05:21:41 INFO - "Change" 05:21:41 INFO - ], 05:21:41 INFO - [ 05:21:41 INFO - "builduid", 05:21:41 INFO - "b56bb530e8484b2a98a3b37474e7220a", 05:21:41 INFO - "Change" 05:21:41 INFO - ], 05:21:41 INFO - [ 05:21:41 INFO - "pgo_build", 05:21:41 INFO - "True", 05:21:41 INFO - "Change" 05:21:41 INFO - ] 05:21:41 INFO - ], 05:21:41 INFO - "revision": "c216ff19d690" 05:21:41 INFO - } 05:21:41 INFO - ], 05:21:41 INFO - "revision": "c216ff19d690" 05:21:41 INFO - } 05:21:41 INFO - } 05:21:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 05:21:41 INFO - Running post-action listener: _resource_record_post_action 05:21:41 INFO - ##### 05:21:41 INFO - ##### Running download-and-extract step. 05:21:41 INFO - ##### 05:21:41 INFO - Running pre-action listener: _resource_record_pre_action 05:21:41 INFO - Running main action method: download_and_extract 05:21:41 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:21:41 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')]} 05:21:41 INFO - https://queue.taskcluster.net/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:21:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/test_packages.json 05:21:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/test_packages.json 05:21:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:21:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:21:43 INFO - Downloaded 1302 bytes. 05:21:43 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:21:43 INFO - Using the following test package requirements: 05:21:43 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:21:43 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:21:43 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:43 INFO - u'jsshell-linux-x86_64.zip'], 05:21:43 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:21:43 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:21:43 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:21:43 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:21:43 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:21:43 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:21:43 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:21:43 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:21: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')]} 05:21:43 INFO - https://queue.taskcluster.net/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:21:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:21:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:21:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:21:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:21:50 INFO - Downloaded 22529409 bytes. 05:21:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:21:50 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:21:50 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:21:51 INFO - Return code: 0 05:21:51 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:21: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')]} 05:21:51 INFO - https://queue.taskcluster.net/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:21:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:21:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:21:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:21:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:21:59 INFO - Downloaded 22529409 bytes. 05:21:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:21:59 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:21:59 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:22:01 INFO - Return code: 0 05:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:01 INFO - https://queue.taskcluster.net/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:22:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:22:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:22:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:22:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:22:02 INFO - Downloaded 11131717 bytes. 05:22:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 05:22:02 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:22:02 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 05:22:02 INFO - Return code: 0 05:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:02 INFO - https://queue.taskcluster.net/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:22:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:22:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:22:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:22:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:22:03 INFO - Downloaded 58354898 bytes. 05:22:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:22:03 INFO - mkdir: /builds/slave/test-pgo/properties 05:22:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:22:03 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:22:03 INFO - Contents: 05:22:03 INFO - build_url:https://queue.taskcluster.net/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:22:03 INFO - Running post-action listener: _resource_record_post_action 05:22:03 INFO - Running post-action listener: set_extra_try_arguments 05:22:03 INFO - ##### 05:22:03 INFO - ##### Running populate-webroot step. 05:22:03 INFO - ##### 05:22:03 INFO - Running pre-action listener: _resource_record_pre_action 05:22:03 INFO - Running main action method: populate_webroot 05:22:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:22:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:22:03 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 05:22:03 INFO - '../fennec_ids.txt'], 05:22:03 INFO - 'tests': ['tcheck2']}, 05:22:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:22:03 INFO - '--tppagecycles', 05:22:03 INFO - '7'], 05:22:03 INFO - 'tests': ['tsvgm']}}, 05:22:03 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:22:03 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:22:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:22:03 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:22:03 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:22:03 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:22:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:22:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:03 INFO - 'talos_options': ['--e10s'], 05:22:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:22:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:03 INFO - 'tests': ['damp', 'tps']}, 05:22:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:03 INFO - 'talos_options': ['--e10s'], 05:22:03 INFO - 'tests': ['damp', 'tps']}, 05:22:03 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:22:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:22:03 INFO - 'other': {'tests': ['a11yr', 05:22:03 INFO - 'ts_paint', 05:22:03 INFO - 'tpaint', 05:22:03 INFO - 'sessionrestore', 05:22:03 INFO - 'sessionrestore_no_auto_restore']}, 05:22:03 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:22:03 INFO - 'tests': ['a11yr', 05:22:03 INFO - 'ts_paint', 05:22:03 INFO - 'tpaint', 05:22:03 INFO - 'sessionrestore', 05:22:03 INFO - 'sessionrestore_no_auto_restore']}, 05:22:03 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:22:03 INFO - 'tests': ['a11yr', 05:22:03 INFO - 'ts_paint', 05:22:03 INFO - 'tpaint', 05:22:03 INFO - 'sessionrestore', 05:22:03 INFO - 'sessionrestore_no_auto_restore']}, 05:22:03 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:22:03 INFO - 'tests': ['a11yr', 05:22:03 INFO - 'ts_paint', 05:22:03 INFO - 'tpaint', 05:22:03 INFO - 'sessionrestore', 05:22:03 INFO - 'sessionrestore_no_auto_restore']}, 05:22:03 INFO - 'other_l64': {'tests': ['a11yr', 05:22:03 INFO - 'ts_paint', 05:22:03 INFO - 'tpaint', 05:22:03 INFO - 'sessionrestore', 05:22:03 INFO - 'sessionrestore_no_auto_restore']}, 05:22:03 INFO - 'other_nol64': {'tests': ['a11yr', 05:22:03 INFO - 'ts_paint', 05:22:03 INFO - 'tpaint', 05:22:03 INFO - 'sessionrestore', 05:22:03 INFO - 'sessionrestore_no_auto_restore']}, 05:22:03 INFO - 'svgr': {'tests': ['tsvgx', 05:22:03 INFO - 'tsvgr_opacity', 05:22:03 INFO - 'tart', 05:22:03 INFO - 'tscrollx', 05:22:03 INFO - 'cart']}, 05:22:03 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:22:03 INFO - 'tests': ['tsvgx', 05:22:03 INFO - 'tsvgr_opacity', 05:22:03 INFO - 'tart', 05:22:03 INFO - 'tscrollx', 05:22:03 INFO - 'cart']}, 05:22:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:03 INFO - 'tests': ['tp5o']}, 05:22:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:03 INFO - 'talos_options': ['--e10s'], 05:22:03 INFO - 'tests': ['tp5o']}, 05:22:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:03 INFO - 'talos_options': ['--xperf_path', 05:22:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:22:03 INFO - 'tests': ['tp5n']}, 05:22:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:03 INFO - 'talos_options': ['--e10s', 05:22:03 INFO - '--xperf_path', 05:22:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:22:03 INFO - 'tests': ['tp5n']}}, 05:22:03 INFO - 'talos.zip': {'path': '', 05:22:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:22:03 INFO - Running post-action listener: _resource_record_post_action 05:22:03 INFO - ##### 05:22:03 INFO - ##### Running create-virtualenv step. 05:22:03 INFO - ##### 05:22:03 INFO - Running pre-action listener: _resource_record_pre_action 05:22:03 INFO - Running main action method: create_virtualenv 05:22:03 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:22:03 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 05:22:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:22:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:22:03 INFO - Using real prefix '/usr' 05:22:03 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:22:04 INFO - Installing distribute.............................................................................................................................................................................................done. 05:22:06 INFO - Installing pip.................done. 05:22:06 INFO - Return code: 0 05:22:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:22:06 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')]} 05:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:06 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')]} 05:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:06 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252e50>, '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 0x1253d50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12541e0>, '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': '928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226', '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 05:22:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 05:22:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:22:06 INFO - Using env: {'DISPLAY': ':0', 05:22:06 INFO - 'HOME': '/home/cltbld', 05:22:06 INFO - 'LANG': 'en_US.UTF-8', 05:22:06 INFO - 'LANGUAGE': 'en_US:en', 05:22:06 INFO - 'LOGNAME': 'cltbld', 05:22:06 INFO - 'MAIL': '/var/mail/cltbld', 05:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:06 INFO - 'MOZ_NO_REMOTE': '1', 05:22:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:06 INFO - 'NO_EM_RESTART': '1', 05:22:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:22:06 INFO - 'PWD': '/builds/slave/test-pgo', 05:22:06 INFO - 'SHELL': '/bin/bash', 05:22:06 INFO - 'SHLVL': '1', 05:22:06 INFO - 'TERM': 'linux', 05:22:06 INFO - 'TMOUT': '86400', 05:22:06 INFO - 'USER': 'cltbld', 05:22:06 INFO - 'XDG_SESSION_COOKIE': '928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226', 05:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:06 INFO - '_': '/tools/buildbot/bin/python'} 05:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:06 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 05:22:06 INFO - Cleaning up... 05:22:06 INFO - Return code: 0 05:22:06 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:22:06 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')]} 05:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:06 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')]} 05:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:06 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252e50>, '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 0x1253d50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12541e0>, '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': '928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226', '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 05:22:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 05:22:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:22:06 INFO - Using env: {'DISPLAY': ':0', 05:22:06 INFO - 'HOME': '/home/cltbld', 05:22:06 INFO - 'LANG': 'en_US.UTF-8', 05:22:06 INFO - 'LANGUAGE': 'en_US:en', 05:22:06 INFO - 'LOGNAME': 'cltbld', 05:22:06 INFO - 'MAIL': '/var/mail/cltbld', 05:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:06 INFO - 'MOZ_NO_REMOTE': '1', 05:22:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:06 INFO - 'NO_EM_RESTART': '1', 05:22:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:22:06 INFO - 'PWD': '/builds/slave/test-pgo', 05:22:06 INFO - 'SHELL': '/bin/bash', 05:22:06 INFO - 'SHLVL': '1', 05:22:06 INFO - 'TERM': 'linux', 05:22:06 INFO - 'TMOUT': '86400', 05:22:06 INFO - 'USER': 'cltbld', 05:22:06 INFO - 'XDG_SESSION_COOKIE': '928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226', 05:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:06 INFO - '_': '/tools/buildbot/bin/python'} 05:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:06 INFO - Downloading/unpacking psutil>=0.7.1 05:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:08 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:22:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:22:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:22:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:22:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:22:08 INFO - Installing collected packages: psutil 05:22:08 INFO - Running setup.py install for psutil 05:22:08 INFO - building 'psutil._psutil_linux' extension 05:22:08 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 05:22:08 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 05:22:08 INFO - building 'psutil._psutil_posix' extension 05:22:08 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 05:22:09 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 05:22:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:22:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:22:09 INFO - Successfully installed psutil 05:22:09 INFO - Cleaning up... 05:22:09 INFO - Return code: 0 05:22:09 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252e50>, '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 0x1253d50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12541e0>, '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': '928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226', '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 05:22:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 05:22:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:22:09 INFO - Using env: {'DISPLAY': ':0', 05:22:09 INFO - 'HOME': '/home/cltbld', 05:22:09 INFO - 'LANG': 'en_US.UTF-8', 05:22:09 INFO - 'LANGUAGE': 'en_US:en', 05:22:09 INFO - 'LOGNAME': 'cltbld', 05:22:09 INFO - 'MAIL': '/var/mail/cltbld', 05:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:09 INFO - 'MOZ_NO_REMOTE': '1', 05:22:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:09 INFO - 'NO_EM_RESTART': '1', 05:22:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:22:09 INFO - 'PWD': '/builds/slave/test-pgo', 05:22:09 INFO - 'SHELL': '/bin/bash', 05:22:09 INFO - 'SHLVL': '1', 05:22:09 INFO - 'TERM': 'linux', 05:22:09 INFO - 'TMOUT': '86400', 05:22:09 INFO - 'USER': 'cltbld', 05:22:09 INFO - 'XDG_SESSION_COOKIE': '928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226', 05:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:09 INFO - '_': '/tools/buildbot/bin/python'} 05:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:09 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:11 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:22:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:22:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:22:11 INFO - Installing collected packages: mozsystemmonitor 05:22:11 INFO - Running setup.py install for mozsystemmonitor 05:22:11 INFO - Successfully installed mozsystemmonitor 05:22:11 INFO - Cleaning up... 05:22:11 INFO - Return code: 0 05:22:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:11 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252e50>, '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 0x1253d50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12541e0>, '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': '928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226', '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 05:22:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 05:22:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:22:11 INFO - Using env: {'DISPLAY': ':0', 05:22:11 INFO - 'HOME': '/home/cltbld', 05:22:11 INFO - 'LANG': 'en_US.UTF-8', 05:22:11 INFO - 'LANGUAGE': 'en_US:en', 05:22:11 INFO - 'LOGNAME': 'cltbld', 05:22:11 INFO - 'MAIL': '/var/mail/cltbld', 05:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:11 INFO - 'MOZ_NO_REMOTE': '1', 05:22:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:11 INFO - 'NO_EM_RESTART': '1', 05:22:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:22:11 INFO - 'PWD': '/builds/slave/test-pgo', 05:22:11 INFO - 'SHELL': '/bin/bash', 05:22:11 INFO - 'SHLVL': '1', 05:22:11 INFO - 'TERM': 'linux', 05:22:11 INFO - 'TMOUT': '86400', 05:22:11 INFO - 'USER': 'cltbld', 05:22:11 INFO - 'XDG_SESSION_COOKIE': '928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226', 05:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:11 INFO - '_': '/tools/buildbot/bin/python'} 05:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:11 INFO - Downloading/unpacking blobuploader==1.2.4 05:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:13 INFO - Downloading blobuploader-1.2.4.tar.gz 05:22:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:22:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:22:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:22:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:22:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:14 INFO - Downloading docopt-0.6.1.tar.gz 05:22:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:22:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:22:14 INFO - Installing collected packages: blobuploader, requests, docopt 05:22:14 INFO - Running setup.py install for blobuploader 05:22:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:22:14 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:22:14 INFO - Running setup.py install for requests 05:22:14 INFO - Running setup.py install for docopt 05:22:14 INFO - Successfully installed blobuploader requests docopt 05:22:14 INFO - Cleaning up... 05:22:14 INFO - Return code: 0 05:22:14 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:14 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252e50>, '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 0x1253d50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12541e0>, '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': '928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226', '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 05:22:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 05:22:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:22:14 INFO - Using env: {'DISPLAY': ':0', 05:22:14 INFO - 'HOME': '/home/cltbld', 05:22:14 INFO - 'LANG': 'en_US.UTF-8', 05:22:14 INFO - 'LANGUAGE': 'en_US:en', 05:22:14 INFO - 'LOGNAME': 'cltbld', 05:22:14 INFO - 'MAIL': '/var/mail/cltbld', 05:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:14 INFO - 'MOZ_NO_REMOTE': '1', 05:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:14 INFO - 'NO_EM_RESTART': '1', 05:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:22:14 INFO - 'PWD': '/builds/slave/test-pgo', 05:22:14 INFO - 'SHELL': '/bin/bash', 05:22:14 INFO - 'SHLVL': '1', 05:22:14 INFO - 'TERM': 'linux', 05:22:14 INFO - 'TMOUT': '86400', 05:22:14 INFO - 'USER': 'cltbld', 05:22:14 INFO - 'XDG_SESSION_COOKIE': '928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226', 05:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:14 INFO - '_': '/tools/buildbot/bin/python'} 05:22:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:22:15 INFO - Running setup.py (path:/tmp/pip-scxXf3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:22:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:22:15 INFO - Running setup.py (path:/tmp/pip-O2PoG8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:22:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:22:15 INFO - Running setup.py (path:/tmp/pip-ybiIdu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:22:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:22:15 INFO - Running setup.py (path:/tmp/pip-qyUfIx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:22:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:22:15 INFO - Running setup.py (path:/tmp/pip-wVV8hp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:22:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:22:15 INFO - Running setup.py (path:/tmp/pip-tKi_Z8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:22:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:22:15 INFO - Running setup.py (path:/tmp/pip-i1R37G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:22:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:22:15 INFO - Running setup.py (path:/tmp/pip-NFTFRH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:22:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:22:15 INFO - Running setup.py (path:/tmp/pip-sPq6kE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:22:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:22:15 INFO - Running setup.py (path:/tmp/pip-NIGIxW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:22:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:22:15 INFO - Running setup.py (path:/tmp/pip-d9TIYX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:22:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:22:15 INFO - Running setup.py (path:/tmp/pip-EySBa1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:22:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:22:15 INFO - Running setup.py (path:/tmp/pip-vMEENF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:22:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:22:15 INFO - Running setup.py (path:/tmp/pip-SIQHeu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:22:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:22:16 INFO - Running setup.py (path:/tmp/pip-PFUo4n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:22:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:22:16 INFO - Running setup.py (path:/tmp/pip-qVtRSk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:22:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:22:16 INFO - Running setup.py (path:/tmp/pip-bMbGli-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:22:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:22:16 INFO - Running setup.py install for manifestparser 05:22:16 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:22:16 INFO - Running setup.py install for mozcrash 05:22:16 INFO - Running setup.py install for mozdebug 05:22:16 INFO - Running setup.py install for mozdevice 05:22:16 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:22:16 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:22:16 INFO - Running setup.py install for mozfile 05:22:16 INFO - Running setup.py install for mozhttpd 05:22:16 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:22:16 INFO - Running setup.py install for mozinfo 05:22:16 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:22:16 INFO - Running setup.py install for mozInstall 05:22:17 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:22:17 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:22:17 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:22:17 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:22:17 INFO - Running setup.py install for mozleak 05:22:17 INFO - Running setup.py install for mozlog 05:22:17 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:22:17 INFO - Running setup.py install for moznetwork 05:22:17 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:22:17 INFO - Running setup.py install for mozprocess 05:22:17 INFO - Running setup.py install for mozprofile 05:22:17 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:22:17 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:22:17 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:22:17 INFO - Running setup.py install for mozrunner 05:22:17 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:22:17 INFO - Running setup.py install for mozscreenshot 05:22:17 INFO - Running setup.py install for moztest 05:22:17 INFO - Running setup.py install for mozversion 05:22:18 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:22:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:22:18 INFO - Cleaning up... 05:22:18 INFO - Return code: 0 05:22:18 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:22:18 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')]} 05:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:18 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')]} 05:22:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:18 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252e50>, '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 0x1253d50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12541e0>, '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': '928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226', '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 05:22:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 05:22:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:22:18 INFO - Using env: {'DISPLAY': ':0', 05:22:18 INFO - 'HOME': '/home/cltbld', 05:22:18 INFO - 'LANG': 'en_US.UTF-8', 05:22:18 INFO - 'LANGUAGE': 'en_US:en', 05:22:18 INFO - 'LOGNAME': 'cltbld', 05:22:18 INFO - 'MAIL': '/var/mail/cltbld', 05:22:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:18 INFO - 'MOZ_NO_REMOTE': '1', 05:22:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:18 INFO - 'NO_EM_RESTART': '1', 05:22:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:22:18 INFO - 'PWD': '/builds/slave/test-pgo', 05:22:18 INFO - 'SHELL': '/bin/bash', 05:22:18 INFO - 'SHLVL': '1', 05:22:18 INFO - 'TERM': 'linux', 05:22:18 INFO - 'TMOUT': '86400', 05:22:18 INFO - 'USER': 'cltbld', 05:22:18 INFO - 'XDG_SESSION_COOKIE': '928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226', 05:22:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:18 INFO - '_': '/tools/buildbot/bin/python'} 05:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:22:18 INFO - Running setup.py (path:/tmp/pip-7aypc1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:22:18 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)) 05:22:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:22:18 INFO - Running setup.py (path:/tmp/pip-YCMxAh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:22:18 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)) 05:22:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:22:18 INFO - Running setup.py (path:/tmp/pip-JZFwpW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:22:18 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)) 05:22:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:22:18 INFO - Running setup.py (path:/tmp/pip-aX6ugk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:22:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:22:18 INFO - Running setup.py (path:/tmp/pip-CqFnMf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:22:18 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)) 05:22:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:22:18 INFO - Running setup.py (path:/tmp/pip-msvMMM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:22:18 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)) 05:22:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:22:18 INFO - Running setup.py (path:/tmp/pip-q80bvT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:22:18 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)) 05:22:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:22:18 INFO - Running setup.py (path:/tmp/pip-T5sreW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:22:18 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)) 05:22:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:22:18 INFO - Running setup.py (path:/tmp/pip-QA5bUC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:22:18 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)) 05:22:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:22:18 INFO - Running setup.py (path:/tmp/pip-9yCJx6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:22:18 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)) 05:22:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:22:18 INFO - Running setup.py (path:/tmp/pip-AV3kfB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:22:19 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)) 05:22:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:22:19 INFO - Running setup.py (path:/tmp/pip-VgvESm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:22:19 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)) 05:22:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:22:19 INFO - Running setup.py (path:/tmp/pip-Xj335T-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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)) 05:22:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:22:19 INFO - Running setup.py (path:/tmp/pip-DwugF1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:22:19 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)) 05:22:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:22:19 INFO - Running setup.py (path:/tmp/pip-Wd6rV0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:22:19 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)) 05:22:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:22:19 INFO - Running setup.py (path:/tmp/pip-No6zYa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:22:19 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)) 05:22:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:22:19 INFO - Running setup.py (path:/tmp/pip-SuvtjL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:22:19 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)) 05:22:19 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)) 05:22:19 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)) 05:22:19 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)) 05:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:22:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:22:19 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 05:22:19 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 05:22:19 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 05:22:19 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 05:22:19 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 05:22:19 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 05:22:21 INFO - Downloading blessings-1.5.1.tar.gz 05:22:21 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 05:22:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:22:21 INFO - Installing collected packages: blessings 05:22:21 INFO - Running setup.py install for blessings 05:22:21 INFO - Successfully installed blessings 05:22:21 INFO - Cleaning up... 05:22:21 INFO - Return code: 0 05:22:21 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:22:21 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:22:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:22:21 INFO - Reading from file tmpfile_stdout 05:22:21 INFO - Current package versions: 05:22:21 INFO - argparse == 1.2.1 05:22:21 INFO - blessings == 1.5.1 05:22:21 INFO - blobuploader == 1.2.4 05:22:21 INFO - docopt == 0.6.1 05:22:21 INFO - manifestparser == 1.1 05:22:21 INFO - mozInstall == 1.12 05:22:21 INFO - mozcrash == 0.16 05:22:21 INFO - mozdebug == 0.1 05:22:21 INFO - mozdevice == 0.47 05:22:21 INFO - mozfile == 1.2 05:22:21 INFO - mozhttpd == 0.7 05:22:21 INFO - mozinfo == 0.9 05:22:21 INFO - mozleak == 0.1 05:22:21 INFO - mozlog == 3.1 05:22:21 INFO - moznetwork == 0.27 05:22:21 INFO - mozprocess == 0.22 05:22:21 INFO - mozprofile == 0.27 05:22:21 INFO - mozrunner == 6.11 05:22:21 INFO - mozscreenshot == 0.1 05:22:21 INFO - mozsystemmonitor == 0.0 05:22:21 INFO - moztest == 0.7 05:22:21 INFO - mozversion == 1.4 05:22:21 INFO - psutil == 3.1.1 05:22:21 INFO - requests == 1.2.3 05:22:21 INFO - wsgiref == 0.1.2 05:22:21 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:22:21 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')]} 05:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:21 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')]} 05:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:21 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252e50>, '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 0x1253d50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12541e0>, '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': '928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226', '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 05:22:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 05:22:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:22:21 INFO - Using env: {'DISPLAY': ':0', 05:22:21 INFO - 'HOME': '/home/cltbld', 05:22:21 INFO - 'LANG': 'en_US.UTF-8', 05:22:21 INFO - 'LANGUAGE': 'en_US:en', 05:22:21 INFO - 'LOGNAME': 'cltbld', 05:22:21 INFO - 'MAIL': '/var/mail/cltbld', 05:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:21 INFO - 'MOZ_NO_REMOTE': '1', 05:22:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:21 INFO - 'NO_EM_RESTART': '1', 05:22:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:22:21 INFO - 'PWD': '/builds/slave/test-pgo', 05:22:21 INFO - 'SHELL': '/bin/bash', 05:22:21 INFO - 'SHLVL': '1', 05:22:21 INFO - 'TERM': 'linux', 05:22:21 INFO - 'TMOUT': '86400', 05:22:21 INFO - 'USER': 'cltbld', 05:22:21 INFO - 'XDG_SESSION_COOKIE': '928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226', 05:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:21 INFO - '_': '/tools/buildbot/bin/python'} 05:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:21 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 1)) 05:22:21 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 2)) 05:22:21 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 3)) 05:22:21 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 4)) 05:22:21 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 5)) 05:22:21 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 6)) 05:22:21 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 7)) 05:22:21 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 8)) 05:22:21 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.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:22:21 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 3)) 05:22:21 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.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:22:21 INFO - Cleaning up... 05:22:21 INFO - Return code: 0 05:22:21 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:22:21 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')]} 05:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:21 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')]} 05:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:21 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252e50>, '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 0x1253d50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12541e0>, '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': '928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226', '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 05:22:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 05:22:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:22:21 INFO - Using env: {'DISPLAY': ':0', 05:22:21 INFO - 'HOME': '/home/cltbld', 05:22:21 INFO - 'LANG': 'en_US.UTF-8', 05:22:21 INFO - 'LANGUAGE': 'en_US:en', 05:22:21 INFO - 'LOGNAME': 'cltbld', 05:22:21 INFO - 'MAIL': '/var/mail/cltbld', 05:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:21 INFO - 'MOZ_NO_REMOTE': '1', 05:22:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:21 INFO - 'NO_EM_RESTART': '1', 05:22:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:22:21 INFO - 'PWD': '/builds/slave/test-pgo', 05:22:21 INFO - 'SHELL': '/bin/bash', 05:22:21 INFO - 'SHLVL': '1', 05:22:21 INFO - 'TERM': 'linux', 05:22:21 INFO - 'TMOUT': '86400', 05:22:21 INFO - 'USER': 'cltbld', 05:22:21 INFO - 'XDG_SESSION_COOKIE': '928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226', 05:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:21 INFO - '_': '/tools/buildbot/bin/python'} 05:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:22 INFO - Downloading/unpacking jsonschema 05:22:22 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 05:22:22 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 05:22:22 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 05:22:22 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 05:22:22 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 05:22:22 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 05:22:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:22:23 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 05:22:23 INFO - Downloading/unpacking functools32 (from jsonschema) 05:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:23 INFO - Downloading functools32-3.2.3-2.tar.gz 05:22:23 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:22:23 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:22:23 INFO - warning: no files found matching '*.txt' 05:22:23 INFO - no previously-included directories found matching 'build' 05:22:23 INFO - no previously-included directories found matching 'dist' 05:22:23 INFO - no previously-included directories found matching '.git*' 05:22:24 INFO - Installing collected packages: jsonschema, functools32 05:22:24 INFO - Running setup.py install for functools32 05:22:24 INFO - warning: no files found matching '*.txt' 05:22:24 INFO - no previously-included directories found matching 'build' 05:22:24 INFO - no previously-included directories found matching 'dist' 05:22:24 INFO - no previously-included directories found matching '.git*' 05:22:24 INFO - Successfully installed jsonschema functools32 05:22:24 INFO - Cleaning up... 05:22:24 INFO - Return code: 0 05:22:24 INFO - Running post-action listener: _resource_record_post_action 05:22:24 INFO - Running post-action listener: _start_resource_monitoring 05:22:24 INFO - Starting resource monitoring. 05:22:24 INFO - ##### 05:22:24 INFO - ##### Running install step. 05:22:24 INFO - ##### 05:22:24 INFO - Running pre-action listener: _resource_record_pre_action 05:22:24 INFO - Running main action method: install 05:22:24 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:22:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:22:24 INFO - Reading from file tmpfile_stdout 05:22:24 INFO - Detecting whether we're running mozinstall >=1.0... 05:22:24 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:22:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:22:24 INFO - Reading from file tmpfile_stdout 05:22:24 INFO - Output received: 05:22:24 INFO - Usage: mozinstall [options] installer 05:22:24 INFO - Options: 05:22:24 INFO - -h, --help show this help message and exit 05:22:24 INFO - -d DEST, --destination=DEST 05:22:24 INFO - Directory to install application into. [default: 05:22:24 INFO - "/builds/slave/test-pgo"] 05:22:24 INFO - --app=APP Application being installed. [default: firefox] 05:22:24 INFO - mkdir: /builds/slave/test-pgo/build/application 05:22:24 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 05:22:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 05:22:39 INFO - Reading from file tmpfile_stdout 05:22:39 INFO - Output received: 05:22:39 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:22:39 INFO - Running post-action listener: _resource_record_post_action 05:22:39 INFO - ##### 05:22:39 INFO - ##### Running run-tests step. 05:22:39 INFO - ##### 05:22:39 INFO - Running pre-action listener: _resource_record_pre_action 05:22:39 INFO - Running main action method: run_tests 05:22:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:22:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:22:39 INFO - Python 2.7.3 05:22:39 INFO - Return code: 0 05:22:39 INFO - grabbing minidump binary from tooltool 05:22:39 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')]} 05:22:39 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 0x1252e50>, '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 0x1253d50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12541e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:22:39 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 05:22:39 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 05:22:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:22:39 INFO - Return code: 0 05:22:39 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:22:39 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:22:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:22:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:22:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:22:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:22:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-012 --webServer localhost 05:22:39 INFO - Using env: {'DISPLAY': ':0', 05:22:39 INFO - 'HOME': '/home/cltbld', 05:22:39 INFO - 'LANG': 'en_US.UTF-8', 05:22:39 INFO - 'LANGUAGE': 'en_US:en', 05:22:39 INFO - 'LOGNAME': 'cltbld', 05:22:39 INFO - 'MAIL': '/var/mail/cltbld', 05:22:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:22:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:22:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:39 INFO - 'MOZ_NO_REMOTE': '1', 05:22:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:22:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:39 INFO - 'NO_EM_RESTART': '1', 05:22:39 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:22:39 INFO - 'PWD': '/builds/slave/test-pgo', 05:22:39 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:22:39 INFO - 'SHELL': '/bin/bash', 05:22:39 INFO - 'SHLVL': '1', 05:22:39 INFO - 'TERM': 'linux', 05:22:39 INFO - 'TMOUT': '86400', 05:22:39 INFO - 'USER': 'cltbld', 05:22:39 INFO - 'XDG_SESSION_COOKIE': '928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226', 05:22:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:39 INFO - '_': '/tools/buildbot/bin/python'} 05:22:39 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost'] with output_timeout 3600 05:22:39 INFO - mozversion INFO | application_buildid: 20151124030138 05:22:39 INFO - mozversion INFO | application_changeset: c216ff19d6904fca645db371b45ef5d25e3c125f 05:22:39 INFO - mozversion INFO | application_display_name: Nightly 05:22:39 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:22:39 INFO - mozversion INFO | application_name: Firefox 05:22:39 INFO - mozversion INFO | application_remotingname: firefox 05:22:39 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:22:39 INFO - mozversion INFO | application_vendor: Mozilla 05:22:39 INFO - mozversion INFO | application_version: 45.0a1 05:22:39 INFO - mozversion INFO | platform_buildid: 20151124030138 05:22:39 INFO - mozversion INFO | platform_changeset: c216ff19d6904fca645db371b45ef5d25e3c125f 05:22:39 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:22:39 INFO - mozversion INFO | platform_version: 45.0a1 05:22:39 INFO - 2015-11-24 05:22:39,357 DEBUG : using testdate: 1448371359 05:22:39 INFO - 2015-11-24 05:22:39,357 DEBUG : actual date: 1448371359 05:22:39 INFO - 2015-11-24 05:22:39,414 INFO : starting webserver on 'localhost:56852' 05:22:39 INFO - 2015-11-24 05:22:39,415 INFO : Starting test suite talos-linux64-ix-012 05:22:39 INFO - 2015-11-24 05:22:39,415 INFO : Starting test dromaeo_css 05:22:39 INFO - 2015-11-24 05:22:39,415 DEBUG : operating with platform_type : linux_ 05:22:39 INFO - 2015-11-24 05:22:39,416 INFO : Initialising browser for dromaeo_css test... 05:22:39 INFO - 2015-11-24 05:22:39,430 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBH_XHH/profile http://localhost:56852/getInfo.html 05:22:42 INFO - 2015-11-24 05:22:42,850 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:22:42 INFO - 2015-11-24 05:22:42,850 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:22:42 INFO - 2015-11-24 05:22:42,850 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:22:42 INFO - 2015-11-24 05:22:42,850 DEBUG : BROWSER_OUTPUT: __metrics 05:22:42 INFO - 2015-11-24 05:22:42,987 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:22:44 INFO - 2015-11-24 05:22:44,000 INFO : Browser initialized. 05:22:44 INFO - 2015-11-24 05:22:44,001 INFO : Running cycle 1/1 for dromaeo_css test... 05:22:44 INFO - 2015-11-24 05:22:44,001 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBH_XHH/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:22:55 INFO - 2015-11-24 05:22:55,778 DEBUG : BROWSER_OUTPUT: RSS: Main: 138887168 05:22:55 INFO - 2015-11-24 05:22:55,779 DEBUG : BROWSER_OUTPUT: 05:23:47 INFO - 2015-11-24 05:23:47,384 DEBUG : BROWSER_OUTPUT: 1448371427379 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] 05:25:55 INFO - 2015-11-24 05:25:55,484 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56852/tests/dromaeo/cssquery-ext.html) 05:25:57 INFO - 2015-11-24 05:25:57,366 DEBUG : BROWSER_OUTPUT: RSS: Main: 326193152 05:25:57 INFO - 2015-11-24 05:25:57,366 DEBUG : BROWSER_OUTPUT: 05:28:59 INFO - 2015-11-24 05:28:59,947 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/dromaeo/cssquery-ext.html (next: http://localhost:56852/tests/dromaeo/cssquery-jquery.html) 05:29:00 INFO - 2015-11-24 05:29:00,521 DEBUG : BROWSER_OUTPUT: RSS: Main: 223354880 05:29:00 INFO - 2015-11-24 05:29:00,521 DEBUG : BROWSER_OUTPUT: 05:32:02 INFO - 2015-11-24 05:32:02,479 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56852/tests/dromaeo/cssquery-mootools.html) 05:32:03 INFO - 2015-11-24 05:32:03,046 DEBUG : BROWSER_OUTPUT: RSS: Main: 220983296 05:32:03 INFO - 2015-11-24 05:32:03,046 DEBUG : BROWSER_OUTPUT: 05:35:04 INFO - 2015-11-24 05:35:04,922 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56852/tests/dromaeo/cssquery-prototype.html) 05:35:05 INFO - 2015-11-24 05:35:05,491 DEBUG : BROWSER_OUTPUT: RSS: Main: 203194368 05:35:05 INFO - 2015-11-24 05:35:05,491 DEBUG : BROWSER_OUTPUT: 05:38:07 INFO - 2015-11-24 05:38:07,892 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56852/tests/dromaeo/cssquery-yui.html) 05:38:08 INFO - 2015-11-24 05:38:08,457 DEBUG : BROWSER_OUTPUT: RSS: Main: 203390976 05:38:08 INFO - 2015-11-24 05:38:08,457 DEBUG : BROWSER_OUTPUT: 05:41:11 INFO - 2015-11-24 05:41:11,003 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/dromaeo/cssquery-yui.html (next: http://localhost:56852/tests/dromaeo/cssquery-dojo.html) 05:41:11 INFO - 2015-11-24 05:41:11,254 DEBUG : BROWSER_OUTPUT: RSS: Main: 289488896 05:41:11 INFO - 2015-11-24 05:41:11,254 DEBUG : BROWSER_OUTPUT: 05:41:11 INFO - 2015-11-24 05:41:11,254 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:41:11 INFO - 2015-11-24 05:41:11,255 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:41:11 INFO - 2015-11-24 05:41:11,255 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:41:11 INFO - 2015-11-24 05:41:11,255 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:41:11 INFO - 2015-11-24 05:41:11,257 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3185.251465905512;3239.4169049571096;3283.1463819406963;3296.307775367173;3314.4696848504245;5237.5548078413285;5331.493508116728;5339.41266460689;5413.972930135349;5475.753591088405;5370.5435038021815;5452.454754524546;5506.779728810853;5516.558675305976;5520.089185284433;4965.478624744395;4987.127252730776;5005.899882002376;5033.194688849785;5084.593232541388;4813.253945638432;5009;5025.623078269145;5058.822941197059;5099.235114732783;4838.443578988408;4903.509649035086;4907.092187945242;4927.901441971177;4938.58022068121;4966.106100901836;4966.801327946878;4972.65191436596;4979.4771548987555;5006.924896126561;4783.760811959423;4946.208606622959;4954.975225123887;4960.677555958851;5018.874528136789;5144.588432925394;5192.688438693691;5212.556932660728;5230.189320655267;5269.473052694738;5856.267966504187;5875.236219291465;5882.647041177543;5886.381929897385;5897.941020589782;5629.60592758507;5910.408959104056;5917.14201440793;5924.466797988159;5934.13954976526;5350.5452036576935;5370.838874833722;5455.236266922634;5590.329160500766;5601.579881508903;5995.880082398327;6007.669578173155;6015.1278064681255;6028.336882942873;6068.878622427526;5681.744321505542;5787.536997040311;5794.8551286217335;5801.390853960358;5860.00379935412;5685.403032681592;5808.970955145197;5841.649501029951;5887.264091988502;5890.4698577128265;5849.590528663038;5877.147813567094;5882.647041177543;5891.3814049525035;5926.348101708809;5827.475527202488;5828.300603927556;5828.562857785683;5831.475167234969;5886.705664716747;4374.540673229329;4463.4420697412825;4479.014616784302;4549.613282870927;4549.681522293407;4023.275810354164;4031.697622678311;4037.6164264394833;4042.0905296308097;4077.1641813428178;5583.860403489864;5587.826556423115;5589.133684278943;5589.608727389125;5638.464345887134;5743.913841292384;5836;5855.941440585625;5907.409259074144;5987.341392446828;5585.776568937278;5612.354579223442;5618.943810561842;5622.156676498558;5626.240457538262;5179.093658609803;5203.479652034842;5209.739513024334;5212.9739351303;5253.605979551549;5901.911471327933;5997.940020599825;6033.366496517802;6042;6116.204893363834;5866.472017518444;5882;5885.852853678693;5897.64614123154;5954.523638109027;6010.7295171716505;6015.1278064681255;6020.789272375446;6023.397660233942;6080.361562035922;5511.283533140666;5584.720763961867;5630.943690563042;5649.576281778801;5661.801836935688;5741;5763.02028655138;5785.016547187072;5794.971025144847;5795.7391917362975;5643.125315576092;5661.2923384576925;5671.319441667027;5674.432556744293;5777.191193232867;5762.8847423050465;5766.769329226951;5774;5797.681127537857;5826.592138550431;5660.688662123623;5663.235463212578;5665.291838520185;5667.688277144796;5682.289713785777;5574.191742197439;5651.208830763614;5683.318001839806;5754.309482862083;5802.448767367094;5588.832335029955;5674.347450043133;5677.801276955287;5679.801206957902;5720.3707592164 05:41:11 INFO - 2015-11-24 05:41:11,258 DEBUG : BROWSER_OUTPUT: |1;ext.html;585.1164741240726;588.0855270055065;588.8733922206725;596.1951365656362;599.0774207720111;30895.38209235814;39369.2126157477;39783.00542486436;39814.800925995405;39867.60132398675;43231.37992061028;43810.78094609531;43898.561014389845;43944.56055439453;43950.340744888854;7513.135989361212;7616.885746713804;8653.437526560769;8899.37704360695;8901.77745556363;10485.37087774736;10701.518431670593;10705.946470267676;10717.83923241152;10718.142548596132;10275.177985761158;10409.68770936873;10468.947655261712;10472.057514823664;10485.318454300446;7698.807529811744;7886.526808391457;10331.380117192955;10508.054275115277;10795.29830561011;10216.335938163995;10342.689719308357;10352.378857268588;10355.378677279386;10358.741031474197;39237.215255694726;39473.40789888154;39775.20449590992;39938;40091.59908400908;3253.8535765890592;3285.671432856719;3348.029071569242;3364.34395292918;3605.4952306677087;30600.387992240252;30808.691913080806;31058.223544411343;31127;31730.365392692016;3917.157811070605;3928.7249892507534;3993.381025940984;4005.919881602381;4040.979795101035;266434;266662;266949;266950.66524667444;268287;56891.14663280054;58241.4175858236;58902.410975889696;65580.67209663922;67008.66495667491;59873.70063149656;60193;60227.096593551134;60336.396636033955;60348;14773.92613036928;14927.179005154821;14930.178840163895;14957.252137393087;14957.326920288395;11173.776524469466;11226.382548959886;11230.3261804292;11236.831447528293;11249.10007199422;19618.80381196198;19706.31027914016;19719.309824156087;19732.802671973095;20040.599188016156;7319.5242309249725;7394.890766385085;7467.141278753013;7497.925020749832;7610.467267291237;953.3755390219418;957.0764212534938;961.9086186812242;992.8014397120461;1037.0459177556666;28387.432251354858;30012.24969375783;31302.217444563612;31481;31742.523862142087;957.4351132831663;1048.3814549415729;1053.1837825685154;1100.9925917785297;1126.2285334545863;14665.633359165893;15370;15663.102326069486;15730.528084157493;16020.359185632444;306386;309857;309971;310691;311172;53058;53410;53477.465225347245;53698.73150634222;53703;1312;1338.4779935825231;1346.4344975110478;1356.1795113956111;1358.517726207202;22287.665685014414;26414;26648;27139.86430067837;27179.456410872066;40286;40913.795431022656;40969.77090687165;41029.589704102575;41061.17877642371;202493;223732;242549;280197;289550;31251;31376.68623313829;31521.21196970094;31949.520757188195;31974.840125799223;37995;39126.2174756509;39458.60541394549;39480.2103957925;42346;32636.510452342758;33982.320353593284;35546.46680299746;37345.626543734215;37356.49644634189;35850.64149358473;36311.455328170625;36851.81574092113;36977.07557311089;37097.814510927274;35534.46698299579;36213.09467263339;36461.27077458383;36691.63308366989;36775;31622.841885790425;33170;33423.665763342055;33504.16239593932;33559.66440335565;24893.626595601443;25060.373216246466;25208.621870671588;25320.366990825376;25336.239912802645 05:41:11 INFO - 2015-11-24 05:41:11,259 DEBUG : BROWSER_OUTPUT: |2;jquery.html;353.79303107682006;356.1807841963483;356.6576086956522;359.73020234823883;360.4935066231943;14291.71416571668;14441.783373249384;16269.34922603096;16536.173191340444;17223.569410764736;15725.719770633252;16547.172641367913;16891.23989420476;16906.154692265398;17014.659706805858;5330.573554115671;5480.3971563128025;5484.808031718891;5488.341399032121;5490.258815059976;3985.780782057;4088.407180958759;5351.438098999607;5380.461953804627;5783.768649254025;5796.50729687977;5826.650400975934;5837.9124313135335;5843.970780146094;5853.824385268427;5295.20571914214;5323;5342.225377320306;5445.373782015079;5840.737166827482;5076.746162691888;5159.974200128975;5171.172612382001;5173.068847607429;5175.534201921808;19755.20979160832;20271.89864050685;20276.290329838386;20391;20921.476963075893;1983.097690550796;2017.3141132015185;2129.7018417421573;2153.5800518898823;2392.4497365605835;9469;9570.521473926276;9674;9761.51192440375;9839.93361394108;1744.5135050266965;1826.1873466307497;1871.7847447543434;1882.2565086790778;1891.3096719697348;59760.40239597635;61965.69017154886;63701.362986370004;63949.36050639527;64184.679076604785;10049.145822605014;10088;10127.797444051077;10138.645147419806;10172.084512393772;13266.469341226242;13295.069345145746;13299.401526931311;13301.334933253298;13301.933490332487;7416.517926334771;7472.252774722593;7484.925150748531;7494.887576686354;7639.656215470317;7169.713211471587;7185.712571497186;7289.343959043712;7317.12194536659;7357.8160545986775;4269.018125831076;4324.221640104748;4333.43665323506;4377.671674624416;4386.912261754811;1571.9764203536956;1602.190893598725;1624.5207663739225;1640.6882692288427;1668.3576822923262;862.1723145780121;876.2114097312469;914.446759710379;931.4969916245152;989.7674046599044;14247.21640309793;16286.592835179215;16301.67396652084;16623.91688041552;16912.746308805377;889.8309321228945;895.8297923394534;940.2337095267335;975.9512024398894;1052.3370276725766;9113.726588202364;9229.400088994194;9277.90722092784;9280;9382.812343753223;537149;552311;554282.9716068442;554399;565339;76762.6161869187;76770.23229767743;77259.22740772633;77317;77784;1110.0231796019452;1113.8663360396642;1117.8267368557886;1120.5741818109066;1137.3801278303345;14791.890608204212;14867.479638212611;15042.548723538312;15085.170315632746;15319.387224510894;32666.510002349507;32904.67095329112;33200.501992469646;34199.829000854836;35612.82193589119;40471;41325;41637.167256654095;41948;42049.78975105105;8531.274841638531;8554.828903422022;8706.390552661253;8742.38803283764;8796.384253102222;10272.33229840058;10369.896301037195;10389.792204155723;10447;10476.528556214836;9980.60077596888;13548.458061677817;13612.727745445234;14091.859081409055;14551.126932384092;14176.645583860074;14408.567742967727;14640.557886696675;14711.779323309946;14718.852811471748;11185.608503702331;11233.71915702114;13986.090904091201;14045.36795844169;14642.414303427744;8920.55397230123;9122.452652840851;9465.148136667733;9465.952670236606;9477.857832132662;9969;9988.450635215133;13567.660808479472;13761.587152385444;14460.710785784015 05:41:11 INFO - 2015-11-24 05:41:11,263 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1365.1536047650459;1645.2760785254488;1723.3709695960974;1724.310275889644;1763.497403240076;6128.387161283869;7039.296070392958;7204.819879503015;7212.495125341228;7214.567125972445;6982.16214054313;7137.928620713791;7141.71433142675;7154.320339567746;7164.03285556451;1939.1952339778975;1970.7635083789964;1973.5756812285356;2163.361808266561;2201.746799118098;1973.7828838827788;2208.569328980849;2217.6230040893006;2253.9436514087115;2255.1655887321713;2181.530970841277;2214.8560343577697;2221.6334304839684;2222.533268013719;2222.8555143915673;1924.4034349351746;1973.477028587411;2226.810721088709;2232.497688020199;2233.8771367574777;2197.1540956731615;2229.375774783063;2233.698450709166;2234.3743751749535;2235.7540670526228;6925.896111558331;7026.086608740831;7086.468514861406;7091.397231235351;7093.3970612498;4474.865754027384;4557.79489922951;4571.291449825282;4574.222382195002;4592.471865735416;2977.7171168738937;3136.9372612547622;3145.1979745165067;3154.4479716049827;3155.54244634529;1010.7473131717071;1023.3399457349967;1060.581070477165;1068.1988508618535;1114.5374669512144;9702.805943881083;9711.514424278757;9716.805663886682;9721.90278097217;9726;4123.8556650517085;4194.098268872207;4235.110626768352;4237.724547904377;4246.915061698749;8346.791330216673;8352;8354.624041918129;8362.916370836214;8377.120402357665;4569.520200378979;4606.907861842745;4607.953920460753;4608.838690645811;4613.884652883705;4204.957950420518;4215.557366476537;4223.239816832938;4227.598378154071;4230.1539692061715;3442.3287458945265;3504.456809194579;3517.56030496188;3530.1704099536587;3552.875649352312;1178.699431644925;1192.797224471842;1200.4057991294364;1204.9216800907914;1206.1315852586124;1147.3460127727283;1280.3022352817736;1299.3243513372993;1321.3525372567374;1327.1042046618686;6804.08144900442;6825.795226143224;6834.179898412222;6838.384545390839;6840.486963477759;452.87093178443985;452.93885325480636;455.97264164150255;460.82949308755974;497.1796535716097;5101.336826212569;5157.277981082651;5329.094054010828;5571.526420254324;5687.03320435527;8086.919130808734;8187.549684767276;8216.46592971455;8218.054923683734;8234.341252699769;8168.183181681784;8299.377546684023;8431.451955622864;8457.238848503664;8458.534780587004;6663.300353462913;6732.730690772412;6747.932520674829;6748.628825414551;6778.830529236809;6608.768693095719;6701.731930722716;6703.497237707289;6704.932950670431;6725.596464212163;7349.154847192642;7433.888491672521;7527.435442341955;7586.582737949466;7607.125180604301;7659.272369124924;7661.118971318369;7668.731594394169;7755.3407960324;7813.882791758242;6516;6579.2104947404705;6587.440067594308;6587.670616469253;6597.406233438822;6598.934010660024;6775.830604234737;7031.472639551952;7077.186123595852;7104.680289387094;6475.417212450902;6511.576747511396;6565.901511477427;6638.83402914931;6644.900326495203;6107.969460152671;6396.392342727433;6504.089427480251;6508.511861610304;6537.607743535213;6512.381323774234;6527.314631963575;6567.211934567882;6570.671466426564;6578.80263592093;5875.500582450369;5899.882002360015;5910.822675319747;5911.438413350725;5921.851953701192;6085.695715214133;6158.507319414346;6166.229221347332;6179.16581261524;6186.226721659793 05:41:11 INFO - 2015-11-24 05:41:11,263 DEBUG : BROWSER_OUTPUT: |4;prototype.html;339.694275152363;340.6287147009759;341.34121146187846;342.74123037106983;349.26305495404694;5970.880582388355;6002.189704389912;6084.300305464862;6096.542759293048;6105.938940610593;5711.114777209539;5783.277090363705;5848.444397782215;5996.070609055603;6001.129836173752;4530.818767249306;4582.931256031162;4590.311453282001;4594.218982772936;4595.2188128018315;4577.93133103002;4590.816367345302;4612.215923293032;4613.792379342937;4618.191816432128;4377.562243775629;4462.419885414892;4491.887702807423;4544.818347229728;4570.817167313304;4551.271796512542;4558.749268790215;4559.042601053783;4576.862694119149;4580.862574122748;4017.658499027586;4021.296273152216;4023.839046438139;4025.8590949316927;4029.4560234368278;5737.627054241461;5747.885042299131;5750.166225897222;5752.712364381764;5756.884862302731;5241.161414173714;5362.946370536322;5380;5382;5389.703566303851;6408.487321014308;6411.3909178627955;6421.5825971311715;6422.42198202164;6422.972324428069;4463.486699029589;4467.508574056851;4472.239719247736;4475.373447717322;4476.239039363316;6507.154069970921;6605.471562275055;6648.4681225501845;6665.000249962496;6670.766523171715;4571.634269258451;4574.542545745466;4577.22187228172;4578.175928332866;4584.816607335736;4631.397918270603;4631.861044168681;4633.698809577367;4638.373819534319;4639.86080417588;5325.573954083664;5336.893262134813;5340.172273297645;5343.465653434625;5369.48989845964;6289.056641503719;6353.5234857385885;6426.036094585849;6434.453071488882;6451.870962580722;1626.8779841511935;1644.9424270150487;1645.7942757155356;1647.1517168658152;1648.077076836975;1098.4892025208317;1099.340395762536;1099.4502748625687;1120.697411698853;1124.668222874254;6218.725161342005;6224.533160013018;6225.906611400833;6228.719707613168;6265.966115590968;6663.267040625527;6669.899951500732;6677.632730199788;6679.86640267192;6683.897156969046;6107.236595425572;6107.297660769068;6216.160818289473;6292.905606415908;6303.243610766737;3606.783592984377;3624.9456258156147;3631.891043268706;3642.7450078494253;3642.9453558196647;6946.583205007716;6949.339812717783;6964.0598519200275;6965.686544105527;6967.710973469924;6688.498362622823;6851.314868513109;6854.760083397157;6870.725170993204;6872.003559483846;6944.270851560514;6947.325279767326;6958.547694399746;6961.095057642474;6961.686724097428;6601.570897891622;6715.966420167868;6717.966410167918;6731.730730770714;6733.158355205599;6569.343065693392;6600.504962127764;6610.4711623071;6614.305497922649;6618.801435956929;6909.861802764017;6914.274001229798;6921.965390173017;6922.930770692228;6955.513114081966;4552.613027892534;4553.726776393427;4554.294084416921;4563.520830312902;4564;6545.21457425093;6593.142891424084;6600.933990660032;6603.20761508622;6625.204975402982;6455.838604034937;6501.187351581052;6503.642299673564;6504.60972341661;6508.316626754123;6481.902771458337;6485.578437401554;6489.059086432535;6490.123833282447;6492.480601551959;6211.751529938751;6308.747650093945;6322.399372059647;6400.743970241325;6406.615603063831;6490.415862572391;6521.184851893513;6548.0832683423405;6556.2788093308745;6560.671966401756;6249.468795152281;6355;6393.552451328363;6395.808125756234;6404.231492220963 05:41:11 INFO - 2015-11-24 05:41:11,264 DEBUG : BROWSER_OUTPUT: |5;yui.html;822.6832669422271;828.432523721251;835.2858306148247;840.6973489543764;844.2992316377404;13572.728545429085;13767.311634418289;13814.585562433143;13843.930780346096;13849.446022159114;12622.873771262284;12827.871721282785;14174.078684885475;14393.712125757505;14451.783223251634;689.4828878341244;805.2631841864702;825.038829763325;826.1243082332717;827.7433995461396;690.1063123255396;819.0376307838296;821.2403526737744;830.2776584371605;833.3041910005122;851.936104792143;859.1966511311912;868.6612221233723;868.9087645797196;869.826034793044;719.8200449887528;725.0900120348981;859.2395729779128;862.9439086459391;864.0107077396377;639.68015992004;642.6561789442673;642.9292777794439;644.1754554170623;646.053531576243;13641.795373069412;13775.104618199884;13785;13810.65473363164;13814;11673.824892626617;11855.46650400734;11955.880441195564;12153.392330383534;12220.633380998495;10126.54430550627;10249;10287.742806429824;10418.635347762793;10864.837027444595;3580.3555360035184;3637.781733096023;3642.25333806569;3659.963400365989;3761.492198553188;47390.7630461849;47475.525244747456;47495.525044749455;47626.285605715944;47640.761796191146;8978.236849867704;9172.220361269367;9363.204127649089;9653;9732.367396119229;16839.915800421164;16859.578510537336;16862.831371686127;16886.493405197863;16910.15449227534;7445.702171913063;7554.735584254525;7737;7851.607419628996;7950.562719050507;4449.354843547668;4453.309736990772;4473.4408198975125;4497.437820272466;4652.62778977681;3067.156531953731;3071.877124915023;3104.891328803481;3105.4099721052926;3108.3006323577465;552.4917076289823;570.6433479075578;571.0035987202275;578.6817250512202;579.2816907035246;546.2761840561272;547.5181839980792;559.5491746151829;582.3535875178549;676.9255381907986;8835.851339325975;8939.837204211177;9234.584443700049;9263.861042084374;9322.30082743797;489.7918384686565;496.3473032961644;549.1844610753028;647.2362612117746;676.2223443040444;3862.343401621731;4008.218397412535;4099.221147981874;4113.979430102891;4189.958100419018;51524.227136592985;51593;53685;58064.41935580675;58310.416895831346;50877.491225088015;51336.486635133915;51873.2219016715;51925.740371298656;52225.47774522282;46614.5338546617;47633.523664763605;48145.277820832714;48303.516964830604;48416.75791621019;7938.960305198437;8114.391420643594;8151.918480815116;8255.133211012757;8286.792830179294;35270;35596;35664.65030245484;35696.64303356933;36057;41696;43935;45986.31020534762;46002.30996534988;46239;10221.795564088528;10290.588376465157;10318.48407579603;10356.741081472723;10391.51444429495;11332.6033588824;11529.827052594386;11552.768944620891;11658.76682466329;11969;10289.588416463257;10332.638357657446;10393.630032698378;10474.895251047392;11007;9938.950305248427;10193.490325483845;10289.9485502572;10360.222983276435;10421.478926053818;10512.684619461428;10643.414612196404;10751.623693171014;11025.724356890822;11074.446277685922;8521.957390213009;8624;8659.97588938901;8737.388382813142;8979.10208979097;8311.272119269659;8535.31717462614;8608.225259726654;8630.611622477129;8906.198442140238 05:41:11 INFO - 2015-11-24 05:41:11,265 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:41:11 INFO - 2015-11-24 05:41:11,265 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:41:11 INFO - 2015-11-24 05:41:11,265 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1658 05:41:11 INFO - 2015-11-24 05:41:11,265 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:41:11 INFO - 2015-11-24 05:41:11,265 DEBUG : BROWSER_OUTPUT: __startTimestamp1448372471253__endTimestamp 05:41:11 INFO - 2015-11-24 05:41:11,265 DEBUG : BROWSER_OUTPUT: 05:41:11 INFO - 2015-11-24 05:41:11,265 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:41:11 INFO - 2015-11-24 05:41:11,265 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:41:11 INFO - 2015-11-24 05:41:11,265 DEBUG : BROWSER_OUTPUT: 05:41:11 INFO - 2015-11-24 05:41:11,266 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5434.99 Median:5649.58 stddev:602.45 (10.7%) stddev-sans-first:577.87 05:41:11 INFO - 2015-11-24 05:41:11,267 DEBUG : BROWSER_OUTPUT: Values: 3185.3 3239.4 3283.1 3296.3 3314.5 5237.6 5331.5 5339.4 5414.0 5475.8 5370.5 5452.5 5506.8 5516.6 5520.1 4965.5 4987.1 5005.9 5033.2 5084.6 4813.3 5009.0 5025.6 5058.8 5099.2 4838.4 4903.5 4907.1 4927.9 4938.6 4966.1 4966.8 4972.7 4979.5 5006.9 4783.8 4946.2 4955.0 4960.7 5018.9 5144.6 5192.7 5212.6 5230.2 5269.5 5856.3 5875.2 5882.6 5886.4 5897.9 5629.6 5910.4 5917.1 5924.5 5934.1 5350.5 5370.8 5455.2 5590.3 5601.6 5995.9 6007.7 6015.1 6028.3 6068.9 5681.7 5787.5 5794.9 5801.4 5860.0 5685.4 5809.0 5841.6 5887.3 5890.5 5849.6 5877.1 5882.6 5891.4 5926.3 5827.5 5828.3 5828.6 5831.5 5886.7 4374.5 4463.4 4479.0 4549.6 4549.7 4023.3 4031.7 4037.6 4042.1 4077.2 5583.9 5587.8 5589.1 5589.6 5638.5 5743.9 5836.0 5855.9 5907.4 5987.3 5585.8 5612.4 5618.9 5622.2 5626.2 5179.1 5203.5 5209.7 5213.0 5253.6 5901.9 5997.9 6033.4 6042.0 6116.2 5866.5 5882.0 5885.9 5897.6 5954.5 6010.7 6015.1 6020.8 6023.4 6080.4 5511.3 5584.7 5630.9 5649.6 5661.8 5741.0 5763.0 5785.0 5795.0 5795.7 5643.1 5661.3 5671.3 5674.4 5777.2 5762.9 5766.8 5774.0 5797.7 5826.6 5660.7 5663.2 5665.3 5667.7 5682.3 5574.2 5651.2 5683.3 5754.3 5802.4 5588.8 5674.3 5677.8 5679.8 5720.4 05:41:11 INFO - 2015-11-24 05:41:11,267 DEBUG : BROWSER_OUTPUT: 05:41:11 INFO - 2015-11-24 05:41:11,267 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:44899.96 Median:17945.18 stddev:72134.72 (402.0%) stddev-sans-first:72260.34 05:41:11 INFO - 2015-11-24 05:41:11,267 DEBUG : BROWSER_OUTPUT: Values: 585.1 588.1 588.9 596.2 599.1 30895.4 39369.2 39783.0 39814.8 39867.6 43231.4 43810.8 43898.6 43944.6 43950.3 7513.1 7616.9 8653.4 8899.4 8901.8 10485.4 10701.5 10705.9 10717.8 10718.1 10275.2 10409.7 10468.9 10472.1 10485.3 7698.8 7886.5 10331.4 10508.1 10795.3 10216.3 10342.7 10352.4 10355.4 10358.7 39237.2 39473.4 39775.2 39938.0 40091.6 3253.9 3285.7 3348.0 3364.3 3605.5 30600.4 30808.7 31058.2 31127.0 31730.4 3917.2 3928.7 3993.4 4005.9 4041.0 266434.0 266662.0 266949.0 266950.7 268287.0 56891.1 58241.4 58902.4 65580.7 67008.7 59873.7 60193.0 60227.1 60336.4 60348.0 14773.9 14927.2 14930.2 14957.3 14957.3 11173.8 11226.4 11230.3 11236.8 11249.1 19618.8 19706.3 19719.3 19732.8 20040.6 7319.5 7394.9 7467.1 7497.9 7610.5 953.4 957.1 961.9 992.8 1037.0 28387.4 30012.2 31302.2 31481.0 31742.5 957.4 1048.4 1053.2 1101.0 1126.2 14665.6 15370.0 15663.1 15730.5 16020.4 306386.0 309857.0 309971.0 310691.0 311172.0 53058.0 53410.0 53477.5 53698.7 53703.0 1312.0 1338.5 1346.4 1356.2 1358.5 22287.7 26414.0 26648.0 27139.9 27179.5 40286.0 40913.8 40969.8 41029.6 41061.2 202493.0 223732.0 242549.0 280197.0 289550.0 31251.0 31376.7 31521.2 31949.5 31974.8 37995.0 39126.2 39458.6 39480.2 42346.0 32636.5 33982.3 35546.5 37345.6 37356.5 35850.6 36311.5 36851.8 36977.1 37097.8 35534.5 36213.1 36461.3 36691.6 36775.0 31622.8 33170.0 33423.7 33504.2 33559.7 24893.6 25060.4 25208.6 25320.4 25336.2 05:41:11 INFO - 2015-11-24 05:41:11,267 DEBUG : BROWSER_OUTPUT: 05:41:11 INFO - 2015-11-24 05:41:11,267 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28615.62 Median:22706.70 stddev:90309.90 (397.7%) stddev-sans-first:90538.31 05:41:11 INFO - 2015-11-24 05:41:11,267 DEBUG : BROWSER_OUTPUT: Values: 353.8 356.2 356.7 359.7 360.5 14291.7 14441.8 16269.3 16536.2 17223.6 15725.7 16547.2 16891.2 16906.2 17014.7 5330.6 5480.4 5484.8 5488.3 5490.3 3985.8 4088.4 5351.4 5380.5 5783.8 5796.5 5826.7 5837.9 5844.0 5853.8 5295.2 5323.0 5342.2 5445.4 5840.7 5076.7 5160.0 5171.2 5173.1 5175.5 19755.2 20271.9 20276.3 20391.0 20921.5 1983.1 2017.3 2129.7 2153.6 2392.4 9469.0 9570.5 9674.0 9761.5 9839.9 1744.5 1826.2 1871.8 1882.3 1891.3 59760.4 61965.7 63701.4 63949.4 64184.7 10049.1 10088.0 10127.8 10138.6 10172.1 13266.5 13295.1 13299.4 13301.3 13301.9 7416.5 7472.3 7484.9 7494.9 7639.7 7169.7 7185.7 7289.3 7317.1 7357.8 4269.0 4324.2 4333.4 4377.7 4386.9 1572.0 1602.2 1624.5 1640.7 1668.4 862.2 876.2 914.4 931.5 989.8 14247.2 16286.6 16301.7 16623.9 16912.7 889.8 895.8 940.2 976.0 1052.3 9113.7 9229.4 9277.9 9280.0 9382.8 537149.0 552311.0 554283.0 554399.0 565339.0 76762.6 76770.2 77259.2 77317.0 77784.0 1110.0 1113.9 1117.8 1120.6 1137.4 14791.9 14867.5 15042.5 15085.2 15319.4 32666.5 32904.7 33200.5 34199.8 35612.8 40471.0 41325.0 41637.2 41948.0 42049.8 8531.3 8554.8 8706.4 8742.4 8796.4 10272.3 10369.9 10389.8 10447.0 10476.5 9980.6 13548.5 13612.7 14091.9 14551.1 14176.6 14408.6 14640.6 14711.8 14718.9 11185.6 11233.7 13986.1 14045.4 14642.4 8920.6 9122.5 9465.1 9466.0 9477.9 9969.0 9988.5 13567.7 13761.6 14460.7 05:41:11 INFO - 2015-11-24 05:41:11,267 DEBUG : BROWSER_OUTPUT: 05:41:11 INFO - 2015-11-24 05:41:11,268 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5063.63 Median:6096.83 stddev:2531.38 (41.5%) stddev-sans-first:2523.22 05:41:11 INFO - 2015-11-24 05:41:11,269 DEBUG : BROWSER_OUTPUT: Values: 1365.2 1645.3 1723.4 1724.3 1763.5 6128.4 7039.3 7204.8 7212.5 7214.6 6982.2 7137.9 7141.7 7154.3 7164.0 1939.2 1970.8 1973.6 2163.4 2201.7 1973.8 2208.6 2217.6 2253.9 2255.2 2181.5 2214.9 2221.6 2222.5 2222.9 1924.4 1973.5 2226.8 2232.5 2233.9 2197.2 2229.4 2233.7 2234.4 2235.8 6925.9 7026.1 7086.5 7091.4 7093.4 4474.9 4557.8 4571.3 4574.2 4592.5 2977.7 3136.9 3145.2 3154.4 3155.5 1010.7 1023.3 1060.6 1068.2 1114.5 9702.8 9711.5 9716.8 9721.9 9726.0 4123.9 4194.1 4235.1 4237.7 4246.9 8346.8 8352.0 8354.6 8362.9 8377.1 4569.5 4606.9 4608.0 4608.8 4613.9 4205.0 4215.6 4223.2 4227.6 4230.2 3442.3 3504.5 3517.6 3530.2 3552.9 1178.7 1192.8 1200.4 1204.9 1206.1 1147.3 1280.3 1299.3 1321.4 1327.1 6804.1 6825.8 6834.2 6838.4 6840.5 452.9 452.9 456.0 460.8 497.2 5101.3 5157.3 5329.1 5571.5 5687.0 8086.9 8187.5 8216.5 8218.1 8234.3 8168.2 8299.4 8431.5 8457.2 8458.5 6663.3 6732.7 6747.9 6748.6 6778.8 6608.8 6701.7 6703.5 6704.9 6725.6 7349.2 7433.9 7527.4 7586.6 7607.1 7659.3 7661.1 7668.7 7755.3 7813.9 6516.0 6579.2 6587.4 6587.7 6597.4 6598.9 6775.8 7031.5 7077.2 7104.7 6475.4 6511.6 6565.9 6638.8 6644.9 6108.0 6396.4 6504.1 6508.5 6537.6 6512.4 6527.3 6567.2 6570.7 6578.8 5875.5 5899.9 5910.8 5911.4 5921.9 6085.7 6158.5 6166.2 6179.2 6186.2 05:41:11 INFO - 2015-11-24 05:41:11,269 DEBUG : BROWSER_OUTPUT: 05:41:11 INFO - 2015-11-24 05:41:11,269 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5386.05 Median:6107.27 stddev:1631.84 (26.7%) stddev-sans-first:1591.85 05:41:11 INFO - 2015-11-24 05:41:11,269 DEBUG : BROWSER_OUTPUT: Values: 339.7 340.6 341.3 342.7 349.3 5970.9 6002.2 6084.3 6096.5 6105.9 5711.1 5783.3 5848.4 5996.1 6001.1 4530.8 4582.9 4590.3 4594.2 4595.2 4577.9 4590.8 4612.2 4613.8 4618.2 4377.6 4462.4 4491.9 4544.8 4570.8 4551.3 4558.7 4559.0 4576.9 4580.9 4017.7 4021.3 4023.8 4025.9 4029.5 5737.6 5747.9 5750.2 5752.7 5756.9 5241.2 5362.9 5380.0 5382.0 5389.7 6408.5 6411.4 6421.6 6422.4 6423.0 4463.5 4467.5 4472.2 4475.4 4476.2 6507.2 6605.5 6648.5 6665.0 6670.8 4571.6 4574.5 4577.2 4578.2 4584.8 4631.4 4631.9 4633.7 4638.4 4639.9 5325.6 5336.9 5340.2 5343.5 5369.5 6289.1 6353.5 6426.0 6434.5 6451.9 1626.9 1644.9 1645.8 1647.2 1648.1 1098.5 1099.3 1099.5 1120.7 1124.7 6218.7 6224.5 6225.9 6228.7 6266.0 6663.3 6669.9 6677.6 6679.9 6683.9 6107.2 6107.3 6216.2 6292.9 6303.2 3606.8 3624.9 3631.9 3642.7 3642.9 6946.6 6949.3 6964.1 6965.7 6967.7 6688.5 6851.3 6854.8 6870.7 6872.0 6944.3 6947.3 6958.5 6961.1 6961.7 6601.6 6716.0 6718.0 6731.7 6733.2 6569.3 6600.5 6610.5 6614.3 6618.8 6909.9 6914.3 6922.0 6922.9 6955.5 4552.6 4553.7 4554.3 4563.5 4564.0 6545.2 6593.1 6600.9 6603.2 6625.2 6455.8 6501.2 6503.6 6504.6 6508.3 6481.9 6485.6 6489.1 6490.1 6492.5 6211.8 6308.7 6322.4 6400.7 6406.6 6490.4 6521.2 6548.1 6556.3 6560.7 6249.5 6355.0 6393.6 6395.8 6404.2 05:41:11 INFO - 2015-11-24 05:41:11,269 DEBUG : BROWSER_OUTPUT: 05:41:11 INFO - 2015-11-24 05:41:11,269 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13595.03 Median:47893.02 stddev:15874.05 (33.1%) stddev-sans-first:15889.61 05:41:11 INFO - 2015-11-24 05:41:11,269 DEBUG : BROWSER_OUTPUT: Values: 822.7 828.4 835.3 840.7 844.3 13572.7 13767.3 13814.6 13843.9 13849.4 12622.9 12827.9 14174.1 14393.7 14451.8 689.5 805.3 825.0 826.1 827.7 690.1 819.0 821.2 830.3 833.3 851.9 859.2 868.7 868.9 869.8 719.8 725.1 859.2 862.9 864.0 639.7 642.7 642.9 644.2 646.1 13641.8 13775.1 13785.0 13810.7 13814.0 11673.8 11855.5 11955.9 12153.4 12220.6 10126.5 10249.0 10287.7 10418.6 10864.8 3580.4 3637.8 3642.3 3660.0 3761.5 47390.8 47475.5 47495.5 47626.3 47640.8 8978.2 9172.2 9363.2 9653.0 9732.4 16839.9 16859.6 16862.8 16886.5 16910.2 7445.7 7554.7 7737.0 7851.6 7950.6 4449.4 4453.3 4473.4 4497.4 4652.6 3067.2 3071.9 3104.9 3105.4 3108.3 552.5 570.6 571.0 578.7 579.3 546.3 547.5 559.5 582.4 676.9 8835.9 8939.8 9234.6 9263.9 9322.3 489.8 496.3 549.2 647.2 676.2 3862.3 4008.2 4099.2 4114.0 4190.0 51524.2 51593.0 53685.0 58064.4 58310.4 50877.5 51336.5 51873.2 51925.7 52225.5 46614.5 47633.5 48145.3 48303.5 48416.8 7939.0 8114.4 8151.9 8255.1 8286.8 35270.0 35596.0 35664.7 35696.6 36057.0 41696.0 43935.0 45986.3 46002.3 46239.0 10221.8 10290.6 10318.5 10356.7 10391.5 11332.6 11529.8 11552.8 11658.8 11969.0 10289.6 10332.6 10393.6 10474.9 11007.0 9939.0 10193.5 10289.9 10360.2 10421.5 10512.7 10643.4 10751.6 11025.7 11074.4 8522.0 8624.0 8660.0 8737.4 8979.1 8311.3 8535.3 8608.2 8630.6 8906.2 05:41:11 INFO - 2015-11-24 05:41:11,269 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:41:11 INFO - 2015-11-24 05:41:11,269 DEBUG : BROWSER_OUTPUT: 05:41:11 INFO - 2015-11-24 05:41:11,852 INFO : Browser exited with error code: 0 05:41:11 INFO - 2015-11-24 05:41:11,871 INFO : Completed test dromaeo_css (00:18:32) 05:41:11 INFO - 2015-11-24 05:41:11,871 INFO : Starting test kraken 05:41:11 INFO - 2015-11-24 05:41:11,871 DEBUG : operating with platform_type : linux_ 05:41:11 INFO - 2015-11-24 05:41:11,871 INFO : Initialising browser for kraken test... 05:41:11 INFO - 2015-11-24 05:41:11,877 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYSscvy/profile http://localhost:56852/getInfo.html 05:41:15 INFO - 2015-11-24 05:41:15,240 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:41:15 INFO - 2015-11-24 05:41:15,240 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:41:15 INFO - 2015-11-24 05:41:15,240 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:41:15 INFO - 2015-11-24 05:41:15,240 DEBUG : BROWSER_OUTPUT: __metrics 05:41:15 INFO - 2015-11-24 05:41:15,341 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:41:16 INFO - 2015-11-24 05:41:16,448 INFO : Browser initialized. 05:41:16 INFO - 2015-11-24 05:41:16,449 INFO : Running cycle 1/1 for kraken test... 05:41:16 INFO - 2015-11-24 05:41:16,449 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYSscvy/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:41:28 INFO - 2015-11-24 05:41:28,160 DEBUG : BROWSER_OUTPUT: RSS: Main: 138985472 05:41:28 INFO - 2015-11-24 05:41:28,160 DEBUG : BROWSER_OUTPUT: 05:41:30 INFO - 2015-11-24 05:41:30,615 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56852/tests/kraken/driver.html?testName=audio-beat-detection) 05:41:31 INFO - 2015-11-24 05:41:31,196 DEBUG : BROWSER_OUTPUT: RSS: Main: 177639424 05:41:31 INFO - 2015-11-24 05:41:31,196 DEBUG : BROWSER_OUTPUT: 05:41:33 INFO - 2015-11-24 05:41:33,777 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56852/tests/kraken/driver.html?testName=audio-dft) 05:41:34 INFO - 2015-11-24 05:41:34,347 DEBUG : BROWSER_OUTPUT: RSS: Main: 167931904 05:41:34 INFO - 2015-11-24 05:41:34,347 DEBUG : BROWSER_OUTPUT: 05:41:37 INFO - 2015-11-24 05:41:37,207 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56852/tests/kraken/driver.html?testName=audio-fft) 05:41:37 INFO - 2015-11-24 05:41:37,760 DEBUG : BROWSER_OUTPUT: RSS: Main: 150863872 05:41:37 INFO - 2015-11-24 05:41:37,760 DEBUG : BROWSER_OUTPUT: 05:41:39 INFO - 2015-11-24 05:41:39,732 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56852/tests/kraken/driver.html?testName=audio-oscillator) 05:41:40 INFO - 2015-11-24 05:41:40,293 DEBUG : BROWSER_OUTPUT: RSS: Main: 151764992 05:41:40 INFO - 2015-11-24 05:41:40,293 DEBUG : BROWSER_OUTPUT: 05:41:42 INFO - 2015-11-24 05:41:42,486 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56852/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:41:43 INFO - 2015-11-24 05:41:43,047 DEBUG : BROWSER_OUTPUT: RSS: Main: 155533312 05:41:43 INFO - 2015-11-24 05:41:43,047 DEBUG : BROWSER_OUTPUT: 05:41:45 INFO - 2015-11-24 05:41:45,946 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56852/tests/kraken/driver.html?testName=imaging-darkroom) 05:41:46 INFO - 2015-11-24 05:41:46,528 DEBUG : BROWSER_OUTPUT: RSS: Main: 154005504 05:41:46 INFO - 2015-11-24 05:41:46,528 DEBUG : BROWSER_OUTPUT: 05:41:49 INFO - 2015-11-24 05:41:49,862 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56852/tests/kraken/driver.html?testName=imaging-desaturate) 05:41:50 INFO - 2015-11-24 05:41:50,432 DEBUG : BROWSER_OUTPUT: RSS: Main: 155824128 05:41:50 INFO - 2015-11-24 05:41:50,432 DEBUG : BROWSER_OUTPUT: 05:41:53 INFO - 2015-11-24 05:41:53,183 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56852/tests/kraken/driver.html?testName=json-parse-financial) 05:41:53 INFO - 2015-11-24 05:41:53,764 DEBUG : BROWSER_OUTPUT: RSS: Main: 154783744 05:41:53 INFO - 2015-11-24 05:41:53,765 DEBUG : BROWSER_OUTPUT: 05:41:55 INFO - 2015-11-24 05:41:55,297 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56852/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:41:55 INFO - 2015-11-24 05:41:55,864 DEBUG : BROWSER_OUTPUT: RSS: Main: 165076992 05:41:55 INFO - 2015-11-24 05:41:55,864 DEBUG : BROWSER_OUTPUT: 05:41:57 INFO - 2015-11-24 05:41:57,700 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56852/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:41:58 INFO - 2015-11-24 05:41:58,293 DEBUG : BROWSER_OUTPUT: RSS: Main: 163377152 05:41:58 INFO - 2015-11-24 05:41:58,293 DEBUG : BROWSER_OUTPUT: 05:42:00 INFO - 2015-11-24 05:42:00,345 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56852/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:42:00 INFO - 2015-11-24 05:42:00,918 DEBUG : BROWSER_OUTPUT: RSS: Main: 170147840 05:42:00 INFO - 2015-11-24 05:42:00,919 DEBUG : BROWSER_OUTPUT: 05:42:03 INFO - 2015-11-24 05:42:03,341 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56852/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:42:03 INFO - 2015-11-24 05:42:03,927 DEBUG : BROWSER_OUTPUT: RSS: Main: 179286016 05:42:03 INFO - 2015-11-24 05:42:03,927 DEBUG : BROWSER_OUTPUT: 05:42:06 INFO - 2015-11-24 05:42:06,462 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56852/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:42:07 INFO - 2015-11-24 05:42:07,043 DEBUG : BROWSER_OUTPUT: RSS: Main: 179363840 05:42:07 INFO - 2015-11-24 05:42:07,043 DEBUG : BROWSER_OUTPUT: 05:42:08 INFO - 2015-11-24 05:42:08,697 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56852/tests/kraken/driver.html?testName=ai-astar) 05:42:08 INFO - 2015-11-24 05:42:08,947 DEBUG : BROWSER_OUTPUT: RSS: Main: 238714880 05:42:08 INFO - 2015-11-24 05:42:08,947 DEBUG : BROWSER_OUTPUT: 05:42:08 INFO - 2015-11-24 05:42:08,947 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:42:08 INFO - 2015-11-24 05:42:08,949 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:42:08 INFO - 2015-11-24 05:42:08,949 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:42:08 INFO - 2015-11-24 05:42:08,949 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:42:08 INFO - 2015-11-24 05:42:08,949 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;110;105;109;109;111;101;109;110;101 05:42:08 INFO - 2015-11-24 05:42:08,949 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;124;150;175;126;151;158;124;166;122 05:42:08 INFO - 2015-11-24 05:42:08,950 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;159;159;161;160;161;160;162;160;162 05:42:08 INFO - 2015-11-24 05:42:08,950 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;73;88;91;71;74;71;103;93;71 05:42:08 INFO - 2015-11-24 05:42:08,950 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;94;143;92;93;93;137;91;92;140 05:42:08 INFO - 2015-11-24 05:42:08,950 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;110;109;109;109;110;109;109;110 05:42:08 INFO - 2015-11-24 05:42:08,950 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;145;147;146;147;145;147;145;145 05:42:08 INFO - 2015-11-24 05:42:08,951 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;95;96;96;96;95;96;97;96 05:42:08 INFO - 2015-11-24 05:42:08,951 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;58;57;57;58;57;57;57;57;59;56 05:42:08 INFO - 2015-11-24 05:42:08,951 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;70;70;70;70;73;71;69;69;70;70 05:42:08 INFO - 2015-11-24 05:42:08,951 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;85;84;86;84;85;94;85;87;86 05:42:08 INFO - 2015-11-24 05:42:08,951 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;125;123;120;113;119;121;120;125;115;124 05:42:08 INFO - 2015-11-24 05:42:08,952 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;139;162;147;149;138;152;144;148;142 05:42:08 INFO - 2015-11-24 05:42:08,952 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;66;67;66;66;66;67;65;66;67 05:42:08 INFO - 2015-11-24 05:42:08,952 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:42:08 INFO - 2015-11-24 05:42:08,952 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:42:08 INFO - 2015-11-24 05:42:08,952 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,923 05:42:08 INFO - 2015-11-24 05:42:08,953 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:42:08 INFO - 2015-11-24 05:42:08,953 DEBUG : BROWSER_OUTPUT: __startTimestamp1448372528947__endTimestamp 05:42:08 INFO - 2015-11-24 05:42:08,953 DEBUG : BROWSER_OUTPUT: 05:42:08 INFO - 2015-11-24 05:42:08,954 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:42:08 INFO - 2015-11-24 05:42:08,955 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:42:08 INFO - 2015-11-24 05:42:08,955 DEBUG : BROWSER_OUTPUT: 05:42:08 INFO - 2015-11-24 05:42:08,955 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.40 Median:109.00 stddev:3.72 (3.4%) stddev-sans-first:3.90 05:42:08 INFO - 2015-11-24 05:42:08,955 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 105.0 109.0 109.0 111.0 101.0 109.0 110.0 101.0 05:42:08 INFO - 2015-11-24 05:42:08,956 DEBUG : BROWSER_OUTPUT: 05:42:08 INFO - 2015-11-24 05:42:08,956 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.50 Median:150.50 stddev:19.31 (12.8%) stddev-sans-first:20.41 05:42:08 INFO - 2015-11-24 05:42:08,956 DEBUG : BROWSER_OUTPUT: Values: 149.0 124.0 150.0 175.0 126.0 151.0 158.0 124.0 166.0 122.0 05:42:08 INFO - 2015-11-24 05:42:08,956 DEBUG : BROWSER_OUTPUT: 05:42:08 INFO - 2015-11-24 05:42:08,957 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:1.08 (0.7%) stddev-sans-first:1.13 05:42:08 INFO - 2015-11-24 05:42:08,957 DEBUG : BROWSER_OUTPUT: Values: 161.0 159.0 159.0 161.0 160.0 161.0 160.0 162.0 160.0 162.0 05:42:08 INFO - 2015-11-24 05:42:08,957 DEBUG : BROWSER_OUTPUT: 05:42:08 INFO - 2015-11-24 05:42:08,957 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.80 Median:73.50 stddev:11.80 (16.1%) stddev-sans-first:12.18 05:42:08 INFO - 2015-11-24 05:42:08,958 DEBUG : BROWSER_OUTPUT: Values: 73.0 73.0 88.0 91.0 71.0 74.0 71.0 103.0 93.0 71.0 05:42:08 INFO - 2015-11-24 05:42:08,958 DEBUG : BROWSER_OUTPUT: 05:42:08 INFO - 2015-11-24 05:42:08,958 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.80 Median:92.50 stddev:22.97 (24.8%) stddev-sans-first:23.81 05:42:08 INFO - 2015-11-24 05:42:08,958 DEBUG : BROWSER_OUTPUT: Values: 93.0 94.0 143.0 92.0 93.0 93.0 137.0 91.0 92.0 140.0 05:42:08 INFO - 2015-11-24 05:42:08,959 DEBUG : BROWSER_OUTPUT: 05:42:08 INFO - 2015-11-24 05:42:08,959 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.40 Median:109.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 05:42:08 INFO - 2015-11-24 05:42:08,959 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 110.0 109.0 109.0 109.0 110.0 109.0 109.0 110.0 05:42:08 INFO - 2015-11-24 05:42:08,959 DEBUG : BROWSER_OUTPUT: 05:42:08 INFO - 2015-11-24 05:42:08,960 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 05:42:08 INFO - 2015-11-24 05:42:08,960 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 145.0 147.0 146.0 147.0 145.0 147.0 145.0 145.0 05:42:08 INFO - 2015-11-24 05:42:08,960 DEBUG : BROWSER_OUTPUT: 05:42:08 INFO - 2015-11-24 05:42:08,960 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 05:42:08 INFO - 2015-11-24 05:42:08,960 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 95.0 96.0 96.0 96.0 95.0 96.0 97.0 96.0 05:42:08 INFO - 2015-11-24 05:42:08,960 DEBUG : BROWSER_OUTPUT: 05:42:08 INFO - 2015-11-24 05:42:08,960 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.30 Median:57.00 stddev:0.82 (1.4%) stddev-sans-first:0.83 05:42:08 INFO - 2015-11-24 05:42:08,961 DEBUG : BROWSER_OUTPUT: Values: 58.0 57.0 57.0 58.0 57.0 57.0 57.0 57.0 59.0 56.0 05:42:08 INFO - 2015-11-24 05:42:08,961 DEBUG : BROWSER_OUTPUT: 05:42:08 INFO - 2015-11-24 05:42:08,961 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:70.00 stddev:1.14 (1.6%) stddev-sans-first:1.20 05:42:08 INFO - 2015-11-24 05:42:08,961 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 70.0 70.0 73.0 71.0 69.0 69.0 70.0 70.0 05:42:08 INFO - 2015-11-24 05:42:08,961 DEBUG : BROWSER_OUTPUT: 05:42:08 INFO - 2015-11-24 05:42:08,961 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:85.50 stddev:2.98 (3.5%) stddev-sans-first:3.07 05:42:08 INFO - 2015-11-24 05:42:08,961 DEBUG : BROWSER_OUTPUT: Values: 84.0 85.0 84.0 86.0 84.0 85.0 94.0 85.0 87.0 86.0 05:42:08 INFO - 2015-11-24 05:42:08,961 DEBUG : BROWSER_OUTPUT: 05:42:08 INFO - 2015-11-24 05:42:08,962 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:120.50 Median:122.00 stddev:4.06 (3.3%) stddev-sans-first:3.97 05:42:08 INFO - 2015-11-24 05:42:08,962 DEBUG : BROWSER_OUTPUT: Values: 125.0 123.0 120.0 113.0 119.0 121.0 120.0 125.0 115.0 124.0 05:42:08 INFO - 2015-11-24 05:42:08,962 DEBUG : BROWSER_OUTPUT: 05:42:08 INFO - 2015-11-24 05:42:08,962 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.20 Median:147.50 stddev:7.18 (4.9%) stddev-sans-first:7.36 05:42:08 INFO - 2015-11-24 05:42:08,962 DEBUG : BROWSER_OUTPUT: Values: 141.0 139.0 162.0 147.0 149.0 138.0 152.0 144.0 148.0 142.0 05:42:08 INFO - 2015-11-24 05:42:08,962 DEBUG : BROWSER_OUTPUT: 05:42:08 INFO - 2015-11-24 05:42:08,962 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:0.67 (1.0%) stddev-sans-first:0.67 05:42:08 INFO - 2015-11-24 05:42:08,962 DEBUG : BROWSER_OUTPUT: Values: 67.0 66.0 67.0 66.0 66.0 66.0 67.0 65.0 66.0 67.0 05:42:08 INFO - 2015-11-24 05:42:08,962 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:42:08 INFO - 2015-11-24 05:42:08,962 DEBUG : BROWSER_OUTPUT: 05:42:09 INFO - 2015-11-24 05:42:09,744 INFO : Browser exited with error code: 0 05:42:09 INFO - 2015-11-24 05:42:09,801 INFO : Completed test kraken (00:00:57) 05:42:09 INFO - 2015-11-24 05:42:09,801 INFO : Starting test v8_7 05:42:09 INFO - 2015-11-24 05:42:09,801 DEBUG : operating with platform_type : linux_ 05:42:09 INFO - 2015-11-24 05:42:09,801 INFO : Initialising browser for v8_7 test... 05:42:09 INFO - 2015-11-24 05:42:09,807 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpPPCRkz/profile http://localhost:56852/getInfo.html 05:42:13 INFO - 2015-11-24 05:42:13,322 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:42:13 INFO - 2015-11-24 05:42:13,323 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:42:13 INFO - 2015-11-24 05:42:13,323 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:42:13 INFO - 2015-11-24 05:42:13,323 DEBUG : BROWSER_OUTPUT: __metrics 05:42:13 INFO - 2015-11-24 05:42:13,439 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:42:14 INFO - 2015-11-24 05:42:14,478 INFO : Browser initialized. 05:42:14 INFO - 2015-11-24 05:42:14,478 INFO : Running cycle 1/1 for v8_7 test... 05:42:14 INFO - 2015-11-24 05:42:14,478 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpPPCRkz/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:42:26 INFO - 2015-11-24 05:42:26,229 DEBUG : BROWSER_OUTPUT: RSS: Main: 138940416 05:42:26 INFO - 2015-11-24 05:42:26,229 DEBUG : BROWSER_OUTPUT: 05:42:47 INFO - 2015-11-24 05:42:47,645 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56852/tests/v8_7/run.html (next: http://localhost:56852/tests/v8_7/run.html) 05:42:47 INFO - 2015-11-24 05:42:47,895 DEBUG : BROWSER_OUTPUT: RSS: Main: 616640512 05:42:47 INFO - 2015-11-24 05:42:47,895 DEBUG : BROWSER_OUTPUT: 05:42:47 INFO - 2015-11-24 05:42:47,895 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:42:47 INFO - 2015-11-24 05:42:47,896 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:42:47 INFO - 2015-11-24 05:42:47,896 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:42:47 INFO - 2015-11-24 05:42:47,896 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:42:47 INFO - 2015-11-24 05:42:47,896 DEBUG : BROWSER_OUTPUT: |0;Richards;164.31153466973382 05:42:47 INFO - 2015-11-24 05:42:47,896 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;188.82175226586102 05:42:47 INFO - 2015-11-24 05:42:47,896 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.3296089385475 05:42:47 INFO - 2015-11-24 05:42:47,896 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5181.347150259067 05:42:47 INFO - 2015-11-24 05:42:47,896 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1083.4236186348862 05:42:47 INFO - 2015-11-24 05:42:47,896 DEBUG : BROWSER_OUTPUT: |5;Earley;775.1937984496124 05:42:47 INFO - 2015-11-24 05:42:47,897 DEBUG : BROWSER_OUTPUT: |6;Boyer;12204.819277108434 05:42:47 INFO - 2015-11-24 05:42:47,897 DEBUG : BROWSER_OUTPUT: |7;RegExp;25575 05:42:47 INFO - 2015-11-24 05:42:47,897 DEBUG : BROWSER_OUTPUT: |8;Splay;625.7822277847309 05:42:47 INFO - 2015-11-24 05:42:47,897 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 05:42:47 INFO - 2015-11-24 05:42:47,897 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:42:47 INFO - 2015-11-24 05:42:47,897 DEBUG : BROWSER_OUTPUT: __startTimestamp1448372567895__endTimestamp 05:42:47 INFO - 2015-11-24 05:42:47,897 DEBUG : BROWSER_OUTPUT: 05:42:47 INFO - 2015-11-24 05:42:47,897 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:42:47 INFO - 2015-11-24 05:42:47,897 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:42:47 INFO - 2015-11-24 05:42:47,897 DEBUG : BROWSER_OUTPUT: 05:42:47 INFO - 2015-11-24 05:42:47,897 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.31 Median:164.31 stddev:0.00 (0.0%) 05:42:47 INFO - 2015-11-24 05:42:47,898 DEBUG : BROWSER_OUTPUT: Values: 164.3 05:42:47 INFO - 2015-11-24 05:42:47,898 DEBUG : BROWSER_OUTPUT: 05:42:47 INFO - 2015-11-24 05:42:47,898 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:188.82 Median:188.82 stddev:0.00 (0.0%) 05:42:47 INFO - 2015-11-24 05:42:47,898 DEBUG : BROWSER_OUTPUT: Values: 188.8 05:42:47 INFO - 2015-11-24 05:42:47,898 DEBUG : BROWSER_OUTPUT: 05:42:47 INFO - 2015-11-24 05:42:47,898 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.33 Median:279.33 stddev:0.00 (0.0%) 05:42:47 INFO - 2015-11-24 05:42:47,898 DEBUG : BROWSER_OUTPUT: Values: 279.3 05:42:47 INFO - 2015-11-24 05:42:47,898 DEBUG : BROWSER_OUTPUT: 05:42:47 INFO - 2015-11-24 05:42:47,899 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5181.35 Median:5181.35 stddev:0.00 (0.0%) 05:42:47 INFO - 2015-11-24 05:42:47,899 DEBUG : BROWSER_OUTPUT: Values: 5181.3 05:42:47 INFO - 2015-11-24 05:42:47,900 DEBUG : BROWSER_OUTPUT: 05:42:47 INFO - 2015-11-24 05:42:47,900 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1083.42 Median:1083.42 stddev:0.00 (0.0%) 05:42:47 INFO - 2015-11-24 05:42:47,900 DEBUG : BROWSER_OUTPUT: Values: 1083.4 05:42:47 INFO - 2015-11-24 05:42:47,900 DEBUG : BROWSER_OUTPUT: 05:42:47 INFO - 2015-11-24 05:42:47,900 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:775.19 Median:775.19 stddev:0.00 (0.0%) 05:42:47 INFO - 2015-11-24 05:42:47,900 DEBUG : BROWSER_OUTPUT: Values: 775.2 05:42:47 INFO - 2015-11-24 05:42:47,900 DEBUG : BROWSER_OUTPUT: 05:42:47 INFO - 2015-11-24 05:42:47,900 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12204.82 Median:12204.82 stddev:0.00 (0.0%) 05:42:47 INFO - 2015-11-24 05:42:47,900 DEBUG : BROWSER_OUTPUT: Values: 12204.8 05:42:47 INFO - 2015-11-24 05:42:47,900 DEBUG : BROWSER_OUTPUT: 05:42:47 INFO - 2015-11-24 05:42:47,900 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25575.00 Median:25575.00 stddev:0.00 (0.0%) 05:42:47 INFO - 2015-11-24 05:42:47,900 DEBUG : BROWSER_OUTPUT: Values: 25575.0 05:42:47 INFO - 2015-11-24 05:42:47,900 DEBUG : BROWSER_OUTPUT: 05:42:47 INFO - 2015-11-24 05:42:47,901 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:625.78 Median:625.78 stddev:0.00 (0.0%) 05:42:47 INFO - 2015-11-24 05:42:47,901 DEBUG : BROWSER_OUTPUT: Values: 625.8 05:42:47 INFO - 2015-11-24 05:42:47,901 DEBUG : BROWSER_OUTPUT: 05:42:47 INFO - 2015-11-24 05:42:47,901 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 05:42:47 INFO - 2015-11-24 05:42:47,901 DEBUG : BROWSER_OUTPUT: Values: 4830.9 05:42:47 INFO - 2015-11-24 05:42:47,901 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:42:47 INFO - 2015-11-24 05:42:47,901 DEBUG : BROWSER_OUTPUT: 05:42:48 INFO - 2015-11-24 05:42:48,668 INFO : Browser exited with error code: 0 05:42:48 INFO - 2015-11-24 05:42:48,683 INFO : Completed test v8_7 (00:00:38) 05:42:48 INFO - 2015-11-24 05:42:48,890 INFO : Completed test suite (00:20:09) 05:42:48 INFO - 2015-11-24 05:42:48,890 DEBUG : Working with test: dromaeo_css 05:42:48 INFO - 2015-11-24 05:42:48,890 DEBUG : Generating results file: dromaeo_css 05:42:48 INFO - 2015-11-24 05:42:48,891 DEBUG : Working with test: kraken 05:42:48 INFO - 2015-11-24 05:42:48,891 DEBUG : Generating results file: kraken 05:42:48 INFO - 2015-11-24 05:42:48,892 INFO : javascript benchmark 05:42:48 INFO - 2015-11-24 05:42:48,892 DEBUG : Working with test: v8_7 05:42:48 INFO - 2015-11-24 05:42:48,892 DEBUG : Generating results file: v8_7 05:42:48 INFO - 2015-11-24 05:42:48,892 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:42:48 INFO - 2015-11-24 05:42:48,939 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,63,35]] 05:42:48 INFO - 2015-11-24 05:42:48,939 DEBUG : process_Request line: dromaeo_css 6891.18 graph.html#tests=[[72,63,35]] 05:42:48 INFO - 2015-11-24 05:42:48,939 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:42:48 INFO - 2015-11-24 05:42:48,969 DEBUG : process_Request line: kraken 1497.70 graph.html#tests=[[232,63,35]] 05:42:48 INFO - 2015-11-24 05:42:48,969 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:42:49 INFO - 2015-11-24 05:42:48,999 DEBUG : process_Request line: v8_7 21102.54 graph.html#tests=[[230,63,35]] 05:42:49 INFO - 2015-11-24 05:42:49,004 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3185.251465905512, 3239.4169049571096, 3283.1463819406963, 3296.307775367173, 3314.4696848504245, 5237.5548078413285, 5331.493508116728, 5339.41266460689, 5413.972930135349, 5475.753591088405, 5370.5435038021815, 5452.454754524546, 5506.779728810853, 5516.558675305976, 5520.089185284433, 4965.478624744395, 4987.127252730776, 5005.899882002376, 5033.194688849785, 5084.593232541388, 4813.253945638432, 5009.0, 5025.623078269145, 5058.822941197059, 5099.235114732783, 4838.443578988408, 4903.509649035086, 4907.092187945242, 4927.901441971177, 4938.58022068121, 4966.106100901836, 4966.801327946878, 4972.65191436596, 4979.4771548987555, 5006.924896126561, 4783.760811959423, 4946.208606622959, 4954.975225123887, 4960.677555958851, 5018.874528136789, 5144.588432925394, 5192.688438693691, 5212.556932660728, 5230.189320655267, 5269.473052694738, 5856.267966504187, 5875.236219291465, 5882.647041177543, 5886.381929897385, 5897.941020589782, 5629.60592758507, 5910.408959104056, 5917.14201440793, 5924.466797988159, 5934.13954976526, 5350.5452036576935, 5370.838874833722, 5455.236266922634, 5590.329160500766, 5601.579881508903, 5995.880082398327, 6007.669578173155, 6015.1278064681255, 6028.336882942873, 6068.878622427526, 5681.744321505542, 5787.536997040311, 5794.8551286217335, 5801.390853960358, 5860.00379935412, 5685.403032681592, 5808.970955145197, 5841.649501029951, 5887.264091988502, 5890.4698577128265, 5849.590528663038, 5877.147813567094, 5882.647041177543, 5891.3814049525035, 5926.348101708809, 5827.475527202488, 5828.300603927556, 5828.562857785683, 5831.475167234969, 5886.705664716747, 4374.540673229329, 4463.4420697412825, 4479.014616784302, 4549.613282870927, 4549.681522293407, 4023.275810354164, 4031.697622678311, 4037.6164264394833, 4042.0905296308097, 4077.1641813428178, 5583.860403489864, 5587.826556423115, 5589.133684278943, 5589.608727389125, 5638.464345887134, 5743.913841292384, 5836.0, 5855.941440585625, 5907.409259074144, 5987.341392446828, 5585.776568937278, 5612.354579223442, 5618.943810561842, 5622.156676498558, 5626.240457538262, 5179.093658609803, 5203.479652034842, 5209.739513024334, 5212.9739351303, 5253.605979551549, 5901.911471327933, 5997.940020599825, 6033.366496517802, 6042.0, 6116.204893363834, 5866.472017518444, 5882.0, 5885.852853678693, 5897.64614123154, 5954.523638109027, 6010.7295171716505, 6015.1278064681255, 6020.789272375446, 6023.397660233942, 6080.361562035922, 5511.283533140666, 5584.720763961867, 5630.943690563042, 5649.576281778801, 5661.801836935688, 5741.0, 5763.02028655138, 5785.016547187072, 5794.971025144847, 5795.7391917362975, 5643.125315576092, 5661.2923384576925, 5671.319441667027, 5674.432556744293, 5777.191193232867, 5762.8847423050465, 5766.769329226951, 5774.0, 5797.681127537857, 5826.592138550431, 5660.688662123623, 5663.235463212578, 5665.291838520185, 5667.688277144796, 5682.289713785777, 5574.191742197439, 5651.208830763614, 5683.318001839806, 5754.309482862083, 5802.448767367094, 5588.832335029955, 5674.347450043133, 5677.801276955287, 5679.801206957902, 5720.3707592164], "unit": "ms", "name": "dojo.html", "value": 5395.936428602412}, {"lowerIsBetter": false, "replicates": [585.1164741240726, 588.0855270055065, 588.8733922206725, 596.1951365656362, 599.0774207720111, 30895.38209235814, 39369.2126157477, 39783.00542486436, 39814.800925995405, 39867.60132398675, 43231.37992061028, 43810.78094609531, 43898.561014389845, 43944.56055439453, 43950.340744888854, 7513.135989361212, 7616.885746713804, 8653.437526560769, 8899.37704360695, 8901.77745556363, 10485.37087774736, 10701.518431670593, 10705.946470267676, 10717.83923241152, 10718.142548596132, 10275.177985761158, 10409.68770936873, 10468.947655261712, 10472.057514823664, 10485.318454300446, 7698.807529811744, 7886.526808391457, 10331.380117192955, 10508.054275115277, 10795.29830561011, 10216.335938163995, 10342.689719308357, 10352.378857268588, 10355.378677279386, 10358.741031474197, 39237.215255694726, 39473.40789888154, 39775.20449590992, 39938.0, 40091.59908400908, 3253.8535765890592, 3285.671432856719, 3348.029071569242, 3364.34395292918, 3605.4952306677087, 30600.387992240252, 30808.691913080806, 31058.223544411343, 31127.0, 31730.365392692016, 3917.157811070605, 3928.7249892507534, 3993.381025940984, 4005.919881602381, 4040.979795101035, 266434.0, 266662.0, 266949.0, 266950.66524667444, 268287.0, 56891.14663280054, 58241.4175858236, 58902.410975889696, 65580.67209663922, 67008.66495667491, 59873.70063149656, 60193.0, 60227.096593551134, 60336.396636033955, 60348.0, 14773.92613036928, 14927.179005154821, 14930.178840163895, 14957.252137393087, 14957.326920288395, 11173.776524469466, 11226.382548959886, 11230.3261804292, 11236.831447528293, 11249.10007199422, 19618.80381196198, 19706.31027914016, 19719.309824156087, 19732.802671973095, 20040.599188016156, 7319.5242309249725, 7394.890766385085, 7467.141278753013, 7497.925020749832, 7610.467267291237, 953.3755390219418, 957.0764212534938, 961.9086186812242, 992.8014397120461, 1037.0459177556666, 28387.432251354858, 30012.24969375783, 31302.217444563612, 31481.0, 31742.523862142087, 957.4351132831663, 1048.3814549415729, 1053.1837825685154, 1100.9925917785297, 1126.2285334545863, 14665.633359165893, 15370.0, 15663.102326069486, 15730.528084157493, 16020.359185632444, 306386.0, 309857.0, 309971.0, 310691.0, 311172.0, 53058.0, 53410.0, 53477.465225347245, 53698.73150634222, 53703.0, 1312.0, 1338.4779935825231, 1346.4344975110478, 1356.1795113956111, 1358.517726207202, 22287.665685014414, 26414.0, 26648.0, 27139.86430067837, 27179.456410872066, 40286.0, 40913.795431022656, 40969.77090687165, 41029.589704102575, 41061.17877642371, 202493.0, 223732.0, 242549.0, 280197.0, 289550.0, 31251.0, 31376.68623313829, 31521.21196970094, 31949.520757188195, 31974.840125799223, 37995.0, 39126.2174756509, 39458.60541394549, 39480.2103957925, 42346.0, 32636.510452342758, 33982.320353593284, 35546.46680299746, 37345.626543734215, 37356.49644634189, 35850.64149358473, 36311.455328170625, 36851.81574092113, 36977.07557311089, 37097.814510927274, 35534.46698299579, 36213.09467263339, 36461.27077458383, 36691.63308366989, 36775.0, 31622.841885790425, 33170.0, 33423.665763342055, 33504.16239593932, 33559.66440335565, 24893.626595601443, 25060.373216246466, 25208.621870671588, 25320.366990825376, 25336.239912802645], "unit": "ms", "name": "ext.html", "value": 18599.01628441283}, {"lowerIsBetter": false, "replicates": [353.79303107682006, 356.1807841963483, 356.6576086956522, 359.73020234823883, 360.4935066231943, 14291.71416571668, 14441.783373249384, 16269.34922603096, 16536.173191340444, 17223.569410764736, 15725.719770633252, 16547.172641367913, 16891.23989420476, 16906.154692265398, 17014.659706805858, 5330.573554115671, 5480.3971563128025, 5484.808031718891, 5488.341399032121, 5490.258815059976, 3985.780782057, 4088.407180958759, 5351.438098999607, 5380.461953804627, 5783.768649254025, 5796.50729687977, 5826.650400975934, 5837.9124313135335, 5843.970780146094, 5853.824385268427, 5295.20571914214, 5323.0, 5342.225377320306, 5445.373782015079, 5840.737166827482, 5076.746162691888, 5159.974200128975, 5171.172612382001, 5173.068847607429, 5175.534201921808, 19755.20979160832, 20271.89864050685, 20276.290329838386, 20391.0, 20921.476963075893, 1983.097690550796, 2017.3141132015185, 2129.7018417421573, 2153.5800518898823, 2392.4497365605835, 9469.0, 9570.521473926276, 9674.0, 9761.51192440375, 9839.93361394108, 1744.5135050266965, 1826.1873466307497, 1871.7847447543434, 1882.2565086790778, 1891.3096719697348, 59760.40239597635, 61965.69017154886, 63701.362986370004, 63949.36050639527, 64184.679076604785, 10049.145822605014, 10088.0, 10127.797444051077, 10138.645147419806, 10172.084512393772, 13266.469341226242, 13295.069345145746, 13299.401526931311, 13301.334933253298, 13301.933490332487, 7416.517926334771, 7472.252774722593, 7484.925150748531, 7494.887576686354, 7639.656215470317, 7169.713211471587, 7185.712571497186, 7289.343959043712, 7317.12194536659, 7357.8160545986775, 4269.018125831076, 4324.221640104748, 4333.43665323506, 4377.671674624416, 4386.912261754811, 1571.9764203536956, 1602.190893598725, 1624.5207663739225, 1640.6882692288427, 1668.3576822923262, 862.1723145780121, 876.2114097312469, 914.446759710379, 931.4969916245152, 989.7674046599044, 14247.21640309793, 16286.592835179215, 16301.67396652084, 16623.91688041552, 16912.746308805377, 889.8309321228945, 895.8297923394534, 940.2337095267335, 975.9512024398894, 1052.3370276725766, 9113.726588202364, 9229.400088994194, 9277.90722092784, 9280.0, 9382.812343753223, 537149.0, 552311.0, 554282.9716068442, 554399.0, 565339.0, 76762.6161869187, 76770.23229767743, 77259.22740772633, 77317.0, 77784.0, 1110.0231796019452, 1113.8663360396642, 1117.8267368557886, 1120.5741818109066, 1137.3801278303345, 14791.890608204212, 14867.479638212611, 15042.548723538312, 15085.170315632746, 15319.387224510894, 32666.510002349507, 32904.67095329112, 33200.501992469646, 34199.829000854836, 35612.82193589119, 40471.0, 41325.0, 41637.167256654095, 41948.0, 42049.78975105105, 8531.274841638531, 8554.828903422022, 8706.390552661253, 8742.38803283764, 8796.384253102222, 10272.33229840058, 10369.896301037195, 10389.792204155723, 10447.0, 10476.528556214836, 9980.60077596888, 13548.458061677817, 13612.727745445234, 14091.859081409055, 14551.126932384092, 14176.645583860074, 14408.567742967727, 14640.557886696675, 14711.779323309946, 14718.852811471748, 11185.608503702331, 11233.71915702114, 13986.090904091201, 14045.36795844169, 14642.414303427744, 8920.55397230123, 9122.452652840851, 9465.148136667733, 9465.952670236606, 9477.857832132662, 9969.0, 9988.450635215133, 13567.660808479472, 13761.587152385444, 14460.710785784015], "unit": "ms", "name": "jquery.html", "value": 8542.903821034723}, {"lowerIsBetter": false, "replicates": [1365.1536047650459, 1645.2760785254488, 1723.3709695960974, 1724.310275889644, 1763.497403240076, 6128.387161283869, 7039.296070392958, 7204.819879503015, 7212.495125341228, 7214.567125972445, 6982.16214054313, 7137.928620713791, 7141.71433142675, 7154.320339567746, 7164.03285556451, 1939.1952339778975, 1970.7635083789964, 1973.5756812285356, 2163.361808266561, 2201.746799118098, 1973.7828838827788, 2208.569328980849, 2217.6230040893006, 2253.9436514087115, 2255.1655887321713, 2181.530970841277, 2214.8560343577697, 2221.6334304839684, 2222.533268013719, 2222.8555143915673, 1924.4034349351746, 1973.477028587411, 2226.810721088709, 2232.497688020199, 2233.8771367574777, 2197.1540956731615, 2229.375774783063, 2233.698450709166, 2234.3743751749535, 2235.7540670526228, 6925.896111558331, 7026.086608740831, 7086.468514861406, 7091.397231235351, 7093.3970612498, 4474.865754027384, 4557.79489922951, 4571.291449825282, 4574.222382195002, 4592.471865735416, 2977.7171168738937, 3136.9372612547622, 3145.1979745165067, 3154.4479716049827, 3155.54244634529, 1010.7473131717071, 1023.3399457349967, 1060.581070477165, 1068.1988508618535, 1114.5374669512144, 9702.805943881083, 9711.514424278757, 9716.805663886682, 9721.90278097217, 9726.0, 4123.8556650517085, 4194.098268872207, 4235.110626768352, 4237.724547904377, 4246.915061698749, 8346.791330216673, 8352.0, 8354.624041918129, 8362.916370836214, 8377.120402357665, 4569.520200378979, 4606.907861842745, 4607.953920460753, 4608.838690645811, 4613.884652883705, 4204.957950420518, 4215.557366476537, 4223.239816832938, 4227.598378154071, 4230.1539692061715, 3442.3287458945265, 3504.456809194579, 3517.56030496188, 3530.1704099536587, 3552.875649352312, 1178.699431644925, 1192.797224471842, 1200.4057991294364, 1204.9216800907914, 1206.1315852586124, 1147.3460127727283, 1280.3022352817736, 1299.3243513372993, 1321.3525372567374, 1327.1042046618686, 6804.08144900442, 6825.795226143224, 6834.179898412222, 6838.384545390839, 6840.486963477759, 452.87093178443985, 452.93885325480636, 455.97264164150255, 460.82949308755974, 497.1796535716097, 5101.336826212569, 5157.277981082651, 5329.094054010828, 5571.526420254324, 5687.03320435527, 8086.919130808734, 8187.549684767276, 8216.46592971455, 8218.054923683734, 8234.341252699769, 8168.183181681784, 8299.377546684023, 8431.451955622864, 8457.238848503664, 8458.534780587004, 6663.300353462913, 6732.730690772412, 6747.932520674829, 6748.628825414551, 6778.830529236809, 6608.768693095719, 6701.731930722716, 6703.497237707289, 6704.932950670431, 6725.596464212163, 7349.154847192642, 7433.888491672521, 7527.435442341955, 7586.582737949466, 7607.125180604301, 7659.272369124924, 7661.118971318369, 7668.731594394169, 7755.3407960324, 7813.882791758242, 6516.0, 6579.2104947404705, 6587.440067594308, 6587.670616469253, 6597.406233438822, 6598.934010660024, 6775.830604234737, 7031.472639551952, 7077.186123595852, 7104.680289387094, 6475.417212450902, 6511.576747511396, 6565.901511477427, 6638.83402914931, 6644.900326495203, 6107.969460152671, 6396.392342727433, 6504.089427480251, 6508.511861610304, 6537.607743535213, 6512.381323774234, 6527.314631963575, 6567.211934567882, 6570.671466426564, 6578.80263592093, 5875.500582450369, 5899.882002360015, 5910.822675319747, 5911.438413350725, 5921.851953701192, 6085.695715214133, 6158.507319414346, 6166.229221347332, 6179.16581261524, 6186.226721659793], "unit": "ms", "name": "mootools.html", "value": 4162.260013912988}, {"lowerIsBetter": false, "replicates": [339.694275152363, 340.6287147009759, 341.34121146187846, 342.74123037106983, 349.26305495404694, 5970.880582388355, 6002.189704389912, 6084.300305464862, 6096.542759293048, 6105.938940610593, 5711.114777209539, 5783.277090363705, 5848.444397782215, 5996.070609055603, 6001.129836173752, 4530.818767249306, 4582.931256031162, 4590.311453282001, 4594.218982772936, 4595.2188128018315, 4577.93133103002, 4590.816367345302, 4612.215923293032, 4613.792379342937, 4618.191816432128, 4377.562243775629, 4462.419885414892, 4491.887702807423, 4544.818347229728, 4570.817167313304, 4551.271796512542, 4558.749268790215, 4559.042601053783, 4576.862694119149, 4580.862574122748, 4017.658499027586, 4021.296273152216, 4023.839046438139, 4025.8590949316927, 4029.4560234368278, 5737.627054241461, 5747.885042299131, 5750.166225897222, 5752.712364381764, 5756.884862302731, 5241.161414173714, 5362.946370536322, 5380.0, 5382.0, 5389.703566303851, 6408.487321014308, 6411.3909178627955, 6421.5825971311715, 6422.42198202164, 6422.972324428069, 4463.486699029589, 4467.508574056851, 4472.239719247736, 4475.373447717322, 4476.239039363316, 6507.154069970921, 6605.471562275055, 6648.4681225501845, 6665.000249962496, 6670.766523171715, 4571.634269258451, 4574.542545745466, 4577.22187228172, 4578.175928332866, 4584.816607335736, 4631.397918270603, 4631.861044168681, 4633.698809577367, 4638.373819534319, 4639.86080417588, 5325.573954083664, 5336.893262134813, 5340.172273297645, 5343.465653434625, 5369.48989845964, 6289.056641503719, 6353.5234857385885, 6426.036094585849, 6434.453071488882, 6451.870962580722, 1626.8779841511935, 1644.9424270150487, 1645.7942757155356, 1647.1517168658152, 1648.077076836975, 1098.4892025208317, 1099.340395762536, 1099.4502748625687, 1120.697411698853, 1124.668222874254, 6218.725161342005, 6224.533160013018, 6225.906611400833, 6228.719707613168, 6265.966115590968, 6663.267040625527, 6669.899951500732, 6677.632730199788, 6679.86640267192, 6683.897156969046, 6107.236595425572, 6107.297660769068, 6216.160818289473, 6292.905606415908, 6303.243610766737, 3606.783592984377, 3624.9456258156147, 3631.891043268706, 3642.7450078494253, 3642.9453558196647, 6946.583205007716, 6949.339812717783, 6964.0598519200275, 6965.686544105527, 6967.710973469924, 6688.498362622823, 6851.314868513109, 6854.760083397157, 6870.725170993204, 6872.003559483846, 6944.270851560514, 6947.325279767326, 6958.547694399746, 6961.095057642474, 6961.686724097428, 6601.570897891622, 6715.966420167868, 6717.966410167918, 6731.730730770714, 6733.158355205599, 6569.343065693392, 6600.504962127764, 6610.4711623071, 6614.305497922649, 6618.801435956929, 6909.861802764017, 6914.274001229798, 6921.965390173017, 6922.930770692228, 6955.513114081966, 4552.613027892534, 4553.726776393427, 4554.294084416921, 4563.520830312902, 4564.0, 6545.21457425093, 6593.142891424084, 6600.933990660032, 6603.20761508622, 6625.204975402982, 6455.838604034937, 6501.187351581052, 6503.642299673564, 6504.60972341661, 6508.316626754123, 6481.902771458337, 6485.578437401554, 6489.059086432535, 6490.123833282447, 6492.480601551959, 6211.751529938751, 6308.747650093945, 6322.399372059647, 6400.743970241325, 6406.615603063831, 6490.415862572391, 6521.184851893513, 6548.0832683423405, 6556.2788093308745, 6560.671966401756, 6249.468795152281, 6355.0, 6393.552451328363, 6395.808125756234, 6404.231492220963], "unit": "ms", "name": "prototype.html", "value": 4858.238925188738}, {"lowerIsBetter": false, "replicates": [822.6832669422271, 828.432523721251, 835.2858306148247, 840.6973489543764, 844.2992316377404, 13572.728545429085, 13767.311634418289, 13814.585562433143, 13843.930780346096, 13849.446022159114, 12622.873771262284, 12827.871721282785, 14174.078684885475, 14393.712125757505, 14451.783223251634, 689.4828878341244, 805.2631841864702, 825.038829763325, 826.1243082332717, 827.7433995461396, 690.1063123255396, 819.0376307838296, 821.2403526737744, 830.2776584371605, 833.3041910005122, 851.936104792143, 859.1966511311912, 868.6612221233723, 868.9087645797196, 869.826034793044, 719.8200449887528, 725.0900120348981, 859.2395729779128, 862.9439086459391, 864.0107077396377, 639.68015992004, 642.6561789442673, 642.9292777794439, 644.1754554170623, 646.053531576243, 13641.795373069412, 13775.104618199884, 13785.0, 13810.65473363164, 13814.0, 11673.824892626617, 11855.46650400734, 11955.880441195564, 12153.392330383534, 12220.633380998495, 10126.54430550627, 10249.0, 10287.742806429824, 10418.635347762793, 10864.837027444595, 3580.3555360035184, 3637.781733096023, 3642.25333806569, 3659.963400365989, 3761.492198553188, 47390.7630461849, 47475.525244747456, 47495.525044749455, 47626.285605715944, 47640.761796191146, 8978.236849867704, 9172.220361269367, 9363.204127649089, 9653.0, 9732.367396119229, 16839.915800421164, 16859.578510537336, 16862.831371686127, 16886.493405197863, 16910.15449227534, 7445.702171913063, 7554.735584254525, 7737.0, 7851.607419628996, 7950.562719050507, 4449.354843547668, 4453.309736990772, 4473.4408198975125, 4497.437820272466, 4652.62778977681, 3067.156531953731, 3071.877124915023, 3104.891328803481, 3105.4099721052926, 3108.3006323577465, 552.4917076289823, 570.6433479075578, 571.0035987202275, 578.6817250512202, 579.2816907035246, 546.2761840561272, 547.5181839980792, 559.5491746151829, 582.3535875178549, 676.9255381907986, 8835.851339325975, 8939.837204211177, 9234.584443700049, 9263.861042084374, 9322.30082743797, 489.7918384686565, 496.3473032961644, 549.1844610753028, 647.2362612117746, 676.2223443040444, 3862.343401621731, 4008.218397412535, 4099.221147981874, 4113.979430102891, 4189.958100419018, 51524.227136592985, 51593.0, 53685.0, 58064.41935580675, 58310.416895831346, 50877.491225088015, 51336.486635133915, 51873.2219016715, 51925.740371298656, 52225.47774522282, 46614.5338546617, 47633.523664763605, 48145.277820832714, 48303.516964830604, 48416.75791621019, 7938.960305198437, 8114.391420643594, 8151.918480815116, 8255.133211012757, 8286.792830179294, 35270.0, 35596.0, 35664.65030245484, 35696.64303356933, 36057.0, 41696.0, 43935.0, 45986.31020534762, 46002.30996534988, 46239.0, 10221.795564088528, 10290.588376465157, 10318.48407579603, 10356.741081472723, 10391.51444429495, 11332.6033588824, 11529.827052594386, 11552.768944620891, 11658.76682466329, 11969.0, 10289.588416463257, 10332.638357657446, 10393.630032698378, 10474.895251047392, 11007.0, 9938.950305248427, 10193.490325483845, 10289.9485502572, 10360.222983276435, 10421.478926053818, 10512.684619461428, 10643.414612196404, 10751.623693171014, 11025.724356890822, 11074.446277685922, 8521.957390213009, 8624.0, 8659.97588938901, 8737.388382813142, 8979.10208979097, 8311.272119269659, 8535.31717462614, 8608.225259726654, 8630.611622477129, 8906.198442140238], "unit": "ms", "name": "yui.html", "value": 6176.611563997638}], "name": "dromaeo_css"}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 105.0, 109.0, 109.0, 111.0, 101.0, 109.0, 110.0, 101.0], "unit": "ms", "name": "ai-astar", "value": 107.4}, {"lowerIsBetter": true, "replicates": [149.0, 124.0, 150.0, 175.0, 126.0, 151.0, 158.0, 124.0, 166.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 144.5}, {"lowerIsBetter": true, "replicates": [161.0, 159.0, 159.0, 161.0, 160.0, 161.0, 160.0, 162.0, 160.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 160.5}, {"lowerIsBetter": true, "replicates": [73.0, 73.0, 88.0, 91.0, 71.0, 74.0, 71.0, 103.0, 93.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 80.8}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 143.0, 92.0, 93.0, 93.0, 137.0, 91.0, 92.0, 140.0], "unit": "ms", "name": "audio-oscillator", "value": 106.8}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.4}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 145.0, 147.0, 146.0, 147.0, 145.0, 147.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.9}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 97.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [58.0, 57.0, 57.0, 58.0, 57.0, 57.0, 57.0, 57.0, 59.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 57.3}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 70.0, 70.0, 73.0, 71.0, 69.0, 69.0, 70.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 70.2}, {"lowerIsBetter": true, "replicates": [84.0, 85.0, 84.0, 86.0, 84.0, 85.0, 94.0, 85.0, 87.0, 86.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.0}, {"lowerIsBetter": true, "replicates": [125.0, 123.0, 120.0, 113.0, 119.0, 121.0, 120.0, 125.0, 115.0, 124.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 120.5}, {"lowerIsBetter": true, "replicates": [141.0, 139.0, 162.0, 147.0, 149.0, 138.0, 152.0, 144.0, 148.0, 142.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 146.2}, {"lowerIsBetter": true, "replicates": [67.0, 66.0, 67.0, 66.0, 66.0, 66.0, 67.0, 65.0, 66.0, 67.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.3}], "name": "kraken"}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.31153466973382], "unit": "score", "name": "Richards", "value": 214.84797200000008}, {"lowerIsBetter": false, "replicates": [188.82175226586102], "unit": "score", "name": "DeltaBlue", "value": 350.16092799999996}, {"lowerIsBetter": false, "replicates": [279.3296089385475], "unit": "score", "name": "Encrypt", "value": 952.9279799999998}, {"lowerIsBetter": false, "replicates": [5181.347150259067], "unit": "score", "name": "Decrypt", "value": 51.372933}, {"lowerIsBetter": false, "replicates": [1083.4236186348862], "unit": "score", "name": "RayTrace", "value": 683.0098469999997}, {"lowerIsBetter": false, "replicates": [775.1937984496124], "unit": "score", "name": "Earley", "value": 859.7372699999999}, {"lowerIsBetter": false, "replicates": [12204.819277108434], "unit": "score", "name": "Boyer", "value": 54.606543928923976}, {"lowerIsBetter": false, "replicates": [25575.0], "unit": "score", "name": "RegExp", "value": 35.620136852394914}, {"lowerIsBetter": false, "replicates": [625.7822277847309], "unit": "score", "name": "Splay", "value": 130.22261800000004}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7"}]} 05:42:49 INFO - RETURN: dromaeo_css: 6891.18 05:42:49 INFO - RETURN: kraken: 1497.70 05:42:49 INFO - RETURN: v8_7: 21102.54 05:42:49 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,35]]", "result": "21102.54"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,35]]", "result": "1497.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,35]]", "result": "6891.18"}}} 05:42:49 INFO - Return code: 0 05:42:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:42:49 INFO - # TBPL SUCCESS # 05:42:49 INFO - Running post-action listener: _resource_record_post_action 05:42:49 INFO - Running post-run listener: _resource_record_post_run 05:42:50 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 516096; Write bytes: 267235328; Read time: 268; Write time: 153740 05:42:50 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 4378624; Read time: 0; Write time: 276 05:42:50 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 516096; Write bytes: 262856704; Read time: 268; Write time: 153464 05:42:50 INFO - Running post-run listener: _upload_blobber_files 05:42:50 INFO - Blob upload gear active. 05:42:50 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:42:50 INFO - Copying logs to upload dir... 05:42:50 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1273.344519 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2015-11-24 05:42:50.244735) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 05:42:50.248845) ========= 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=928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010064 build_url: 'https://queue.taskcluster.net/v1/task/B68JMfYYTRegvPmD0mBSRQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 05:42:50.300263) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:42:50.300609) ========= 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=928fd09416829678a2f67a56000001ac-1448370913.400567-1555527226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004623 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:42:50.350337) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 05:42:50.350665) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 05:42:50.350994) ========= ========= Total master_lag: 0.25 =========