builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-052 starttime: 1448466624.05 results: success (0) buildid: 20151125053453 builduid: 71fa2468bdfe486b8183b4ea7f4ea531 revision: ba1686bae88d9ef8b01d16f2e89d0bec5c79e85f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:24.047681) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:24.048147) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:24.048493) ========= 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=2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.007928 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:24.076271) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:24.076608) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:24.165700) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:24.166045) ========= 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=2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004688 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:24.216228) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:24.216541) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:24.216926) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:24.217263) ========= 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=2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-25 07:50:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 47.2M=0s 2015-11-25 07:50:25 (47.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.178143 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:24.413082) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:24.413403) ========= 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=2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020248 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:24.464044) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:24.464487) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ba1686bae88d9ef8b01d16f2e89d0bec5c79e85f --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/fx-team --rev ba1686bae88d9ef8b01d16f2e89d0bec5c79e85f --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=2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-25 07:50:26,042 truncating revision to first 12 chars 2015-11-25 07:50:26,042 Setting DEBUG logging. 2015-11-25 07:50:26,042 attempt 1/10 2015-11-25 07:50:26,042 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ba1686bae88d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-25 07:50:26,407 unpacking tar archive at: fx-team-ba1686bae88d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.760218 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:25.238489) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:25.238844) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:25.248563) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:25.249000) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 07:50:25.249584) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2015-11-25 07:50:25.249958) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:50:26 INFO - MultiFileLogger online at 20151125 07:50:26 in /builds/slave/test-pgo 07:50:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 07:50:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 07:50:26 INFO - {'append_to_log': False, 07:50:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 07:50:26 INFO - 'blob_upload_branch': 'Fx-Team', 07:50:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 07:50:26 INFO - 'branch': 'Fx-Team', 07:50:26 INFO - 'buildbot_json_path': 'buildprops.json', 07:50:26 INFO - 'config_files': ('talos/linux_config.py',), 07:50:26 INFO - 'default_actions': ('clobber', 07:50:26 INFO - 'read-buildbot-config', 07:50:26 INFO - 'download-and-extract', 07:50:26 INFO - 'populate-webroot', 07:50:26 INFO - 'create-virtualenv', 07:50:26 INFO - 'install', 07:50:26 INFO - 'run-tests'), 07:50:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:50:26 INFO - 'download_minidump_stackwalk': True, 07:50:26 INFO - 'download_symbols': 'ondemand', 07:50:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:50:26 INFO - 'tooltool.py': '/tools/tooltool.py', 07:50:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:50:26 INFO - '/tools/misc-python/virtualenv.py')}, 07:50:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:50:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:50:26 INFO - 'installer_path': 'installer.exe', 07:50:26 INFO - 'log_level': 'info', 07:50:26 INFO - 'log_name': 'talos', 07:50:26 INFO - 'log_to_console': True, 07:50:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:50:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:50:26 INFO - 'opt_config_files': (), 07:50:26 INFO - 'pip_index': False, 07:50:26 INFO - 'sps_profile': False, 07:50:26 INFO - 'sps_profile_interval': 0, 07:50:26 INFO - 'suite': 'dromaeojs', 07:50:26 INFO - 'system_bits': '64', 07:50:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:50:26 INFO - 'title': 'talos-linux64-ix-052', 07:50:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:50:26 INFO - 'use_talos_json': True, 07:50:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 07:50:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:50:26 INFO - 'work_dir': 'build'} 07:50:26 INFO - ##### 07:50:26 INFO - ##### Running clobber step. 07:50:26 INFO - ##### 07:50:26 INFO - Running pre-action listener: _resource_record_pre_action 07:50:26 INFO - Running main action method: clobber 07:50:26 INFO - rmtree: /builds/slave/test-pgo/build 07:50:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 07:50:27 INFO - Running post-action listener: _resource_record_post_action 07:50:27 INFO - ##### 07:50:27 INFO - ##### Running read-buildbot-config step. 07:50:27 INFO - ##### 07:50:27 INFO - Running pre-action listener: _resource_record_pre_action 07:50:27 INFO - Running main action method: read_buildbot_config 07:50:27 INFO - Using buildbot properties: 07:50:27 INFO - { 07:50:27 INFO - "properties": { 07:50:27 INFO - "buildnumber": 516, 07:50:27 INFO - "product": "firefox", 07:50:27 INFO - "script_repo_revision": "production", 07:50:27 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 07:50:27 INFO - "repository": "", 07:50:27 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 07:50:27 INFO - "buildid": "20151125053453", 07:50:27 INFO - "slavename": "talos-linux64-ix-052", 07:50:27 INFO - "pgo_build": "True", 07:50:27 INFO - "basedir": "/builds/slave/test-pgo", 07:50:27 INFO - "project": "", 07:50:27 INFO - "platform": "ubuntu64_hw", 07:50:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:50:27 INFO - "slavebuilddir": "test", 07:50:27 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 07:50:27 INFO - "branch": "fx-team", 07:50:27 INFO - "repo_path": "integration/fx-team", 07:50:27 INFO - "stage_platform": "linux64-pgo", 07:50:27 INFO - "builduid": "71fa2468bdfe486b8183b4ea7f4ea531", 07:50:27 INFO - "revision": "ba1686bae88d9ef8b01d16f2e89d0bec5c79e85f" 07:50:27 INFO - }, 07:50:27 INFO - "sourcestamp": { 07:50:27 INFO - "repository": "", 07:50:27 INFO - "hasPatch": false, 07:50:27 INFO - "project": "", 07:50:27 INFO - "branch": "fx-team-linux64-pgo-talos", 07:50:27 INFO - "changes": [ 07:50:27 INFO - { 07:50:27 INFO - "category": null, 07:50:27 INFO - "files": [ 07:50:27 INFO - { 07:50:27 INFO - "url": null, 07:50:27 INFO - "name": "https://queue.taskcluster.net/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 07:50:27 INFO - } 07:50:27 INFO - ], 07:50:27 INFO - "repository": "", 07:50:27 INFO - "rev": "ba1686bae88d9ef8b01d16f2e89d0bec5c79e85f", 07:50:27 INFO - "who": "sendchange", 07:50:27 INFO - "when": 1448466572, 07:50:27 INFO - "number": 6706175, 07:50:27 INFO - "comments": "", 07:50:27 INFO - "project": "", 07:50:27 INFO - "at": "Wed 25 Nov 2015 07:49:32", 07:50:27 INFO - "branch": "fx-team-linux64-pgo-talos", 07:50:27 INFO - "revlink": "", 07:50:27 INFO - "properties": [ 07:50:27 INFO - [ 07:50:27 INFO - "buildid", 07:50:27 INFO - "20151125053453", 07:50:27 INFO - "Change" 07:50:27 INFO - ], 07:50:27 INFO - [ 07:50:27 INFO - "builduid", 07:50:27 INFO - "71fa2468bdfe486b8183b4ea7f4ea531", 07:50:27 INFO - "Change" 07:50:27 INFO - ], 07:50:27 INFO - [ 07:50:27 INFO - "pgo_build", 07:50:27 INFO - "True", 07:50:27 INFO - "Change" 07:50:27 INFO - ] 07:50:27 INFO - ], 07:50:27 INFO - "revision": "ba1686bae88d9ef8b01d16f2e89d0bec5c79e85f" 07:50:27 INFO - } 07:50:27 INFO - ], 07:50:27 INFO - "revision": "ba1686bae88d9ef8b01d16f2e89d0bec5c79e85f" 07:50:27 INFO - } 07:50:27 INFO - } 07:50:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 07:50:27 INFO - Running post-action listener: _resource_record_post_action 07:50:27 INFO - ##### 07:50:27 INFO - ##### Running download-and-extract step. 07:50:27 INFO - ##### 07:50:27 INFO - Running pre-action listener: _resource_record_pre_action 07:50:27 INFO - Running main action method: download_and_extract 07:50:27 INFO - mkdir: /builds/slave/test-pgo/build/tests 07:50:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:27 INFO - https://queue.taskcluster.net/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 07:50:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/test_packages.json 07:50:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/test_packages.json 07:50:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 07:50:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 07:50:28 INFO - Downloaded 1302 bytes. 07:50:28 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 07:50:28 INFO - Using the following test package requirements: 07:50:28 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 07:50:28 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:50:28 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:50:28 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:50:28 INFO - u'jsshell-linux-x86_64.zip'], 07:50:28 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:50:28 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:50:28 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 07:50:28 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:50:28 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:50:28 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:50:28 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:50:28 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:50:28 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:50:28 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 07:50:28 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:50:28 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:50:28 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:50:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:28 INFO - https://queue.taskcluster.net/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:50:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:50:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:50:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/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 07:50:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/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 07:50:29 INFO - Downloaded 22754332 bytes. 07:50:29 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 07:50:29 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:50:29 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 07:50:30 INFO - Return code: 0 07:50:30 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 07:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:30 INFO - https://queue.taskcluster.net/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:50:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:50:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:50:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/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 07:50:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/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 07:50:31 INFO - Downloaded 22754332 bytes. 07:50:31 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 07:50:31 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:50:31 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 07:50:32 INFO - Return code: 0 07:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:32 INFO - https://queue.taskcluster.net/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:50:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 07:50:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 07:50:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/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 07:50:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/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 07:50:33 INFO - Downloaded 11131717 bytes. 07:50:33 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 07:50:33 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 07:50:33 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 07:50:33 INFO - Return code: 0 07:50:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:33 INFO - https://queue.taskcluster.net/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:50:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 07:50:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 07:50:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/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 07:50:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kR2TulyMTR6wwxFPVGNwJQ/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 07:50:35 INFO - Downloaded 58395032 bytes. 07:50:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 07:50:35 INFO - mkdir: /builds/slave/test-pgo/properties 07:50:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 07:50:35 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 07:50:35 INFO - Contents: 07:50:35 INFO - build_url:https://queue.taskcluster.net/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 07:50:35 INFO - Running post-action listener: _resource_record_post_action 07:50:35 INFO - Running post-action listener: set_extra_try_arguments 07:50:35 INFO - ##### 07:50:35 INFO - ##### Running populate-webroot step. 07:50:35 INFO - ##### 07:50:35 INFO - Running pre-action listener: _resource_record_pre_action 07:50:35 INFO - Running main action method: populate_webroot 07:50:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:50:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:50:35 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 07:50:35 INFO - '../fennec_ids.txt'], 07:50:35 INFO - 'tests': ['tcheck2']}, 07:50:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:50:35 INFO - '--tppagecycles', 07:50:35 INFO - '7'], 07:50:35 INFO - 'tests': ['tsvgm']}}, 07:50:35 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 07:50:35 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 07:50:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:50:35 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:50:35 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 07:50:35 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:50:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:50:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:35 INFO - 'talos_options': ['--e10s'], 07:50:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:50:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:35 INFO - 'tests': ['damp', 'tps']}, 07:50:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:35 INFO - 'talos_options': ['--e10s'], 07:50:35 INFO - 'tests': ['damp', 'tps']}, 07:50:35 INFO - 'g3': {'tests': ['dromaeo_dom']}, 07:50:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:50:35 INFO - 'other': {'tests': ['a11yr', 07:50:35 INFO - 'ts_paint', 07:50:35 INFO - 'tpaint', 07:50:35 INFO - 'sessionrestore', 07:50:35 INFO - 'sessionrestore_no_auto_restore']}, 07:50:35 INFO - 'other-e10s': {'talos_options': ['--e10s'], 07:50:35 INFO - 'tests': ['a11yr', 07:50:35 INFO - 'ts_paint', 07:50:35 INFO - 'tpaint', 07:50:35 INFO - 'sessionrestore', 07:50:35 INFO - 'sessionrestore_no_auto_restore']}, 07:50:35 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 07:50:35 INFO - 'tests': ['a11yr', 07:50:35 INFO - 'ts_paint', 07:50:35 INFO - 'tpaint', 07:50:35 INFO - 'sessionrestore', 07:50:35 INFO - 'sessionrestore_no_auto_restore']}, 07:50:35 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 07:50:35 INFO - 'tests': ['a11yr', 07:50:35 INFO - 'ts_paint', 07:50:35 INFO - 'tpaint', 07:50:35 INFO - 'sessionrestore', 07:50:35 INFO - 'sessionrestore_no_auto_restore']}, 07:50:35 INFO - 'other_l64': {'tests': ['a11yr', 07:50:35 INFO - 'ts_paint', 07:50:35 INFO - 'tpaint', 07:50:35 INFO - 'sessionrestore', 07:50:35 INFO - 'sessionrestore_no_auto_restore']}, 07:50:35 INFO - 'other_nol64': {'tests': ['a11yr', 07:50:35 INFO - 'ts_paint', 07:50:35 INFO - 'tpaint', 07:50:35 INFO - 'sessionrestore', 07:50:35 INFO - 'sessionrestore_no_auto_restore']}, 07:50:35 INFO - 'svgr': {'tests': ['tsvgx', 07:50:35 INFO - 'tsvgr_opacity', 07:50:35 INFO - 'tart', 07:50:35 INFO - 'tscrollx', 07:50:35 INFO - 'cart']}, 07:50:35 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 07:50:35 INFO - 'tests': ['tsvgx', 07:50:35 INFO - 'tsvgr_opacity', 07:50:35 INFO - 'tart', 07:50:35 INFO - 'tscrollx', 07:50:35 INFO - 'cart']}, 07:50:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:35 INFO - 'tests': ['tp5o']}, 07:50:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:35 INFO - 'talos_options': ['--e10s'], 07:50:35 INFO - 'tests': ['tp5o']}, 07:50:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:35 INFO - 'talos_options': ['--xperf_path', 07:50:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:50:35 INFO - 'tests': ['tp5n']}, 07:50:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:35 INFO - 'talos_options': ['--e10s', 07:50:35 INFO - '--xperf_path', 07:50:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:50:35 INFO - 'tests': ['tp5n']}}, 07:50:35 INFO - 'talos.zip': {'path': '', 07:50:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:50:35 INFO - Running post-action listener: _resource_record_post_action 07:50:35 INFO - ##### 07:50:35 INFO - ##### Running create-virtualenv step. 07:50:35 INFO - ##### 07:50:35 INFO - Running pre-action listener: _resource_record_pre_action 07:50:35 INFO - Running main action method: create_virtualenv 07:50:35 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 07:50:35 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 07:50:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 07:50:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:50:35 INFO - Using real prefix '/usr' 07:50:35 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 07:50:35 INFO - Installing distribute.............................................................................................................................................................................................done. 07:50:37 INFO - Installing pip.................done. 07:50:37 INFO - Return code: 0 07:50:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 07:50:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:37 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 0x1e31030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x205e7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ed640>, '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 0x20ee540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ee9d0>, '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': '2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495', '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 07:50:37 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 07:50:37 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 07:50:37 INFO - Using env: {'DISPLAY': ':0', 07:50:37 INFO - 'HOME': '/home/cltbld', 07:50:37 INFO - 'LANG': 'en_US.UTF-8', 07:50:37 INFO - 'LANGUAGE': 'en_US:en', 07:50:37 INFO - 'LOGNAME': 'cltbld', 07:50:37 INFO - 'MAIL': '/var/mail/cltbld', 07:50:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:37 INFO - 'MOZ_NO_REMOTE': '1', 07:50:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:37 INFO - 'NO_EM_RESTART': '1', 07:50:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:50:37 INFO - 'PWD': '/builds/slave/test-pgo', 07:50:37 INFO - 'SHELL': '/bin/bash', 07:50:37 INFO - 'SHLVL': '1', 07:50:37 INFO - 'TERM': 'linux', 07:50:37 INFO - 'TMOUT': '86400', 07:50:37 INFO - 'USER': 'cltbld', 07:50:37 INFO - 'XDG_SESSION_COOKIE': '2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495', 07:50:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:37 INFO - '_': '/tools/buildbot/bin/python'} 07:50:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:37 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 07:50:37 INFO - Cleaning up... 07:50:37 INFO - Return code: 0 07:50:37 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 07:50:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:37 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 0x1e31030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x205e7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ed640>, '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 0x20ee540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ee9d0>, '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': '2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495', '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 07:50:37 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 07:50:37 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 07:50:37 INFO - Using env: {'DISPLAY': ':0', 07:50:37 INFO - 'HOME': '/home/cltbld', 07:50:37 INFO - 'LANG': 'en_US.UTF-8', 07:50:37 INFO - 'LANGUAGE': 'en_US:en', 07:50:37 INFO - 'LOGNAME': 'cltbld', 07:50:37 INFO - 'MAIL': '/var/mail/cltbld', 07:50:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:37 INFO - 'MOZ_NO_REMOTE': '1', 07:50:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:37 INFO - 'NO_EM_RESTART': '1', 07:50:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:50:37 INFO - 'PWD': '/builds/slave/test-pgo', 07:50:37 INFO - 'SHELL': '/bin/bash', 07:50:37 INFO - 'SHLVL': '1', 07:50:37 INFO - 'TERM': 'linux', 07:50:37 INFO - 'TMOUT': '86400', 07:50:37 INFO - 'USER': 'cltbld', 07:50:37 INFO - 'XDG_SESSION_COOKIE': '2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495', 07:50:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:37 INFO - '_': '/tools/buildbot/bin/python'} 07:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:38 INFO - Downloading/unpacking psutil>=0.7.1 07:50:38 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 07:50:38 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 07:50:38 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 07:50:38 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 07:50:38 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 07:50:38 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 07:50:39 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 07:50:39 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 07:50:39 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 07:50:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:50:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:50:39 INFO - Installing collected packages: psutil 07:50:39 INFO - Running setup.py install for psutil 07:50:40 INFO - building 'psutil._psutil_linux' extension 07:50:40 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 07:50:40 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 07:50:40 INFO - building 'psutil._psutil_posix' extension 07:50:40 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 07:50:40 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 07:50:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:50:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:50:40 INFO - Successfully installed psutil 07:50:40 INFO - Cleaning up... 07:50:40 INFO - Return code: 0 07:50:40 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 07:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:40 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 0x1e31030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x205e7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ed640>, '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 0x20ee540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ee9d0>, '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': '2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495', '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 07:50:40 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 07:50:40 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 07:50:40 INFO - Using env: {'DISPLAY': ':0', 07:50:40 INFO - 'HOME': '/home/cltbld', 07:50:40 INFO - 'LANG': 'en_US.UTF-8', 07:50:40 INFO - 'LANGUAGE': 'en_US:en', 07:50:40 INFO - 'LOGNAME': 'cltbld', 07:50:40 INFO - 'MAIL': '/var/mail/cltbld', 07:50:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:40 INFO - 'MOZ_NO_REMOTE': '1', 07:50:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:40 INFO - 'NO_EM_RESTART': '1', 07:50:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:50:40 INFO - 'PWD': '/builds/slave/test-pgo', 07:50:40 INFO - 'SHELL': '/bin/bash', 07:50:40 INFO - 'SHLVL': '1', 07:50:40 INFO - 'TERM': 'linux', 07:50:40 INFO - 'TMOUT': '86400', 07:50:40 INFO - 'USER': 'cltbld', 07:50:40 INFO - 'XDG_SESSION_COOKIE': '2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495', 07:50:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:40 INFO - '_': '/tools/buildbot/bin/python'} 07:50:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:40 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:42 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:50:42 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 07:50:42 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:50:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:50:42 INFO - Installing collected packages: mozsystemmonitor 07:50:42 INFO - Running setup.py install for mozsystemmonitor 07:50:42 INFO - Successfully installed mozsystemmonitor 07:50:42 INFO - Cleaning up... 07:50:42 INFO - Return code: 0 07:50:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 07:50:42 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')]} 07:50:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:42 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')]} 07:50:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:42 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 0x1e31030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x205e7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ed640>, '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 0x20ee540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ee9d0>, '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': '2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495', '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 07:50:42 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 07:50:42 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 07:50:42 INFO - Using env: {'DISPLAY': ':0', 07:50:42 INFO - 'HOME': '/home/cltbld', 07:50:42 INFO - 'LANG': 'en_US.UTF-8', 07:50:42 INFO - 'LANGUAGE': 'en_US:en', 07:50:42 INFO - 'LOGNAME': 'cltbld', 07:50:42 INFO - 'MAIL': '/var/mail/cltbld', 07:50:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:42 INFO - 'MOZ_NO_REMOTE': '1', 07:50:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:42 INFO - 'NO_EM_RESTART': '1', 07:50:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:50:42 INFO - 'PWD': '/builds/slave/test-pgo', 07:50:42 INFO - 'SHELL': '/bin/bash', 07:50:42 INFO - 'SHLVL': '1', 07:50:42 INFO - 'TERM': 'linux', 07:50:42 INFO - 'TMOUT': '86400', 07:50:42 INFO - 'USER': 'cltbld', 07:50:42 INFO - 'XDG_SESSION_COOKIE': '2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495', 07:50:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:42 INFO - '_': '/tools/buildbot/bin/python'} 07:50:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:42 INFO - Downloading/unpacking blobuploader==1.2.4 07:50:42 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 07:50:42 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 07:50:42 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 07:50:42 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 07:50:42 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 07:50:42 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 07:50:44 INFO - Downloading blobuploader-1.2.4.tar.gz 07:50:44 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 07:50:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:50:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:50:44 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 07:50:44 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 07:50:44 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 07:50:44 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 07:50:44 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 07:50:44 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 07:50:44 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 07:50:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 07:50:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:50:44 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 07:50:44 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 07:50:44 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 07:50:44 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 07:50:44 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 07:50:44 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 07:50:45 INFO - Downloading docopt-0.6.1.tar.gz 07:50:45 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 07:50:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 07:50:45 INFO - Installing collected packages: blobuploader, requests, docopt 07:50:45 INFO - Running setup.py install for blobuploader 07:50:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:50:45 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 07:50:45 INFO - Running setup.py install for requests 07:50:45 INFO - Running setup.py install for docopt 07:50:45 INFO - Successfully installed blobuploader requests docopt 07:50:45 INFO - Cleaning up... 07:50:45 INFO - Return code: 0 07:50:45 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:50:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:45 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 0x1e31030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x205e7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ed640>, '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 0x20ee540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ee9d0>, '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': '2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495', '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 07:50:45 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 07:50:45 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 07:50:45 INFO - Using env: {'DISPLAY': ':0', 07:50:45 INFO - 'HOME': '/home/cltbld', 07:50:45 INFO - 'LANG': 'en_US.UTF-8', 07:50:45 INFO - 'LANGUAGE': 'en_US:en', 07:50:45 INFO - 'LOGNAME': 'cltbld', 07:50:45 INFO - 'MAIL': '/var/mail/cltbld', 07:50:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:45 INFO - 'MOZ_NO_REMOTE': '1', 07:50:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:45 INFO - 'NO_EM_RESTART': '1', 07:50:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:50:45 INFO - 'PWD': '/builds/slave/test-pgo', 07:50:45 INFO - 'SHELL': '/bin/bash', 07:50:45 INFO - 'SHLVL': '1', 07:50:45 INFO - 'TERM': 'linux', 07:50:45 INFO - 'TMOUT': '86400', 07:50:45 INFO - 'USER': 'cltbld', 07:50:45 INFO - 'XDG_SESSION_COOKIE': '2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495', 07:50:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:45 INFO - '_': '/tools/buildbot/bin/python'} 07:50:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:50:46 INFO - Running setup.py (path:/tmp/pip-irecUN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:50:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:50:46 INFO - Running setup.py (path:/tmp/pip-PMSKHX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:50:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:50:46 INFO - Running setup.py (path:/tmp/pip-nkmqqN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:50:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:50:46 INFO - Running setup.py (path:/tmp/pip-55VN4I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:50:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 07:50:46 INFO - Running setup.py (path:/tmp/pip-7XcqX7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 07:50:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:50:46 INFO - Running setup.py (path:/tmp/pip-AZ5vvW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:50:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:50:46 INFO - Running setup.py (path:/tmp/pip-qR4Aug-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:50:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:50:46 INFO - Running setup.py (path:/tmp/pip-kTMSaY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:50:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 07:50:46 INFO - Running setup.py (path:/tmp/pip-Uj30Xz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 07:50:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 07:50:46 INFO - Running setup.py (path:/tmp/pip-7Z9gZd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 07:50:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:50:46 INFO - Running setup.py (path:/tmp/pip-cWSXX2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:50:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:50:46 INFO - Running setup.py (path:/tmp/pip-5q8ogU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:50:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:50:46 INFO - Running setup.py (path:/tmp/pip-oLgPjs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:50:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:50:46 INFO - Running setup.py (path:/tmp/pip-BGBds_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:50:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:50:47 INFO - Running setup.py (path:/tmp/pip-t6nKko-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:50:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 07:50:47 INFO - Running setup.py (path:/tmp/pip-xv9Fna-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 07:50:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 07:50:47 INFO - Running setup.py (path:/tmp/pip-lI1His-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 07:50:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:50:47 INFO - Running setup.py install for manifestparser 07:50:47 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 07:50:47 INFO - Running setup.py install for mozcrash 07:50:47 INFO - Running setup.py install for mozdebug 07:50:47 INFO - Running setup.py install for mozdevice 07:50:47 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 07:50:47 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 07:50:47 INFO - Running setup.py install for mozfile 07:50:47 INFO - Running setup.py install for mozhttpd 07:50:47 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 07:50:47 INFO - Running setup.py install for mozinfo 07:50:47 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 07:50:47 INFO - Running setup.py install for mozInstall 07:50:48 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 07:50:48 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 07:50:48 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 07:50:48 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 07:50:48 INFO - Running setup.py install for mozleak 07:50:48 INFO - Running setup.py install for mozlog 07:50:48 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 07:50:48 INFO - Running setup.py install for moznetwork 07:50:48 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 07:50:48 INFO - Running setup.py install for mozprocess 07:50:48 INFO - Running setup.py install for mozprofile 07:50:48 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 07:50:48 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 07:50:48 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 07:50:48 INFO - Running setup.py install for mozrunner 07:50:48 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 07:50:48 INFO - Running setup.py install for mozscreenshot 07:50:48 INFO - Running setup.py install for moztest 07:50:48 INFO - Running setup.py install for mozversion 07:50:48 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 07:50:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:50:48 INFO - Cleaning up... 07:50:48 INFO - Return code: 0 07:50:48 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:50:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:48 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 0x1e31030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x205e7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ed640>, '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 0x20ee540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ee9d0>, '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': '2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495', '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 07:50:48 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 07:50:48 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 07:50:48 INFO - Using env: {'DISPLAY': ':0', 07:50:48 INFO - 'HOME': '/home/cltbld', 07:50:48 INFO - 'LANG': 'en_US.UTF-8', 07:50:48 INFO - 'LANGUAGE': 'en_US:en', 07:50:48 INFO - 'LOGNAME': 'cltbld', 07:50:48 INFO - 'MAIL': '/var/mail/cltbld', 07:50:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:48 INFO - 'MOZ_NO_REMOTE': '1', 07:50:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:48 INFO - 'NO_EM_RESTART': '1', 07:50:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:50:48 INFO - 'PWD': '/builds/slave/test-pgo', 07:50:48 INFO - 'SHELL': '/bin/bash', 07:50:48 INFO - 'SHLVL': '1', 07:50:48 INFO - 'TERM': 'linux', 07:50:48 INFO - 'TMOUT': '86400', 07:50:48 INFO - 'USER': 'cltbld', 07:50:48 INFO - 'XDG_SESSION_COOKIE': '2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495', 07:50:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:48 INFO - '_': '/tools/buildbot/bin/python'} 07:50:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:50:49 INFO - Running setup.py (path:/tmp/pip-z2Ng7g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:50:49 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)) 07:50:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:50:49 INFO - Running setup.py (path:/tmp/pip-d_5luB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:50:49 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)) 07:50:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:50:49 INFO - Running setup.py (path:/tmp/pip-E6P3CB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:50:49 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)) 07:50:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:50:49 INFO - Running setup.py (path:/tmp/pip-FD1VCy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:50:49 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)) 07:50:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 07:50:49 INFO - Running setup.py (path:/tmp/pip-_cMnZC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 07:50:49 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)) 07:50:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:50:49 INFO - Running setup.py (path:/tmp/pip-NQjUsg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:50:49 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)) 07:50:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:50:49 INFO - Running setup.py (path:/tmp/pip-hdnn3u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:50:49 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)) 07:50:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:50:49 INFO - Running setup.py (path:/tmp/pip-b_rP3A-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:50:49 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)) 07:50:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 07:50:49 INFO - Running setup.py (path:/tmp/pip-hrCPRF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 07:50:49 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)) 07:50:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 07:50:49 INFO - Running setup.py (path:/tmp/pip-VfKOLN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 07:50:49 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)) 07:50:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:50:49 INFO - Running setup.py (path:/tmp/pip-gPWxyy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:50:49 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)) 07:50:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:50:50 INFO - Running setup.py (path:/tmp/pip-9kswGZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:50:50 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)) 07:50:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:50:50 INFO - Running setup.py (path:/tmp/pip-N0Xc5j-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:50:50 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)) 07:50:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:50:50 INFO - Running setup.py (path:/tmp/pip-hJLUje-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:50:50 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)) 07:50:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:50:50 INFO - Running setup.py (path:/tmp/pip-MhLBRF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:50:50 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)) 07:50:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 07:50:50 INFO - Running setup.py (path:/tmp/pip-yiOHyF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 07:50:50 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)) 07:50:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 07:50:50 INFO - Running setup.py (path:/tmp/pip-cYEWLV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 07:50:50 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)) 07:50:50 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)) 07:50:50 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)) 07:50:50 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)) 07:50:50 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)) 07:50:50 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)) 07:50:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 07:50:50 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 07:50:50 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 07:50:50 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 07:50:50 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 07:50:50 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 07:50:50 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 07:50:53 INFO - Downloading blessings-1.5.1.tar.gz 07:50:53 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 07:50:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 07:50:53 INFO - Installing collected packages: blessings 07:50:53 INFO - Running setup.py install for blessings 07:50:53 INFO - Successfully installed blessings 07:50:53 INFO - Cleaning up... 07:50:53 INFO - Return code: 0 07:50:53 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 07:50:53 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 07:50:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 07:50:53 INFO - Reading from file tmpfile_stdout 07:50:53 INFO - Current package versions: 07:50:53 INFO - argparse == 1.2.1 07:50:53 INFO - blessings == 1.5.1 07:50:53 INFO - blobuploader == 1.2.4 07:50:53 INFO - docopt == 0.6.1 07:50:53 INFO - manifestparser == 1.1 07:50:53 INFO - mozInstall == 1.12 07:50:53 INFO - mozcrash == 0.16 07:50:53 INFO - mozdebug == 0.1 07:50:53 INFO - mozdevice == 0.47 07:50:53 INFO - mozfile == 1.2 07:50:53 INFO - mozhttpd == 0.7 07:50:53 INFO - mozinfo == 0.9 07:50:53 INFO - mozleak == 0.1 07:50:53 INFO - mozlog == 3.1 07:50:53 INFO - moznetwork == 0.27 07:50:53 INFO - mozprocess == 0.22 07:50:53 INFO - mozprofile == 0.27 07:50:53 INFO - mozrunner == 6.11 07:50:53 INFO - mozscreenshot == 0.1 07:50:53 INFO - mozsystemmonitor == 0.0 07:50:53 INFO - moztest == 0.7 07:50:53 INFO - mozversion == 1.4 07:50:53 INFO - psutil == 3.1.1 07:50:53 INFO - requests == 1.2.3 07:50:53 INFO - wsgiref == 0.1.2 07:50:53 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x1e31030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x205e7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ed640>, '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 0x20ee540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ee9d0>, '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': '2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495', '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 07:50:53 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 07:50:53 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 07:50:53 INFO - Using env: {'DISPLAY': ':0', 07:50:53 INFO - 'HOME': '/home/cltbld', 07:50:53 INFO - 'LANG': 'en_US.UTF-8', 07:50:53 INFO - 'LANGUAGE': 'en_US:en', 07:50:53 INFO - 'LOGNAME': 'cltbld', 07:50:53 INFO - 'MAIL': '/var/mail/cltbld', 07:50:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:53 INFO - 'MOZ_NO_REMOTE': '1', 07:50:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:53 INFO - 'NO_EM_RESTART': '1', 07:50:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:50:53 INFO - 'PWD': '/builds/slave/test-pgo', 07:50:53 INFO - 'SHELL': '/bin/bash', 07:50:53 INFO - 'SHLVL': '1', 07:50:53 INFO - 'TERM': 'linux', 07:50:53 INFO - 'TMOUT': '86400', 07:50:53 INFO - 'USER': 'cltbld', 07:50:53 INFO - 'XDG_SESSION_COOKIE': '2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495', 07:50:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:53 INFO - '_': '/tools/buildbot/bin/python'} 07:50:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:53 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)) 07:50:53 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)) 07:50:53 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)) 07:50:53 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)) 07:50:53 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)) 07:50:53 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)) 07:50:53 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)) 07:50:53 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)) 07:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 07:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 07:50:53 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)) 07:50:53 INFO - Cleaning up... 07:50:53 INFO - Return code: 0 07:50:53 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 07:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e31030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x205e7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ed640>, '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 0x20ee540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ee9d0>, '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': '2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495', '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 07:50:53 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 07:50:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:50:53 INFO - Using env: {'DISPLAY': ':0', 07:50:53 INFO - 'HOME': '/home/cltbld', 07:50:53 INFO - 'LANG': 'en_US.UTF-8', 07:50:53 INFO - 'LANGUAGE': 'en_US:en', 07:50:53 INFO - 'LOGNAME': 'cltbld', 07:50:53 INFO - 'MAIL': '/var/mail/cltbld', 07:50:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:53 INFO - 'MOZ_NO_REMOTE': '1', 07:50:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:53 INFO - 'NO_EM_RESTART': '1', 07:50:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:50:53 INFO - 'PWD': '/builds/slave/test-pgo', 07:50:53 INFO - 'SHELL': '/bin/bash', 07:50:53 INFO - 'SHLVL': '1', 07:50:53 INFO - 'TERM': 'linux', 07:50:53 INFO - 'TMOUT': '86400', 07:50:53 INFO - 'USER': 'cltbld', 07:50:53 INFO - 'XDG_SESSION_COOKIE': '2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495', 07:50:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:53 INFO - '_': '/tools/buildbot/bin/python'} 07:50:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:54 INFO - Downloading/unpacking jsonschema 07:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:50:55 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 07:50:55 INFO - Downloading/unpacking functools32 (from jsonschema) 07:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:55 INFO - Downloading functools32-3.2.3-2.tar.gz 07:50:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 07:50:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 07:50:55 INFO - warning: no files found matching '*.txt' 07:50:55 INFO - no previously-included directories found matching 'build' 07:50:55 INFO - no previously-included directories found matching 'dist' 07:50:55 INFO - no previously-included directories found matching '.git*' 07:50:55 INFO - Installing collected packages: jsonschema, functools32 07:50:56 INFO - Running setup.py install for functools32 07:50:56 INFO - warning: no files found matching '*.txt' 07:50:56 INFO - no previously-included directories found matching 'build' 07:50:56 INFO - no previously-included directories found matching 'dist' 07:50:56 INFO - no previously-included directories found matching '.git*' 07:50:56 INFO - Successfully installed jsonschema functools32 07:50:56 INFO - Cleaning up... 07:50:56 INFO - Return code: 0 07:50:56 INFO - Running post-action listener: _resource_record_post_action 07:50:56 INFO - Running post-action listener: _start_resource_monitoring 07:50:56 INFO - Starting resource monitoring. 07:50:56 INFO - ##### 07:50:56 INFO - ##### Running install step. 07:50:56 INFO - ##### 07:50:56 INFO - Running pre-action listener: _resource_record_pre_action 07:50:56 INFO - Running main action method: install 07:50:56 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 07:50:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 07:50:56 INFO - Reading from file tmpfile_stdout 07:50:56 INFO - Detecting whether we're running mozinstall >=1.0... 07:50:56 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 07:50:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 07:50:56 INFO - Reading from file tmpfile_stdout 07:50:56 INFO - Output received: 07:50:56 INFO - Usage: mozinstall [options] installer 07:50:56 INFO - Options: 07:50:56 INFO - -h, --help show this help message and exit 07:50:56 INFO - -d DEST, --destination=DEST 07:50:56 INFO - Directory to install application into. [default: 07:50:56 INFO - "/builds/slave/test-pgo"] 07:50:56 INFO - --app=APP Application being installed. [default: firefox] 07:50:56 INFO - mkdir: /builds/slave/test-pgo/build/application 07:50:56 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'] 07:50:56 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 07:51:11 INFO - Reading from file tmpfile_stdout 07:51:11 INFO - Output received: 07:51:11 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 07:51:11 INFO - Running post-action listener: _resource_record_post_action 07:51:11 INFO - ##### 07:51:11 INFO - ##### Running run-tests step. 07:51:11 INFO - ##### 07:51:11 INFO - Running pre-action listener: _resource_record_pre_action 07:51:11 INFO - Running main action method: run_tests 07:51:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 07:51:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 07:51:11 INFO - Python 2.7.3 07:51:11 INFO - Return code: 0 07:51:11 INFO - grabbing minidump binary from tooltool 07:51: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')]} 07:51:11 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 0x20ed640>, '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 0x20ee540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ee9d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 07:51:11 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 07:51:11 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 07:51:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:51:11 INFO - Return code: 0 07:51:11 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 07:51:11 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 07:51:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 07:51:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 07:51:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 07:51:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 07:51:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-052 --webServer localhost 07:51:11 INFO - Using env: {'DISPLAY': ':0', 07:51:11 INFO - 'HOME': '/home/cltbld', 07:51:11 INFO - 'LANG': 'en_US.UTF-8', 07:51:11 INFO - 'LANGUAGE': 'en_US:en', 07:51:11 INFO - 'LOGNAME': 'cltbld', 07:51:11 INFO - 'MAIL': '/var/mail/cltbld', 07:51:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 07:51:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 07:51:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:11 INFO - 'MOZ_NO_REMOTE': '1', 07:51:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 07:51:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:51:11 INFO - 'NO_EM_RESTART': '1', 07:51:11 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:51:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:51:11 INFO - 'PWD': '/builds/slave/test-pgo', 07:51:11 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 07:51:11 INFO - 'SHELL': '/bin/bash', 07:51:11 INFO - 'SHLVL': '1', 07:51:11 INFO - 'TERM': 'linux', 07:51:11 INFO - 'TMOUT': '86400', 07:51:11 INFO - 'USER': 'cltbld', 07:51:11 INFO - 'XDG_SESSION_COOKIE': '2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495', 07:51:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:11 INFO - '_': '/tools/buildbot/bin/python'} 07:51:11 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--webServer', 'localhost'] with output_timeout 3600 07:51:11 INFO - mozversion INFO | application_buildid: 20151125053453 07:51:11 INFO - mozversion INFO | application_changeset: ba1686bae88d9ef8b01d16f2e89d0bec5c79e85f 07:51:11 INFO - mozversion INFO | application_display_name: Nightly 07:51:11 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:51:11 INFO - mozversion INFO | application_name: Firefox 07:51:11 INFO - mozversion INFO | application_remotingname: firefox 07:51:11 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 07:51:11 INFO - mozversion INFO | application_vendor: Mozilla 07:51:11 INFO - mozversion INFO | application_version: 45.0a1 07:51:11 INFO - mozversion INFO | platform_buildid: 20151125053453 07:51:11 INFO - mozversion INFO | platform_changeset: ba1686bae88d9ef8b01d16f2e89d0bec5c79e85f 07:51:11 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 07:51:11 INFO - mozversion INFO | platform_version: 45.0a1 07:51:11 INFO - 2015-11-25 07:51:11,402 DEBUG : using testdate: 1448466671 07:51:11 INFO - 2015-11-25 07:51:11,402 DEBUG : actual date: 1448466671 07:51:11 INFO - 2015-11-25 07:51:11,414 INFO : starting webserver on 'localhost:53808' 07:51:11 INFO - 2015-11-25 07:51:11,415 INFO : Starting test suite talos-linux64-ix-052 07:51:11 INFO - 2015-11-25 07:51:11,415 INFO : Starting test dromaeo_css 07:51:11 INFO - 2015-11-25 07:51:11,415 DEBUG : operating with platform_type : linux_ 07:51:11 INFO - 2015-11-25 07:51:11,416 INFO : Initialising browser for dromaeo_css test... 07:51:11 INFO - 2015-11-25 07:51:11,431 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpJjLLWy/profile http://localhost:53808/getInfo.html 07:51:15 INFO - 2015-11-25 07:51:15,561 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:51:15 INFO - 2015-11-25 07:51:15,561 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:51:15 INFO - 2015-11-25 07:51:15,561 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:51:15 INFO - 2015-11-25 07:51:15,561 DEBUG : BROWSER_OUTPUT: __metrics 07:51:15 INFO - 2015-11-25 07:51:15,677 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] 07:51:16 INFO - 2015-11-25 07:51:16,951 INFO : Browser initialized. 07:51:16 INFO - 2015-11-25 07:51:16,951 INFO : Running cycle 1/1 for dromaeo_css test... 07:51:16 INFO - 2015-11-25 07:51:16,951 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpJjLLWy/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:51:28 INFO - 2015-11-25 07:51:28,704 DEBUG : BROWSER_OUTPUT: RSS: Main: 138858496 07:51:28 INFO - 2015-11-25 07:51:28,704 DEBUG : BROWSER_OUTPUT: 07:52:20 INFO - 2015-11-25 07:52:20,266 DEBUG : BROWSER_OUTPUT: 1448466740265 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] 07:54:28 INFO - 2015-11-25 07:54:28,988 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53808/tests/dromaeo/cssquery-ext.html) 07:54:30 INFO - 2015-11-25 07:54:30,339 DEBUG : BROWSER_OUTPUT: RSS: Main: 274919424 07:54:30 INFO - 2015-11-25 07:54:30,339 DEBUG : BROWSER_OUTPUT: 07:57:32 INFO - 2015-11-25 07:57:32,716 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/dromaeo/cssquery-ext.html (next: http://localhost:53808/tests/dromaeo/cssquery-jquery.html) 07:57:33 INFO - 2015-11-25 07:57:33,277 DEBUG : BROWSER_OUTPUT: RSS: Main: 213307392 07:57:33 INFO - 2015-11-25 07:57:33,277 DEBUG : BROWSER_OUTPUT: 08:00:35 INFO - 2015-11-25 08:00:35,222 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53808/tests/dromaeo/cssquery-mootools.html) 08:00:35 INFO - 2015-11-25 08:00:35,787 DEBUG : BROWSER_OUTPUT: RSS: Main: 217722880 08:00:35 INFO - 2015-11-25 08:00:35,787 DEBUG : BROWSER_OUTPUT: 08:03:37 INFO - 2015-11-25 08:03:37,697 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53808/tests/dromaeo/cssquery-prototype.html) 08:03:38 INFO - 2015-11-25 08:03:38,287 DEBUG : BROWSER_OUTPUT: RSS: Main: 199868416 08:03:38 INFO - 2015-11-25 08:03:38,287 DEBUG : BROWSER_OUTPUT: 08:06:40 INFO - 2015-11-25 08:06:40,867 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53808/tests/dromaeo/cssquery-yui.html) 08:06:41 INFO - 2015-11-25 08:06:41,453 DEBUG : BROWSER_OUTPUT: RSS: Main: 193478656 08:06:41 INFO - 2015-11-25 08:06:41,453 DEBUG : BROWSER_OUTPUT: 08:09:44 INFO - 2015-11-25 08:09:44,081 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/dromaeo/cssquery-yui.html (next: http://localhost:53808/tests/dromaeo/cssquery-dojo.html) 08:09:44 INFO - 2015-11-25 08:09:44,325 DEBUG : BROWSER_OUTPUT: RSS: Main: 266338304 08:09:44 INFO - 2015-11-25 08:09:44,325 DEBUG : BROWSER_OUTPUT: 08:09:44 INFO - 2015-11-25 08:09:44,327 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:09:44 INFO - 2015-11-25 08:09:44,327 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:09:44 INFO - 2015-11-25 08:09:44,327 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:09:44 INFO - 2015-11-25 08:09:44,327 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:09:44 INFO - 2015-11-25 08:09:44,328 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3099.1942095055288;3208.6791320867933;3237.6276728176254;3242.675732426756;3278.803271803691;5156.535911767949;5262.315898933133;5378.731063446827;5416.5937554683405;5444.074507333753;5283.181106928422;5328.733563321828;5352.866178345534;5355.758990845412;5385.272988146599;4879.512048795119;4932.358793356859;4963.230699241623;4976.950230497703;5031.371078615173;4842.806287748486;4988.176950803113;5003.899922001558;5034.572061374787;5067.619928505358;4835.057163853033;4894.926576101361;4942.233953737176;4951.108800415924;4969.751512424365;4923.606111511071;4927.039227350668;4928.778204980785;4929.433115191763;4980.252962055684;4742.857714268577;4915.606751459874;4933.975330123362;4935.679180853269;4982.277569752402;5076.36545431821;5095.79616815327;5178.533931946105;5181.740912954375;5206.297149884755;5860.150278209679;5877.38287479817;5880.647161170301;5889.116632505115;5895.292564892197;5633.521150702195;5790.044642634002;5914.319853216892;5931.377205393458;5941.643501389931;5399.406065332811;5453.372862120827;5454.072807622713;5457.863553411117;5499;5980.102984552352;5980.8803823924145;5981.940180598225;5986.3714309997695;6054.303755068224;5534.889302214014;5627.831165065055;5632.3804381518;5756.5682573807135;5767.769289228384;5713.74288157034;5784.681842498703;5788;5788.594798364074;5820.156077368758;5700.059490184156;5701.686407247641;5752.712364381764;5758.827235182951;5764.682942438122;5548.250986116904;5662.433756624387;5757.913631295534;5769.53843692512;5773.509251713567;4523.095380923828;4531.796069176895;4544.477385100689;4558.0428110097255;4573.451185857718;4011.097503061788;4014.4781178446615;4021.879343619696;4026.978774052779;4055.47278853753;5380.461953804588;5459.235707001023;5465.508104270635;5555;5640.717964101778;5842.2405087338375;5851.590388672752;5851.795187168429;5888.676122813286;5890.028145355968;5570.164475328733;5570.749316280696;5577.163425486209;5578.888422231614;5587.552995760329;4980.651354405157;5044.344235249394;5068.771905264272;5097.643164978416;5104.770285337168;6103.0669477704105;6121.704725412397;6129.09499847894;6132.41742034506;6140.109684095871;5851.122331650116;5904.645721256738;5906.675132867734;5912.320083190316;5912.438318359749;5852.3269823969085;5922.230110085661;5959.255093113361;5962.582619216787;6028.728707208274;5782.971085144547;5802.361740208489;5825.475707186373;5833.82498525045;5836.941630583639;5895.823125306248;5903.350631430625;5909.852253693692;5928.140419639214;5934.465898069195;5503.3120859892515;5602.663840169603;5604.971975140262;5616.438356164351;5734.627249228787;5704.743286552032;5705.4579814917515;5717.7141142941855;5719.971400142973;5750.597458177887;5608.85977850557;5613.129964855454;5613.635113717759;5617.943820561742;5619.466150715675;5469;5560.638558493685;5577.024020796425;5663.858403539944;5675.914861277001;5605.1872478491205;5636.267285253055;5640.717964101696;5651.491365777099;5678.063119585222 08:09:44 INFO - 2015-11-25 08:09:44,332 DEBUG : BROWSER_OUTPUT: |1;ext.html;558.366254301368;583.6177303865968;597.7698586044369;603.3544107804648;605.1860247966483;31323.530147047783;40601.390979135336;41888;41938.790306048424;41971.580284197145;44380.556194438046;44752.77623611877;44753.552464475346;44902.550974490245;44951.32573011408;7568.205338439468;8667.393282470246;8827.205551500363;8836.558172091402;8836.867446988268;7694.769156925301;7793;7794.454388192828;7795.298423141916;10309.84535231972;10262.435566043865;10336.069753722164;10375.688729338131;10383.84424233637;10386.740331491697;10210.081092701656;10226.233032522554;10272.69181924536;10274.640387586473;10303.124234440082;7758.107817600991;7957.7612671619945;10077.244206684529;10354.689359319233;10624.309419887682;40805.183896321905;40879.38680919789;41203.58796412028;41253.587464125274;41286;3218.6459489456142;3276.311974485361;3303.339332133583;3466.35872363611;3786.110264087937;30345.696543034508;30791.230219244473;30797.384052318826;31358.52962205569;32072.839635801905;3361.3949489091956;3432.1591210153424;3437.6390478999847;3442.431998720208;3525.312564050011;288368.1163188383;288919;289339;289468;289651;57029.015937768745;61966.69016654978;62342;62356.06465903015;62424.687876560325;54460.45539544554;65847.03688334036;66782.66608667023;66894;67015.66492167606;15302.617434564225;15496.457623983106;15506.689866202838;15600.14199219031;15603.375864965501;11007.339559626447;11029.22795404314;11032.779344413068;11045.061169800658;11064;19663.213471461266;20871.478213044797;20879.8956005219;20949.476263093544;20979.790202097785;7618.657160427683;7710.575918324434;7787.493812902257;7833.255840695125;7857.921420785833;906.6781292641149;937.7186843946789;962.4417837654144;1089.6131873185063;1140.3841925360211;28575.57136642952;28988.13035608935;29148.271293217414;29453.85273073621;29650.258743531584;987.4519641598981;1030.3766221436074;1044.300318786415;1071.6892101290694;1074.1943542343242;14725.263736813116;14728.263586820616;14770.335334909954;15032.774508382383;15057.472988445352;319906;322518;323276;323569;324267;52582;52652;52783.736081319345;52785.47214527882;52845.2073218902;1334.9532766353132;1342.812006319116;1352;1360.775472047121;1360.843502997168;27555.17334479969;28170.2957426066;28173.154805355873;28550;28813.27966800847;47441.76279118582;47800;47801;47814.760926195144;47867;307915;309855;309864;310034;310862;27749.722502775523;31738.36523269568;32247.838760806986;32254;32476.51285230676;38693.613063869;40212.59787402089;40681;42907.35638965481;43530.56469435265;36191.159115015456;36587.268254634226;36967;37262.44106338461;37782.81108594439;32231.677683222868;35043.29913401769;35398.115047124025;36420.45369319515;36896.44655330118;33743.84113497801;34132.14669633279;34395.4840677385;35758.4636230462;36584;31461;32368.35263294674;33218.16584118861;33479.33041339111;33572.49641255432;25365.746342537077;25636.615450768626;25672.486550269263;25892.35269118287;25915.87042064853 08:09:44 INFO - 2015-11-25 08:09:44,332 DEBUG : BROWSER_OUTPUT: |2;jquery.html;345.85474100877644;346.64122633074766;346.760735092786;348.2477847848647;349.20381530111325;14058.859411405883;14458.421663133488;15991.200439977998;16370.427035053775;17191.999999999985;14258.786118208236;15543.378264869392;15798.763018554731;15871.206439678028;15904.84095159048;5302.496262855032;5391.703456309901;5422.864428389283;5437.102878025121;5439.156930675752;4063.674906007512;4180.853670121546;5498.5876059295515;5517;5981.043033114703;6004.909926351086;6024.9698751506185;6043.939560604404;6048.818535443944;6063.545234107438;5846.970765146169;5976.462118409364;5979.671118088503;5987.221661184061;5992.951233534136;5385.919211211835;5397.2443857859935;5399.433059528732;5400.459954004607;5403.756830942617;20803.37589872306;21159.77822328649;21391;21475.355739327846;21510.354689359345;2206.9227577034726;2253.0649780341137;2256.4471704432353;2287.1080278691325;2326.6975293211945;13170.143940643828;13200.603981880558;13428.321459817524;13618.387172577257;13791.51729689456;1770.9557261068448;1802.3241284518306;1804.8014718380969;1815.137809540471;1820.55396427876;60761;62477.687611561654;63914;64066;64455.35544644541;9558.235341172689;9730;9849.458279794537;9890.20878329732;9945.403275803475;12870.292133932724;12893.48426062947;12910.354482276036;12913.870861291454;12945;7674.424418168548;7766;7788;7833.33416659579;7870.252326029018;7278.235785242579;7367.4106071515225;7370.189279179286;7450.739224127129;7654.693812247448;6057.394260573996;6077.20996270482;6078.300995385499;6111.144439778434;6135.263768347738;1610.846969537892;1653.5535405440464;1662.102464669065;1689.6113893804493;1711.6576684663116;949.8959804033959;964.5370222293338;986.5560497775886;1065.408698172506;1095.424901926479;16294.42969496062;17307.221175047154;17320.39378621742;17532.474025779247;17967.191476383596;896.5203616065373;920.2362039507193;935.3218916285639;943.2218419803689;945.2390825385493;9375.465644153377;9666.420014799134;9676.371035882645;9691.321607487409;9785.36395134314;506664;509759;512317;517508;521746;81965;83548;84507.57746211352;86173.56913215519;86876.56561717151;1152.383474840956;1152.9365884876245;1178.8231765235112;1182.3041529550035;1212.5574165429555;14564.417423303374;15378.307976140877;15521.37914483408;15673.68652626964;16022.599435014217;32382.67617323891;32619.673803261663;32645.673543265213;33310.83344583261;33383.833080835415;40803.183936320514;41704.58295417007;44049.55950440455;46451.76774116243;48786;9292.767680808034;9814.06766357195;9826.950865245914;9859.211263099078;9997.700068997941;11484.712882178012;11597.884021160018;11729.472173752372;17036.659266814346;17054.31782728677;11809.468573914366;12217.633470995885;13764;14126.929365353108;14185.077969932334;13954.162750235018;13967.860321397062;14196.787048194492;14347.497837575635;14395.712085758436;10696.89303106959;14404.927975360057;14424.783628245794;14798.48205312809;14884.404623814926;9446.433214007182;9453.818272715911;9573.138417542455;9573.420385636293;9607.183389411979;10164.237682174014;10260.897391025994;11165.720856978316;14037.438502459787;14341.282935853375 08:09:44 INFO - 2015-11-25 08:09:44,333 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1472.3595236072304;1641.802983641963;1687.2829047654743;1719.3896166860757;1735.0457248513314;6493.740250389985;7012.158540975084;7152.213256541776;7168.390686791616;7168.462365322602;7008.719651213945;7234.059572255599;7247.528910620806;7284.963575182117;7295.343419092281;1922.9711554326695;1957.980420195794;2178.096090122597;2190.561887622478;2222.1666874921902;2224.944376390595;2236.496788222645;2241.8206543476563;2243.416711654965;2243.7083179186766;2153.450870028141;2238.955220895581;2242.372135801978;2243.0915479230857;2243.1363924888956;1912.4836294200556;1958.5886963737632;2163.3185546552863;2217.46780772615;2219.2565490560683;2216.2021672198;2229.609818281794;2255.4586899144088;2255.5827171973237;2279.851809632385;7251.927480725178;7296;7303.6488249673985;7307.525010874329;7309.963450182715;4440.822367105312;4551.226291530448;4552.203364411214;4552.658550608718;4559.17934771741;2953.8966136185345;3040.4223197592387;3062.219134120807;3081.245094951729;3089.98030649885;966.7003228999033;972.7179118055826;990.7770751580908;1018.8624535687661;1023.6110278094351;9591.42451452915;9679.951600242024;9728.319017668766;9739.65911193112;9740.512974351253;4029.294873397144;4082.755034697928;4118.814653340607;4226.513950895686;4228.76741779199;8303.8339233215;8366.748997529961;8375.748727538183;8379.581020948928;8392.034915984617;4506.662000349987;4538.183127037165;4563.566461186182;4569.885752856138;4600.5169457207185;4153.1486045361235;4153.958460415418;4174.91650166995;4182.91634167315;4194.6853985951175;3393.389189945784;3430.091025878144;3442.552468179171;3446.4657978013447;3457.481377793301;1170.0990237517235;1187.9643610691694;1194.462491878658;1195.121585634558;1201.9338936358583;929.4051806843626;1268.3721557828933;1274.9681257968625;1290.2387591321165;1302.550620036086;6232.065190221412;6821.761238356634;7047.400970917427;7050.435965122778;7051.89422158668;597.3727586034646;617.0990354083089;619.3837132053635;619.9008158694677;621.7326549583632;5272.182811664198;5285.656432331963;5302.257683924254;5308.203769434538;5311.946880531223;8362.414630975894;8557.187067228604;8631.6978905738;8710.869336959951;8728.170823771732;8613.22480976715;8626.827463450696;8636.352273579507;8640.395172337876;8643.135686431433;6761.3238676132;6830;6901.792946211622;6987;6988.405985491188;6572.638504882278;6598.802035938929;6609.900851487136;6610.735570577315;6610.9669451652435;7700.922990770021;7772.611369431619;7785.260400261966;7916.604169791372;7925.009373828271;7831.060272767304;7957.482763620577;7974.880376794469;8038.758837234892;8096.149904259968;6264.655443950444;6324.715387807468;6345.682715864281;6357.268914074941;6403.519736019724;7010.298970103153;7066.57600543969;7070.151581810215;7077.5753454792875;7080.362767350757;6454.096426500388;6707.865842683022;6733.158355205599;6771.762988295386;6821.045053692586;6376.075469057054;6430.2926678066315;6435.678216089271;6566.146400967844;6612.669366531751;5500.9174862377895;5537.36320323168;5540.196671482532;5887.61730487517;6188.473979711594;5966.433188847052;6153.753849845956;6213.03697926822;6227.252729672468;6229.844253893689;6123.540734444845;6165.321814600301;6171.6297022178815;6176.969115154575;6180.103884936749 08:09:44 INFO - 2015-11-25 08:09:44,333 DEBUG : BROWSER_OUTPUT: |4;prototype.html;326.1845386533664;327.3387756731402;332.99001029969077;336.5708721380241;339.4331466451025;5935.940640593592;6059.909101363478;6094.26868775747;6101.389861013897;6115.969420152904;5879.970600147004;6003.549733769963;6013.308469526006;6015.428534289235;6021.789237376693;4673.532646735325;4681.180793361165;4681.9063618727605;4687.929681054787;4691.437027556698;4487.259602165639;4569.771511424432;4572.108438854425;4575.107853968478;4684.625229981593;4618.792154353062;4627.768611569425;4652.767361631905;4656.41794775653;4657.091867085919;4641.535846415365;4641.907161856744;4642.442906851165;4642.814287428532;4648.674592778473;4084.0402505411203;4084.979575102135;4087.5708050654553;4090.877273681794;4100.91798164038;5628.183913332585;5716.542676585863;5734.369219385864;5734.942650573483;5746.712664366765;5417.349918009864;5419.430959749248;5419.458054194589;5424.593155513313;5426.8371948841595;6163.599366041238;6408.423241908204;6411.871762564722;6425.389587989133;6425.935740642581;4395;4398.208322501949;4510.774461276923;4524.185646583614;4525.208088584517;6655.1348324717965;6675.566088204299;6678.699458524378;6687.364700353459;6688.83277918051;4608.5621865922685;4614.700044497097;4616.8614941551805;4618.930716039262;4619.19164146273;4470.636455880987;4572.7713614319155;4603.9079218415445;4635.351050852883;4645.721256724607;5333.120035194227;5334.653247538898;5337.466253374709;5337.572994160458;5341.813036543703;6441.355864413521;6441.677916104176;6453.225612926385;6454.935450645527;6456.257530383972;1598.057146283698;1606.807183138031;1608.2843134805134;1608.4611655095441;1609.0265389439455;1027.6916924922493;1028.4806172882795;1040.812653722322;1083.9349639021684;1098.6703988803326;6210.068489726486;6212.751489940351;6316.30520642729;6353.237611486558;6365.681715914278;6708.127943367331;6722.865542689119;6731;6734.326567343226;6743.15710536183;6181.876362472725;6344.397282258178;6344.492440604833;6344.555881088279;6357.936420635734;3600.063983364344;3632.364336241147;3637.9090522736615;3639.3449179148006;3642.8542858285373;6927.376536111673;6977.930220697728;6986.895196572055;6989.405900498515;6995.615241161785;6721.66391680414;6892.379685828299;6893.345132212432;6897.310268973062;6909.343612356818;6886.449084073262;6981.05755722981;6981.860362792614;6989.33601307875;6992.300769922967;6692.866142677217;6698.296678848651;6710.597364158166;6714.865702685821;6715.093462382516;6523.151990241238;6610.70251838678;6611.371919667624;6627.734890604322;6672.566283191577;6990.510664253454;7007.299270073156;7009.684564194521;7013.088298521159;7017.859642807218;4527.2303708369;4532.320151977098;4563.38394316763;4563.794629241758;4570.245909425041;6567.737290508326;6691.364320389555;6701.731930722716;6702.597844129368;6708.966455167693;6526.934730652632;6529;6533.575317604341;6536.542442029012;6537.346265373615;6480.449161821298;6522.34776522344;6523.086767852599;6524.706388212447;6529.5102867284195;6136.754529818757;6261.96869015652;6373.904391434039;6405.519586030973;6424.51816113784;6397.424231819158;6402.935970640234;6525.575837570543;6531.183602049744;6543;6311.589746666452;6350.7459701611415;6370.458511026614;6374.872502550016;6381.904271436025 08:09:44 INFO - 2015-11-25 08:09:44,335 DEBUG : BROWSER_OUTPUT: |5;yui.html;635.2662674610821;647.6729251727879;713.9535930164539;720.0747040053532;727.3526561360391;12338.44476998535;12565.623031309064;14156.92921535392;14291.857081429183;14336.713265734705;12955.48178072876;12981.415836287366;13025;13025.934870325658;13026.869731302684;675.395521008697;679.796061181646;794.213728408874;806.379088102161;808.7492877208055;812.6789917982394;816.0248503038871;818.6193420059672;818.6807145213371;819.8975128108987;851.9574021298941;852.3053711225363;852.518327145161;852.9914700853005;853.3599800149888;699.989500157498;705.3052743048094;828.225609055532;835.678263868409;838.8783626374142;618.9009758438673;619.4486906653083;619.7737825693634;622.7508996401431;624.1075262374802;12642.55751048718;12653.620391388273;12663.62009139718;12666.683332916658;12668.556600518938;10900.182486313559;11083.612073577468;11146.498407571678;11305.773884522347;11446;9293.767655808591;9480.762980925463;9513.904860951441;9653.806923861483;9889.703308900744;3467.930641387183;3475.826208689555;3501.107217659506;3504.56192975878;3601.657842504958;49905.25142122871;50152.49847501515;50717.49282507165;50785.23822142634;50798.98402031939;8598.455694316955;8671.56642167889;8786.612956901356;8807.119288071142;9040.954795225982;16068.678626427405;16130.838691613168;16205.513834584981;16206.189690515426;16214.10822404779;6913.89629155563;6967.442604591621;7257.201707812136;7328.59692716895;7423.257674232534;3917.666998305119;4070.8575199868455;4116.279651061112;4199.9370009449885;4208.095259519157;2893.667228268776;2927.780416468773;2935.1047930381437;2939.1182645206354;2939.147647182293;513.6840842881676;534.398801348483;538.951494365509;539.5629540072554;543.5379927061965;524.590819161055;530.7929907336141;540.5540429145892;579.5653260054959;588.2382314902212;7510.737124200736;7744.4578879477895;7948.880766788503;7973.362131029504;8069.919300807034;437.79642466253347;493.3167562925303;516.7571241516481;586.7271718650848;606.6208619612742;3575.5888072871953;3615.8553657853918;3740.831662575191;3742.0831896185064;3753.774773513543;61115;61848;61966;62074.379256207765;62117;53487.46512534825;54027.18959215615;54501.72749136308;54773.178402323996;54785.45214547803;47552.524474755504;48299.517004829504;48984.265236021485;50532.49467505352;50812;7688.077430708351;7694;8032.558209298545;8080.83838323242;8351.707690230813;33617.66382336145;34700.82649586736;35675;36050.819745901106;36084;45560.316595250435;46022;46121.30818037665;47128.764356178;47698.76150619225;9758.316917815684;9797.657082002095;9810.607575696893;10110.39337639744;10117.342372745748;10889.401082940514;11002.834957475805;11033.83449248278;11152.944235278772;11294.322340659586;9411;9778;10260;10318.380897146195;10623.946880265548;9391;9969.601215951281;10080.495975201356;10205.234607404325;10206.591736330463;9338.95330523343;9901.603935842486;10012.249081318785;10014.549345279625;10071.34536255141;8801.515916624647;8831.558422078999;8839.646414143363;9005.14451127142;9215.86176207344;8071.273585377344;8155.755327340189;8271.669133234604;8704.869426958474;8739.38824282294 08:09:44 INFO - 2015-11-25 08:09:44,335 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:09:44 INFO - 2015-11-25 08:09:44,335 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:09:44 INFO - 2015-11-25 08:09:44,335 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1142 08:09:44 INFO - 2015-11-25 08:09:44,335 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:09:44 INFO - 2015-11-25 08:09:44,335 DEBUG : BROWSER_OUTPUT: __startTimestamp1448467784326__endTimestamp 08:09:44 INFO - 2015-11-25 08:09:44,335 DEBUG : BROWSER_OUTPUT: 08:09:44 INFO - 2015-11-25 08:09:44,335 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:09:44 INFO - 2015-11-25 08:09:44,336 DEBUG : BROWSER_OUTPUT: Number of tests: 6 08:09:44 INFO - 2015-11-25 08:09:44,336 DEBUG : BROWSER_OUTPUT: 08:09:44 INFO - 2015-11-25 08:09:44,336 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5401.20 Median:5605.19 stddev:604.87 (10.8%) stddev-sans-first:579.14 08:09:44 INFO - 2015-11-25 08:09:44,336 DEBUG : BROWSER_OUTPUT: Values: 3099.2 3208.7 3237.6 3242.7 3278.8 5156.5 5262.3 5378.7 5416.6 5444.1 5283.2 5328.7 5352.9 5355.8 5385.3 4879.5 4932.4 4963.2 4977.0 5031.4 4842.8 4988.2 5003.9 5034.6 5067.6 4835.1 4894.9 4942.2 4951.1 4969.8 4923.6 4927.0 4928.8 4929.4 4980.3 4742.9 4915.6 4934.0 4935.7 4982.3 5076.4 5095.8 5178.5 5181.7 5206.3 5860.2 5877.4 5880.6 5889.1 5895.3 5633.5 5790.0 5914.3 5931.4 5941.6 5399.4 5453.4 5454.1 5457.9 5499.0 5980.1 5980.9 5981.9 5986.4 6054.3 5534.9 5627.8 5632.4 5756.6 5767.8 5713.7 5784.7 5788.0 5788.6 5820.2 5700.1 5701.7 5752.7 5758.8 5764.7 5548.3 5662.4 5757.9 5769.5 5773.5 4523.1 4531.8 4544.5 4558.0 4573.5 4011.1 4014.5 4021.9 4027.0 4055.5 5380.5 5459.2 5465.5 5555.0 5640.7 5842.2 5851.6 5851.8 5888.7 5890.0 5570.2 5570.7 5577.2 5578.9 5587.6 4980.7 5044.3 5068.8 5097.6 5104.8 6103.1 6121.7 6129.1 6132.4 6140.1 5851.1 5904.6 5906.7 5912.3 5912.4 5852.3 5922.2 5959.3 5962.6 6028.7 5783.0 5802.4 5825.5 5833.8 5836.9 5895.8 5903.4 5909.9 5928.1 5934.5 5503.3 5602.7 5605.0 5616.4 5734.6 5704.7 5705.5 5717.7 5720.0 5750.6 5608.9 5613.1 5613.6 5617.9 5619.5 5469.0 5560.6 5577.0 5663.9 5675.9 5605.2 5636.3 5640.7 5651.5 5678.1 08:09:44 INFO - 2015-11-25 08:09:44,336 DEBUG : BROWSER_OUTPUT: 08:09:44 INFO - 2015-11-25 08:09:44,336 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:47968.79 Median:3289.83 stddev:80409.17 (2444.2%) stddev-sans-first:80555.94 08:09:44 INFO - 2015-11-25 08:09:44,336 DEBUG : BROWSER_OUTPUT: Values: 558.4 583.6 597.8 603.4 605.2 31323.5 40601.4 41888.0 41938.8 41971.6 44380.6 44752.8 44753.6 44902.6 44951.3 7568.2 8667.4 8827.2 8836.6 8836.9 7694.8 7793.0 7794.5 7795.3 10309.8 10262.4 10336.1 10375.7 10383.8 10386.7 10210.1 10226.2 10272.7 10274.6 10303.1 7758.1 7957.8 10077.2 10354.7 10624.3 40805.2 40879.4 41203.6 41253.6 41286.0 3218.6 3276.3 3303.3 3466.4 3786.1 30345.7 30791.2 30797.4 31358.5 32072.8 3361.4 3432.2 3437.6 3442.4 3525.3 288368.1 288919.0 289339.0 289468.0 289651.0 57029.0 61966.7 62342.0 62356.1 62424.7 54460.5 65847.0 66782.7 66894.0 67015.7 15302.6 15496.5 15506.7 15600.1 15603.4 11007.3 11029.2 11032.8 11045.1 11064.0 19663.2 20871.5 20879.9 20949.5 20979.8 7618.7 7710.6 7787.5 7833.3 7857.9 906.7 937.7 962.4 1089.6 1140.4 28575.6 28988.1 29148.3 29453.9 29650.3 987.5 1030.4 1044.3 1071.7 1074.2 14725.3 14728.3 14770.3 15032.8 15057.5 319906.0 322518.0 323276.0 323569.0 324267.0 52582.0 52652.0 52783.7 52785.5 52845.2 1335.0 1342.8 1352.0 1360.8 1360.8 27555.2 28170.3 28173.2 28550.0 28813.3 47441.8 47800.0 47801.0 47814.8 47867.0 307915.0 309855.0 309864.0 310034.0 310862.0 27749.7 31738.4 32247.8 32254.0 32476.5 38693.6 40212.6 40681.0 42907.4 43530.6 36191.2 36587.3 36967.0 37262.4 37782.8 32231.7 35043.3 35398.1 36420.5 36896.4 33743.8 34132.1 34395.5 35758.5 36584.0 31461.0 32368.4 33218.2 33479.3 33572.5 25365.7 25636.6 25672.5 25892.4 25915.9 08:09:44 INFO - 2015-11-25 08:09:44,336 DEBUG : BROWSER_OUTPUT: 08:09:44 INFO - 2015-11-25 08:09:44,337 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28168.57 Median:348.73 stddev:84047.67 (24101.4%) stddev-sans-first:84257.48 08:09:44 INFO - 2015-11-25 08:09:44,338 DEBUG : BROWSER_OUTPUT: Values: 345.9 346.6 346.8 348.2 349.2 14058.9 14458.4 15991.2 16370.4 17192.0 14258.8 15543.4 15798.8 15871.2 15904.8 5302.5 5391.7 5422.9 5437.1 5439.2 4063.7 4180.9 5498.6 5517.0 5981.0 6004.9 6025.0 6043.9 6048.8 6063.5 5847.0 5976.5 5979.7 5987.2 5993.0 5385.9 5397.2 5399.4 5400.5 5403.8 20803.4 21159.8 21391.0 21475.4 21510.4 2206.9 2253.1 2256.4 2287.1 2326.7 13170.1 13200.6 13428.3 13618.4 13791.5 1771.0 1802.3 1804.8 1815.1 1820.6 60761.0 62477.7 63914.0 64066.0 64455.4 9558.2 9730.0 9849.5 9890.2 9945.4 12870.3 12893.5 12910.4 12913.9 12945.0 7674.4 7766.0 7788.0 7833.3 7870.3 7278.2 7367.4 7370.2 7450.7 7654.7 6057.4 6077.2 6078.3 6111.1 6135.3 1610.8 1653.6 1662.1 1689.6 1711.7 949.9 964.5 986.6 1065.4 1095.4 16294.4 17307.2 17320.4 17532.5 17967.2 896.5 920.2 935.3 943.2 945.2 9375.5 9666.4 9676.4 9691.3 9785.4 506664.0 509759.0 512317.0 517508.0 521746.0 81965.0 83548.0 84507.6 86173.6 86876.6 1152.4 1152.9 1178.8 1182.3 1212.6 14564.4 15378.3 15521.4 15673.7 16022.6 32382.7 32619.7 32645.7 33310.8 33383.8 40803.2 41704.6 44049.6 46451.8 48786.0 9292.8 9814.1 9827.0 9859.2 9997.7 11484.7 11597.9 11729.5 17036.7 17054.3 11809.5 12217.6 13764.0 14126.9 14185.1 13954.2 13967.9 14196.8 14347.5 14395.7 10696.9 14404.9 14424.8 14798.5 14884.4 9446.4 9453.8 9573.1 9573.4 9607.2 10164.2 10260.9 11165.7 14037.4 14341.3 08:09:44 INFO - 2015-11-25 08:09:44,338 DEBUG : BROWSER_OUTPUT: 08:09:44 INFO - 2015-11-25 08:09:44,338 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5091.65 Median:619.64 stddev:2577.53 (416.0%) stddev-sans-first:2570.40 08:09:44 INFO - 2015-11-25 08:09:44,338 DEBUG : BROWSER_OUTPUT: Values: 1472.4 1641.8 1687.3 1719.4 1735.0 6493.7 7012.2 7152.2 7168.4 7168.5 7008.7 7234.1 7247.5 7285.0 7295.3 1923.0 1958.0 2178.1 2190.6 2222.2 2224.9 2236.5 2241.8 2243.4 2243.7 2153.5 2239.0 2242.4 2243.1 2243.1 1912.5 1958.6 2163.3 2217.5 2219.3 2216.2 2229.6 2255.5 2255.6 2279.9 7251.9 7296.0 7303.6 7307.5 7310.0 4440.8 4551.2 4552.2 4552.7 4559.2 2953.9 3040.4 3062.2 3081.2 3090.0 966.7 972.7 990.8 1018.9 1023.6 9591.4 9680.0 9728.3 9739.7 9740.5 4029.3 4082.8 4118.8 4226.5 4228.8 8303.8 8366.7 8375.7 8379.6 8392.0 4506.7 4538.2 4563.6 4569.9 4600.5 4153.1 4154.0 4174.9 4182.9 4194.7 3393.4 3430.1 3442.6 3446.5 3457.5 1170.1 1188.0 1194.5 1195.1 1201.9 929.4 1268.4 1275.0 1290.2 1302.6 6232.1 6821.8 7047.4 7050.4 7051.9 597.4 617.1 619.4 619.9 621.7 5272.2 5285.7 5302.3 5308.2 5311.9 8362.4 8557.2 8631.7 8710.9 8728.2 8613.2 8626.8 8636.4 8640.4 8643.1 6761.3 6830.0 6901.8 6987.0 6988.4 6572.6 6598.8 6609.9 6610.7 6611.0 7700.9 7772.6 7785.3 7916.6 7925.0 7831.1 7957.5 7974.9 8038.8 8096.1 6264.7 6324.7 6345.7 6357.3 6403.5 7010.3 7066.6 7070.2 7077.6 7080.4 6454.1 6707.9 6733.2 6771.8 6821.0 6376.1 6430.3 6435.7 6566.1 6612.7 5500.9 5537.4 5540.2 5887.6 6188.5 5966.4 6153.8 6213.0 6227.3 6229.8 6123.5 6165.3 6171.6 6177.0 6180.1 08:09:44 INFO - 2015-11-25 08:09:44,338 DEBUG : BROWSER_OUTPUT: 08:09:44 INFO - 2015-11-25 08:09:44,338 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5412.25 Median:6150.18 stddev:1645.33 (26.8%) stddev-sans-first:1605.05 08:09:44 INFO - 2015-11-25 08:09:44,339 DEBUG : BROWSER_OUTPUT: Values: 326.2 327.3 333.0 336.6 339.4 5935.9 6059.9 6094.3 6101.4 6116.0 5880.0 6003.5 6013.3 6015.4 6021.8 4673.5 4681.2 4681.9 4687.9 4691.4 4487.3 4569.8 4572.1 4575.1 4684.6 4618.8 4627.8 4652.8 4656.4 4657.1 4641.5 4641.9 4642.4 4642.8 4648.7 4084.0 4085.0 4087.6 4090.9 4100.9 5628.2 5716.5 5734.4 5734.9 5746.7 5417.3 5419.4 5419.5 5424.6 5426.8 6163.6 6408.4 6411.9 6425.4 6425.9 4395.0 4398.2 4510.8 4524.2 4525.2 6655.1 6675.6 6678.7 6687.4 6688.8 4608.6 4614.7 4616.9 4618.9 4619.2 4470.6 4572.8 4603.9 4635.4 4645.7 5333.1 5334.7 5337.5 5337.6 5341.8 6441.4 6441.7 6453.2 6454.9 6456.3 1598.1 1606.8 1608.3 1608.5 1609.0 1027.7 1028.5 1040.8 1083.9 1098.7 6210.1 6212.8 6316.3 6353.2 6365.7 6708.1 6722.9 6731.0 6734.3 6743.2 6181.9 6344.4 6344.5 6344.6 6357.9 3600.1 3632.4 3637.9 3639.3 3642.9 6927.4 6977.9 6986.9 6989.4 6995.6 6721.7 6892.4 6893.3 6897.3 6909.3 6886.4 6981.1 6981.9 6989.3 6992.3 6692.9 6698.3 6710.6 6714.9 6715.1 6523.2 6610.7 6611.4 6627.7 6672.6 6990.5 7007.3 7009.7 7013.1 7017.9 4527.2 4532.3 4563.4 4563.8 4570.2 6567.7 6691.4 6701.7 6702.6 6709.0 6526.9 6529.0 6533.6 6536.5 6537.3 6480.4 6522.3 6523.1 6524.7 6529.5 6136.8 6262.0 6373.9 6405.5 6424.5 6397.4 6402.9 6525.6 6531.2 6543.0 6311.6 6350.7 6370.5 6374.9 6381.9 08:09:44 INFO - 2015-11-25 08:09:44,339 DEBUG : BROWSER_OUTPUT: 08:09:44 INFO - 2015-11-25 08:09:44,339 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13723.80 Median:540.06 stddev:16996.42 (3147.1%) stddev-sans-first:17015.68 08:09:44 INFO - 2015-11-25 08:09:44,340 DEBUG : BROWSER_OUTPUT: Values: 635.3 647.7 714.0 720.1 727.4 12338.4 12565.6 14156.9 14291.9 14336.7 12955.5 12981.4 13025.0 13025.9 13026.9 675.4 679.8 794.2 806.4 808.7 812.7 816.0 818.6 818.7 819.9 852.0 852.3 852.5 853.0 853.4 700.0 705.3 828.2 835.7 838.9 618.9 619.4 619.8 622.8 624.1 12642.6 12653.6 12663.6 12666.7 12668.6 10900.2 11083.6 11146.5 11305.8 11446.0 9293.8 9480.8 9513.9 9653.8 9889.7 3467.9 3475.8 3501.1 3504.6 3601.7 49905.3 50152.5 50717.5 50785.2 50799.0 8598.5 8671.6 8786.6 8807.1 9041.0 16068.7 16130.8 16205.5 16206.2 16214.1 6913.9 6967.4 7257.2 7328.6 7423.3 3917.7 4070.9 4116.3 4199.9 4208.1 2893.7 2927.8 2935.1 2939.1 2939.1 513.7 534.4 539.0 539.6 543.5 524.6 530.8 540.6 579.6 588.2 7510.7 7744.5 7948.9 7973.4 8069.9 437.8 493.3 516.8 586.7 606.6 3575.6 3615.9 3740.8 3742.1 3753.8 61115.0 61848.0 61966.0 62074.4 62117.0 53487.5 54027.2 54501.7 54773.2 54785.5 47552.5 48299.5 48984.3 50532.5 50812.0 7688.1 7694.0 8032.6 8080.8 8351.7 33617.7 34700.8 35675.0 36050.8 36084.0 45560.3 46022.0 46121.3 47128.8 47698.8 9758.3 9797.7 9810.6 10110.4 10117.3 10889.4 11002.8 11033.8 11152.9 11294.3 9411.0 9778.0 10260.0 10318.4 10623.9 9391.0 9969.6 10080.5 10205.2 10206.6 9339.0 9901.6 10012.2 10014.5 10071.3 8801.5 8831.6 8839.6 9005.1 9215.9 8071.3 8155.8 8271.7 8704.9 8739.4 08:09:44 INFO - 2015-11-25 08:09:44,340 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:09:44 INFO - 2015-11-25 08:09:44,340 DEBUG : BROWSER_OUTPUT: 08:09:45 INFO - 2015-11-25 08:09:45,027 INFO : Browser exited with error code: 0 08:09:45 INFO - 2015-11-25 08:09:45,048 INFO : Completed test dromaeo_css (00:18:33) 08:09:45 INFO - 2015-11-25 08:09:45,048 INFO : Starting test kraken 08:09:45 INFO - 2015-11-25 08:09:45,048 DEBUG : operating with platform_type : linux_ 08:09:45 INFO - 2015-11-25 08:09:45,048 INFO : Initialising browser for kraken test... 08:09:45 INFO - 2015-11-25 08:09:45,054 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpvuqVi5/profile http://localhost:53808/getInfo.html 08:09:48 INFO - 2015-11-25 08:09:48,826 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:09:48 INFO - 2015-11-25 08:09:48,826 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:09:48 INFO - 2015-11-25 08:09:48,826 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:09:48 INFO - 2015-11-25 08:09:48,826 DEBUG : BROWSER_OUTPUT: __metrics 08:09:49 INFO - 2015-11-25 08:09:49,024 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] 08:09:50 INFO - 2015-11-25 08:09:50,173 INFO : Browser initialized. 08:09:50 INFO - 2015-11-25 08:09:50,173 INFO : Running cycle 1/1 for kraken test... 08:09:50 INFO - 2015-11-25 08:09:50,173 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpvuqVi5/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:10:01 INFO - 2015-11-25 08:10:01,923 DEBUG : BROWSER_OUTPUT: RSS: Main: 138915840 08:10:01 INFO - 2015-11-25 08:10:01,923 DEBUG : BROWSER_OUTPUT: 08:10:04 INFO - 2015-11-25 08:10:04,356 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53808/tests/kraken/driver.html?testName=audio-beat-detection) 08:10:04 INFO - 2015-11-25 08:10:04,937 DEBUG : BROWSER_OUTPUT: RSS: Main: 176156672 08:10:04 INFO - 2015-11-25 08:10:04,937 DEBUG : BROWSER_OUTPUT: 08:10:07 INFO - 2015-11-25 08:10:07,499 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53808/tests/kraken/driver.html?testName=audio-dft) 08:10:08 INFO - 2015-11-25 08:10:08,072 DEBUG : BROWSER_OUTPUT: RSS: Main: 165584896 08:10:08 INFO - 2015-11-25 08:10:08,073 DEBUG : BROWSER_OUTPUT: 08:10:10 INFO - 2015-11-25 08:10:10,930 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53808/tests/kraken/driver.html?testName=audio-fft) 08:10:11 INFO - 2015-11-25 08:10:11,491 DEBUG : BROWSER_OUTPUT: RSS: Main: 150183936 08:10:11 INFO - 2015-11-25 08:10:11,491 DEBUG : BROWSER_OUTPUT: 08:10:13 INFO - 2015-11-25 08:10:13,483 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53808/tests/kraken/driver.html?testName=audio-oscillator) 08:10:14 INFO - 2015-11-25 08:10:14,044 DEBUG : BROWSER_OUTPUT: RSS: Main: 152039424 08:10:14 INFO - 2015-11-25 08:10:14,044 DEBUG : BROWSER_OUTPUT: 08:10:16 INFO - 2015-11-25 08:10:16,217 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53808/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:10:16 INFO - 2015-11-25 08:10:16,770 DEBUG : BROWSER_OUTPUT: RSS: Main: 155721728 08:10:16 INFO - 2015-11-25 08:10:16,771 DEBUG : BROWSER_OUTPUT: 08:10:19 INFO - 2015-11-25 08:10:19,700 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53808/tests/kraken/driver.html?testName=imaging-darkroom) 08:10:20 INFO - 2015-11-25 08:10:20,270 DEBUG : BROWSER_OUTPUT: RSS: Main: 155230208 08:10:20 INFO - 2015-11-25 08:10:20,270 DEBUG : BROWSER_OUTPUT: 08:10:23 INFO - 2015-11-25 08:10:23,635 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53808/tests/kraken/driver.html?testName=imaging-desaturate) 08:10:24 INFO - 2015-11-25 08:10:24,205 DEBUG : BROWSER_OUTPUT: RSS: Main: 155938816 08:10:24 INFO - 2015-11-25 08:10:24,206 DEBUG : BROWSER_OUTPUT: 08:10:26 INFO - 2015-11-25 08:10:26,963 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53808/tests/kraken/driver.html?testName=json-parse-financial) 08:10:27 INFO - 2015-11-25 08:10:27,530 DEBUG : BROWSER_OUTPUT: RSS: Main: 155049984 08:10:27 INFO - 2015-11-25 08:10:27,530 DEBUG : BROWSER_OUTPUT: 08:10:29 INFO - 2015-11-25 08:10:29,084 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53808/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:10:29 INFO - 2015-11-25 08:10:29,652 DEBUG : BROWSER_OUTPUT: RSS: Main: 164868096 08:10:29 INFO - 2015-11-25 08:10:29,652 DEBUG : BROWSER_OUTPUT: 08:10:31 INFO - 2015-11-25 08:10:31,449 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53808/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:10:32 INFO - 2015-11-25 08:10:32,056 DEBUG : BROWSER_OUTPUT: RSS: Main: 165347328 08:10:32 INFO - 2015-11-25 08:10:32,056 DEBUG : BROWSER_OUTPUT: 08:10:34 INFO - 2015-11-25 08:10:34,112 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53808/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:10:34 INFO - 2015-11-25 08:10:34,681 DEBUG : BROWSER_OUTPUT: RSS: Main: 170651648 08:10:34 INFO - 2015-11-25 08:10:34,681 DEBUG : BROWSER_OUTPUT: 08:10:37 INFO - 2015-11-25 08:10:37,115 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53808/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:10:37 INFO - 2015-11-25 08:10:37,697 DEBUG : BROWSER_OUTPUT: RSS: Main: 178647040 08:10:37 INFO - 2015-11-25 08:10:37,697 DEBUG : BROWSER_OUTPUT: 08:10:40 INFO - 2015-11-25 08:10:40,198 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53808/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:10:40 INFO - 2015-11-25 08:10:40,772 DEBUG : BROWSER_OUTPUT: RSS: Main: 177868800 08:10:40 INFO - 2015-11-25 08:10:40,772 DEBUG : BROWSER_OUTPUT: 08:10:42 INFO - 2015-11-25 08:10:42,425 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53808/tests/kraken/driver.html?testName=ai-astar) 08:10:42 INFO - 2015-11-25 08:10:42,675 DEBUG : BROWSER_OUTPUT: RSS: Main: 235917312 08:10:42 INFO - 2015-11-25 08:10:42,675 DEBUG : BROWSER_OUTPUT: 08:10:42 INFO - 2015-11-25 08:10:42,675 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:10:42 INFO - 2015-11-25 08:10:42,675 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:10:42 INFO - 2015-11-25 08:10:42,675 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:10:42 INFO - 2015-11-25 08:10:42,675 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:10:42 INFO - 2015-11-25 08:10:42,676 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;109;103;109;109;110;98;109;110;101 08:10:42 INFO - 2015-11-25 08:10:42,676 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;121;146;167;123;157;152;134;166;125 08:10:42 INFO - 2015-11-25 08:10:42,677 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;161;160;161;162;159;161;162;160;161 08:10:42 INFO - 2015-11-25 08:10:42,678 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;94;90;71;73;72;130;90;72 08:10:42 INFO - 2015-11-25 08:10:42,678 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;137;94;93;92;134;93;93;138 08:10:42 INFO - 2015-11-25 08:10:42,678 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;110;109;109;109;109;109 08:10:42 INFO - 2015-11-25 08:10:42,678 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;146;145;145;145;146;147;145;145 08:10:42 INFO - 2015-11-25 08:10:42,678 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;96;96;95;95;96;95;96 08:10:42 INFO - 2015-11-25 08:10:42,679 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;58;58;57;57;58;57;58;56;60;57 08:10:42 INFO - 2015-11-25 08:10:42,679 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;70;69;70;69;69;71;70;70;70;71 08:10:42 INFO - 2015-11-25 08:10:42,679 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;88;84;85;85;85;85;84;85;86;93 08:10:42 INFO - 2015-11-25 08:10:42,679 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;122;113;116;123;130;129;125;115;125;123 08:10:42 INFO - 2015-11-25 08:10:42,679 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;147;147;149;148;136;148;147;146;136 08:10:42 INFO - 2015-11-25 08:10:42,680 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;68;65;65;65;64;65;64;66;64 08:10:42 INFO - 2015-11-25 08:10:42,680 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:10:42 INFO - 2015-11-25 08:10:42,680 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:10:42 INFO - 2015-11-25 08:10:42,680 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,916 08:10:42 INFO - 2015-11-25 08:10:42,680 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:10:42 INFO - 2015-11-25 08:10:42,681 DEBUG : BROWSER_OUTPUT: __startTimestamp1448467842674__endTimestamp 08:10:42 INFO - 2015-11-25 08:10:42,681 DEBUG : BROWSER_OUTPUT: 08:10:42 INFO - 2015-11-25 08:10:42,682 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:10:42 INFO - 2015-11-25 08:10:42,682 DEBUG : BROWSER_OUTPUT: Number of tests: 14 08:10:42 INFO - 2015-11-25 08:10:42,683 DEBUG : BROWSER_OUTPUT: 08:10:42 INFO - 2015-11-25 08:10:42,683 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.50 Median:109.00 stddev:4.28 (3.9%) stddev-sans-first:4.53 08:10:42 INFO - 2015-11-25 08:10:42,683 DEBUG : BROWSER_OUTPUT: Values: 107.0 109.0 103.0 109.0 109.0 110.0 98.0 109.0 110.0 101.0 08:10:42 INFO - 2015-11-25 08:10:42,683 DEBUG : BROWSER_OUTPUT: 08:10:42 INFO - 2015-11-25 08:10:42,684 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.70 Median:149.00 stddev:17.27 (11.6%) stddev-sans-first:18.30 08:10:42 INFO - 2015-11-25 08:10:42,684 DEBUG : BROWSER_OUTPUT: Values: 146.0 121.0 146.0 167.0 123.0 157.0 152.0 134.0 166.0 125.0 08:10:42 INFO - 2015-11-25 08:10:42,684 DEBUG : BROWSER_OUTPUT: 08:10:42 INFO - 2015-11-25 08:10:42,684 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:0.97 08:10:42 INFO - 2015-11-25 08:10:42,685 DEBUG : BROWSER_OUTPUT: Values: 160.0 161.0 160.0 161.0 162.0 159.0 161.0 162.0 160.0 161.0 08:10:42 INFO - 2015-11-25 08:10:42,685 DEBUG : BROWSER_OUTPUT: 08:10:42 INFO - 2015-11-25 08:10:42,685 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:83.40 Median:72.50 stddev:18.86 (26.0%) stddev-sans-first:19.46 08:10:42 INFO - 2015-11-25 08:10:42,685 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 94.0 90.0 71.0 73.0 72.0 130.0 90.0 72.0 08:10:42 INFO - 2015-11-25 08:10:42,686 DEBUG : BROWSER_OUTPUT: 08:10:42 INFO - 2015-11-25 08:10:42,686 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.00 Median:93.00 stddev:20.96 (22.5%) stddev-sans-first:21.70 08:10:42 INFO - 2015-11-25 08:10:42,686 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 137.0 94.0 93.0 92.0 134.0 93.0 93.0 138.0 08:10:42 INFO - 2015-11-25 08:10:42,686 DEBUG : BROWSER_OUTPUT: 08:10:42 INFO - 2015-11-25 08:10:42,686 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 08:10:42 INFO - 2015-11-25 08:10:42,686 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 109.0 08:10:42 INFO - 2015-11-25 08:10:42,687 DEBUG : BROWSER_OUTPUT: 08:10:42 INFO - 2015-11-25 08:10:42,687 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.73 08:10:42 INFO - 2015-11-25 08:10:42,687 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 146.0 145.0 145.0 145.0 146.0 147.0 145.0 145.0 08:10:42 INFO - 2015-11-25 08:10:42,687 DEBUG : BROWSER_OUTPUT: 08:10:42 INFO - 2015-11-25 08:10:42,687 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 08:10:42 INFO - 2015-11-25 08:10:42,687 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 96.0 96.0 95.0 95.0 96.0 95.0 96.0 08:10:42 INFO - 2015-11-25 08:10:42,687 DEBUG : BROWSER_OUTPUT: 08:10:42 INFO - 2015-11-25 08:10:42,687 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.60 Median:58.00 stddev:1.07 (1.9%) stddev-sans-first:1.13 08:10:42 INFO - 2015-11-25 08:10:42,688 DEBUG : BROWSER_OUTPUT: Values: 58.0 58.0 57.0 57.0 58.0 57.0 58.0 56.0 60.0 57.0 08:10:42 INFO - 2015-11-25 08:10:42,688 DEBUG : BROWSER_OUTPUT: 08:10:42 INFO - 2015-11-25 08:10:42,688 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:70.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 08:10:42 INFO - 2015-11-25 08:10:42,688 DEBUG : BROWSER_OUTPUT: Values: 70.0 69.0 70.0 69.0 69.0 71.0 70.0 70.0 70.0 71.0 08:10:42 INFO - 2015-11-25 08:10:42,688 DEBUG : BROWSER_OUTPUT: 08:10:42 INFO - 2015-11-25 08:10:42,688 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:85.00 stddev:2.71 (3.2%) stddev-sans-first:2.77 08:10:42 INFO - 2015-11-25 08:10:42,688 DEBUG : BROWSER_OUTPUT: Values: 88.0 84.0 85.0 85.0 85.0 85.0 84.0 85.0 86.0 93.0 08:10:42 INFO - 2015-11-25 08:10:42,688 DEBUG : BROWSER_OUTPUT: 08:10:42 INFO - 2015-11-25 08:10:42,688 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:122.10 Median:124.00 stddev:5.76 (4.6%) stddev-sans-first:6.11 08:10:42 INFO - 2015-11-25 08:10:42,688 DEBUG : BROWSER_OUTPUT: Values: 122.0 113.0 116.0 123.0 130.0 129.0 125.0 115.0 125.0 123.0 08:10:42 INFO - 2015-11-25 08:10:42,688 DEBUG : BROWSER_OUTPUT: 08:10:42 INFO - 2015-11-25 08:10:42,689 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:144.30 Median:147.00 stddev:5.17 (3.5%) stddev-sans-first:5.11 08:10:42 INFO - 2015-11-25 08:10:42,689 DEBUG : BROWSER_OUTPUT: Values: 139.0 147.0 147.0 149.0 148.0 136.0 148.0 147.0 146.0 136.0 08:10:42 INFO - 2015-11-25 08:10:42,689 DEBUG : BROWSER_OUTPUT: 08:10:42 INFO - 2015-11-25 08:10:42,689 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.00 stddev:1.20 (1.8%) stddev-sans-first:1.27 08:10:42 INFO - 2015-11-25 08:10:42,689 DEBUG : BROWSER_OUTPUT: Values: 65.0 68.0 65.0 65.0 65.0 64.0 65.0 64.0 66.0 64.0 08:10:42 INFO - 2015-11-25 08:10:42,689 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:10:42 INFO - 2015-11-25 08:10:42,689 DEBUG : BROWSER_OUTPUT: 08:10:43 INFO - 2015-11-25 08:10:43,483 INFO : Browser exited with error code: 0 08:10:43 INFO - 2015-11-25 08:10:43,532 INFO : Completed test kraken (00:00:58) 08:10:43 INFO - 2015-11-25 08:10:43,532 INFO : Starting test v8_7 08:10:43 INFO - 2015-11-25 08:10:43,532 DEBUG : operating with platform_type : linux_ 08:10:43 INFO - 2015-11-25 08:10:43,532 INFO : Initialising browser for v8_7 test... 08:10:43 INFO - 2015-11-25 08:10:43,538 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpyzuo4M/profile http://localhost:53808/getInfo.html 08:10:47 INFO - 2015-11-25 08:10:47,342 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:10:47 INFO - 2015-11-25 08:10:47,343 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:10:47 INFO - 2015-11-25 08:10:47,343 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:10:47 INFO - 2015-11-25 08:10:47,343 DEBUG : BROWSER_OUTPUT: __metrics 08:10:47 INFO - 2015-11-25 08:10:47,411 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] 08:10:48 INFO - 2015-11-25 08:10:48,756 INFO : Browser initialized. 08:10:48 INFO - 2015-11-25 08:10:48,757 INFO : Running cycle 1/1 for v8_7 test... 08:10:48 INFO - 2015-11-25 08:10:48,757 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpyzuo4M/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:11:00 INFO - 2015-11-25 08:11:00,512 DEBUG : BROWSER_OUTPUT: RSS: Main: 139444224 08:11:00 INFO - 2015-11-25 08:11:00,512 DEBUG : BROWSER_OUTPUT: 08:11:21 INFO - 2015-11-25 08:11:21,928 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53808/tests/v8_7/run.html (next: http://localhost:53808/tests/v8_7/run.html) 08:11:22 INFO - 2015-11-25 08:11:22,178 DEBUG : BROWSER_OUTPUT: RSS: Main: 567144448 08:11:22 INFO - 2015-11-25 08:11:22,178 DEBUG : BROWSER_OUTPUT: 08:11:22 INFO - 2015-11-25 08:11:22,179 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:11:22 INFO - 2015-11-25 08:11:22,179 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:11:22 INFO - 2015-11-25 08:11:22,179 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:11:22 INFO - 2015-11-25 08:11:22,179 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:11:22 INFO - 2015-11-25 08:11:22,179 DEBUG : BROWSER_OUTPUT: |0;Richards;166.80567139282735 08:11:22 INFO - 2015-11-25 08:11:22,179 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.28793471925394 08:11:22 INFO - 2015-11-25 08:11:22,179 DEBUG : BROWSER_OUTPUT: |2;Encrypt;282.6455624646693 08:11:22 INFO - 2015-11-25 08:11:22,180 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5181.347150259067 08:11:22 INFO - 2015-11-25 08:11:22,180 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1921.3051823416506 08:11:22 INFO - 2015-11-25 08:11:22,180 DEBUG : BROWSER_OUTPUT: |5;Earley;762.7765064836003 08:11:22 INFO - 2015-11-25 08:11:22,180 DEBUG : BROWSER_OUTPUT: |6;Boyer;12219.512195121952 08:11:22 INFO - 2015-11-25 08:11:22,180 DEBUG : BROWSER_OUTPUT: |7;RegExp;25200 08:11:22 INFO - 2015-11-25 08:11:22,180 DEBUG : BROWSER_OUTPUT: |8;Splay;604.9457177322075 08:11:22 INFO - 2015-11-25 08:11:22,181 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4878.048780487805 08:11:22 INFO - 2015-11-25 08:11:22,181 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:11:22 INFO - 2015-11-25 08:11:22,181 DEBUG : BROWSER_OUTPUT: __startTimestamp1448467882176__endTimestamp 08:11:22 INFO - 2015-11-25 08:11:22,181 DEBUG : BROWSER_OUTPUT: 08:11:22 INFO - 2015-11-25 08:11:22,181 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:11:22 INFO - 2015-11-25 08:11:22,181 DEBUG : BROWSER_OUTPUT: Number of tests: 10 08:11:22 INFO - 2015-11-25 08:11:22,182 DEBUG : BROWSER_OUTPUT: 08:11:22 INFO - 2015-11-25 08:11:22,182 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.81 Median:166.81 stddev:0.00 (0.0%) 08:11:22 INFO - 2015-11-25 08:11:22,182 DEBUG : BROWSER_OUTPUT: Values: 166.8 08:11:22 INFO - 2015-11-25 08:11:22,182 DEBUG : BROWSER_OUTPUT: 08:11:22 INFO - 2015-11-25 08:11:22,182 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.29 Median:194.29 stddev:0.00 (0.0%) 08:11:22 INFO - 2015-11-25 08:11:22,183 DEBUG : BROWSER_OUTPUT: Values: 194.3 08:11:22 INFO - 2015-11-25 08:11:22,183 DEBUG : BROWSER_OUTPUT: 08:11:22 INFO - 2015-11-25 08:11:22,183 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:282.65 Median:282.65 stddev:0.00 (0.0%) 08:11:22 INFO - 2015-11-25 08:11:22,183 DEBUG : BROWSER_OUTPUT: Values: 282.6 08:11:22 INFO - 2015-11-25 08:11:22,183 DEBUG : BROWSER_OUTPUT: 08:11:22 INFO - 2015-11-25 08:11:22,183 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5181.35 Median:5181.35 stddev:0.00 (0.0%) 08:11:22 INFO - 2015-11-25 08:11:22,184 DEBUG : BROWSER_OUTPUT: Values: 5181.3 08:11:22 INFO - 2015-11-25 08:11:22,184 DEBUG : BROWSER_OUTPUT: 08:11:22 INFO - 2015-11-25 08:11:22,184 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1921.31 Median:1921.31 stddev:0.00 (0.0%) 08:11:22 INFO - 2015-11-25 08:11:22,184 DEBUG : BROWSER_OUTPUT: Values: 1921.3 08:11:22 INFO - 2015-11-25 08:11:22,184 DEBUG : BROWSER_OUTPUT: 08:11:22 INFO - 2015-11-25 08:11:22,185 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:762.78 Median:762.78 stddev:0.00 (0.0%) 08:11:22 INFO - 2015-11-25 08:11:22,185 DEBUG : BROWSER_OUTPUT: Values: 762.8 08:11:22 INFO - 2015-11-25 08:11:22,185 DEBUG : BROWSER_OUTPUT: 08:11:22 INFO - 2015-11-25 08:11:22,185 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12219.51 Median:12219.51 stddev:0.00 (0.0%) 08:11:22 INFO - 2015-11-25 08:11:22,185 DEBUG : BROWSER_OUTPUT: Values: 12219.5 08:11:22 INFO - 2015-11-25 08:11:22,185 DEBUG : BROWSER_OUTPUT: 08:11:22 INFO - 2015-11-25 08:11:22,186 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25200.00 Median:25200.00 stddev:0.00 (0.0%) 08:11:22 INFO - 2015-11-25 08:11:22,186 DEBUG : BROWSER_OUTPUT: Values: 25200.0 08:11:22 INFO - 2015-11-25 08:11:22,186 DEBUG : BROWSER_OUTPUT: 08:11:22 INFO - 2015-11-25 08:11:22,186 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:604.95 Median:604.95 stddev:0.00 (0.0%) 08:11:22 INFO - 2015-11-25 08:11:22,187 DEBUG : BROWSER_OUTPUT: Values: 604.9 08:11:22 INFO - 2015-11-25 08:11:22,187 DEBUG : BROWSER_OUTPUT: 08:11:22 INFO - 2015-11-25 08:11:22,187 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4878.05 Median:4878.05 stddev:0.00 (0.0%) 08:11:22 INFO - 2015-11-25 08:11:22,187 DEBUG : BROWSER_OUTPUT: Values: 4878.0 08:11:22 INFO - 2015-11-25 08:11:22,187 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:11:22 INFO - 2015-11-25 08:11:22,187 DEBUG : BROWSER_OUTPUT: 08:11:23 INFO - 2015-11-25 08:11:23,063 INFO : Browser exited with error code: 0 08:11:23 INFO - 2015-11-25 08:11:23,080 INFO : Completed test v8_7 (00:00:39) 08:11:23 INFO - 2015-11-25 08:11:23,174 INFO : Completed test suite (00:20:11) 08:11:23 INFO - 2015-11-25 08:11:23,175 DEBUG : Working with test: dromaeo_css 08:11:23 INFO - 2015-11-25 08:11:23,175 DEBUG : Generating results file: dromaeo_css 08:11:23 INFO - 2015-11-25 08:11:23,176 DEBUG : Working with test: kraken 08:11:23 INFO - 2015-11-25 08:11:23,176 DEBUG : Generating results file: kraken 08:11:23 INFO - 2015-11-25 08:11:23,176 INFO : javascript benchmark 08:11:23 INFO - 2015-11-25 08:11:23,176 DEBUG : Working with test: v8_7 08:11:23 INFO - 2015-11-25 08:11:23,177 DEBUG : Generating results file: v8_7 08:11:23 INFO - 2015-11-25 08:11:23,177 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:11:23 INFO - 2015-11-25 08:11:23,239 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 08:11:23 INFO - 2015-11-25 08:11:23,239 DEBUG : process_Request line: dromaeo_css 6914.92 graph.html#tests=[[72,64,35]] 08:11:23 INFO - 2015-11-25 08:11:23,239 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:11:23 INFO - 2015-11-25 08:11:23,284 DEBUG : process_Request line: kraken 1495.80 graph.html#tests=[[232,64,35]] 08:11:23 INFO - 2015-11-25 08:11:23,285 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:11:23 INFO - 2015-11-25 08:11:23,326 DEBUG : process_Request line: v8_7 19920.32 graph.html#tests=[[230,64,35]] 08:11:23 INFO - 2015-11-25 08:11:23,328 INFO : javascript benchmark 08:11:23 INFO - 2015-11-25 08:11:23,331 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3099.1942095055288, 3208.6791320867933, 3237.6276728176254, 3242.675732426756, 3278.803271803691, 5156.535911767949, 5262.315898933133, 5378.731063446827, 5416.5937554683405, 5444.074507333753, 5283.181106928422, 5328.733563321828, 5352.866178345534, 5355.758990845412, 5385.272988146599, 4879.512048795119, 4932.358793356859, 4963.230699241623, 4976.950230497703, 5031.371078615173, 4842.806287748486, 4988.176950803113, 5003.899922001558, 5034.572061374787, 5067.619928505358, 4835.057163853033, 4894.926576101361, 4942.233953737176, 4951.108800415924, 4969.751512424365, 4923.606111511071, 4927.039227350668, 4928.778204980785, 4929.433115191763, 4980.252962055684, 4742.857714268577, 4915.606751459874, 4933.975330123362, 4935.679180853269, 4982.277569752402, 5076.36545431821, 5095.79616815327, 5178.533931946105, 5181.740912954375, 5206.297149884755, 5860.150278209679, 5877.38287479817, 5880.647161170301, 5889.116632505115, 5895.292564892197, 5633.521150702195, 5790.044642634002, 5914.319853216892, 5931.377205393458, 5941.643501389931, 5399.406065332811, 5453.372862120827, 5454.072807622713, 5457.863553411117, 5499.0, 5980.102984552352, 5980.8803823924145, 5981.940180598225, 5986.3714309997695, 6054.303755068224, 5534.889302214014, 5627.831165065055, 5632.3804381518, 5756.5682573807135, 5767.769289228384, 5713.74288157034, 5784.681842498703, 5788.0, 5788.594798364074, 5820.156077368758, 5700.059490184156, 5701.686407247641, 5752.712364381764, 5758.827235182951, 5764.682942438122, 5548.250986116904, 5662.433756624387, 5757.913631295534, 5769.53843692512, 5773.509251713567, 4523.095380923828, 4531.796069176895, 4544.477385100689, 4558.0428110097255, 4573.451185857718, 4011.097503061788, 4014.4781178446615, 4021.879343619696, 4026.978774052779, 4055.47278853753, 5380.461953804588, 5459.235707001023, 5465.508104270635, 5555.0, 5640.717964101778, 5842.2405087338375, 5851.590388672752, 5851.795187168429, 5888.676122813286, 5890.028145355968, 5570.164475328733, 5570.749316280696, 5577.163425486209, 5578.888422231614, 5587.552995760329, 4980.651354405157, 5044.344235249394, 5068.771905264272, 5097.643164978416, 5104.770285337168, 6103.0669477704105, 6121.704725412397, 6129.09499847894, 6132.41742034506, 6140.109684095871, 5851.122331650116, 5904.645721256738, 5906.675132867734, 5912.320083190316, 5912.438318359749, 5852.3269823969085, 5922.230110085661, 5959.255093113361, 5962.582619216787, 6028.728707208274, 5782.971085144547, 5802.361740208489, 5825.475707186373, 5833.82498525045, 5836.941630583639, 5895.823125306248, 5903.350631430625, 5909.852253693692, 5928.140419639214, 5934.465898069195, 5503.3120859892515, 5602.663840169603, 5604.971975140262, 5616.438356164351, 5734.627249228787, 5704.743286552032, 5705.4579814917515, 5717.7141142941855, 5719.971400142973, 5750.597458177887, 5608.85977850557, 5613.129964855454, 5613.635113717759, 5617.943820561742, 5619.466150715675, 5469.0, 5560.638558493685, 5577.024020796425, 5663.858403539944, 5675.914861277001, 5605.1872478491205, 5636.267285253055, 5640.717964101696, 5651.491365777099, 5678.063119585222], "unit": "ms", "name": "dojo.html", "value": 5361.643932277379}, {"lowerIsBetter": false, "replicates": [558.366254301368, 583.6177303865968, 597.7698586044369, 603.3544107804648, 605.1860247966483, 31323.530147047783, 40601.390979135336, 41888.0, 41938.790306048424, 41971.580284197145, 44380.556194438046, 44752.77623611877, 44753.552464475346, 44902.550974490245, 44951.32573011408, 7568.205338439468, 8667.393282470246, 8827.205551500363, 8836.558172091402, 8836.867446988268, 7694.769156925301, 7793.0, 7794.454388192828, 7795.298423141916, 10309.84535231972, 10262.435566043865, 10336.069753722164, 10375.688729338131, 10383.84424233637, 10386.740331491697, 10210.081092701656, 10226.233032522554, 10272.69181924536, 10274.640387586473, 10303.124234440082, 7758.107817600991, 7957.7612671619945, 10077.244206684529, 10354.689359319233, 10624.309419887682, 40805.183896321905, 40879.38680919789, 41203.58796412028, 41253.587464125274, 41286.0, 3218.6459489456142, 3276.311974485361, 3303.339332133583, 3466.35872363611, 3786.110264087937, 30345.696543034508, 30791.230219244473, 30797.384052318826, 31358.52962205569, 32072.839635801905, 3361.3949489091956, 3432.1591210153424, 3437.6390478999847, 3442.431998720208, 3525.312564050011, 288368.1163188383, 288919.0, 289339.0, 289468.0, 289651.0, 57029.015937768745, 61966.69016654978, 62342.0, 62356.06465903015, 62424.687876560325, 54460.45539544554, 65847.03688334036, 66782.66608667023, 66894.0, 67015.66492167606, 15302.617434564225, 15496.457623983106, 15506.689866202838, 15600.14199219031, 15603.375864965501, 11007.339559626447, 11029.22795404314, 11032.779344413068, 11045.061169800658, 11064.0, 19663.213471461266, 20871.478213044797, 20879.8956005219, 20949.476263093544, 20979.790202097785, 7618.657160427683, 7710.575918324434, 7787.493812902257, 7833.255840695125, 7857.921420785833, 906.6781292641149, 937.7186843946789, 962.4417837654144, 1089.6131873185063, 1140.3841925360211, 28575.57136642952, 28988.13035608935, 29148.271293217414, 29453.85273073621, 29650.258743531584, 987.4519641598981, 1030.3766221436074, 1044.300318786415, 1071.6892101290694, 1074.1943542343242, 14725.263736813116, 14728.263586820616, 14770.335334909954, 15032.774508382383, 15057.472988445352, 319906.0, 322518.0, 323276.0, 323569.0, 324267.0, 52582.0, 52652.0, 52783.736081319345, 52785.47214527882, 52845.2073218902, 1334.9532766353132, 1342.812006319116, 1352.0, 1360.775472047121, 1360.843502997168, 27555.17334479969, 28170.2957426066, 28173.154805355873, 28550.0, 28813.27966800847, 47441.76279118582, 47800.0, 47801.0, 47814.760926195144, 47867.0, 307915.0, 309855.0, 309864.0, 310034.0, 310862.0, 27749.722502775523, 31738.36523269568, 32247.838760806986, 32254.0, 32476.51285230676, 38693.613063869, 40212.59787402089, 40681.0, 42907.35638965481, 43530.56469435265, 36191.159115015456, 36587.268254634226, 36967.0, 37262.44106338461, 37782.81108594439, 32231.677683222868, 35043.29913401769, 35398.115047124025, 36420.45369319515, 36896.44655330118, 33743.84113497801, 34132.14669633279, 34395.4840677385, 35758.4636230462, 36584.0, 31461.0, 32368.35263294674, 33218.16584118861, 33479.33041339111, 33572.49641255432, 25365.746342537077, 25636.615450768626, 25672.486550269263, 25892.35269118287, 25915.87042064853], "unit": "ms", "name": "ext.html", "value": 18800.709491983514}, {"lowerIsBetter": false, "replicates": [345.85474100877644, 346.64122633074766, 346.760735092786, 348.2477847848647, 349.20381530111325, 14058.859411405883, 14458.421663133488, 15991.200439977998, 16370.427035053775, 17191.999999999985, 14258.786118208236, 15543.378264869392, 15798.763018554731, 15871.206439678028, 15904.84095159048, 5302.496262855032, 5391.703456309901, 5422.864428389283, 5437.102878025121, 5439.156930675752, 4063.674906007512, 4180.853670121546, 5498.5876059295515, 5517.0, 5981.043033114703, 6004.909926351086, 6024.9698751506185, 6043.939560604404, 6048.818535443944, 6063.545234107438, 5846.970765146169, 5976.462118409364, 5979.671118088503, 5987.221661184061, 5992.951233534136, 5385.919211211835, 5397.2443857859935, 5399.433059528732, 5400.459954004607, 5403.756830942617, 20803.37589872306, 21159.77822328649, 21391.0, 21475.355739327846, 21510.354689359345, 2206.9227577034726, 2253.0649780341137, 2256.4471704432353, 2287.1080278691325, 2326.6975293211945, 13170.143940643828, 13200.603981880558, 13428.321459817524, 13618.387172577257, 13791.51729689456, 1770.9557261068448, 1802.3241284518306, 1804.8014718380969, 1815.137809540471, 1820.55396427876, 60761.0, 62477.687611561654, 63914.0, 64066.0, 64455.35544644541, 9558.235341172689, 9730.0, 9849.458279794537, 9890.20878329732, 9945.403275803475, 12870.292133932724, 12893.48426062947, 12910.354482276036, 12913.870861291454, 12945.0, 7674.424418168548, 7766.0, 7788.0, 7833.33416659579, 7870.252326029018, 7278.235785242579, 7367.4106071515225, 7370.189279179286, 7450.739224127129, 7654.693812247448, 6057.394260573996, 6077.20996270482, 6078.300995385499, 6111.144439778434, 6135.263768347738, 1610.846969537892, 1653.5535405440464, 1662.102464669065, 1689.6113893804493, 1711.6576684663116, 949.8959804033959, 964.5370222293338, 986.5560497775886, 1065.408698172506, 1095.424901926479, 16294.42969496062, 17307.221175047154, 17320.39378621742, 17532.474025779247, 17967.191476383596, 896.5203616065373, 920.2362039507193, 935.3218916285639, 943.2218419803689, 945.2390825385493, 9375.465644153377, 9666.420014799134, 9676.371035882645, 9691.321607487409, 9785.36395134314, 506664.0, 509759.0, 512317.0, 517508.0, 521746.0, 81965.0, 83548.0, 84507.57746211352, 86173.56913215519, 86876.56561717151, 1152.383474840956, 1152.9365884876245, 1178.8231765235112, 1182.3041529550035, 1212.5574165429555, 14564.417423303374, 15378.307976140877, 15521.37914483408, 15673.68652626964, 16022.599435014217, 32382.67617323891, 32619.673803261663, 32645.673543265213, 33310.83344583261, 33383.833080835415, 40803.183936320514, 41704.58295417007, 44049.55950440455, 46451.76774116243, 48786.0, 9292.767680808034, 9814.06766357195, 9826.950865245914, 9859.211263099078, 9997.700068997941, 11484.712882178012, 11597.884021160018, 11729.472173752372, 17036.659266814346, 17054.31782728677, 11809.468573914366, 12217.633470995885, 13764.0, 14126.929365353108, 14185.077969932334, 13954.162750235018, 13967.860321397062, 14196.787048194492, 14347.497837575635, 14395.712085758436, 10696.89303106959, 14404.927975360057, 14424.783628245794, 14798.48205312809, 14884.404623814926, 9446.433214007182, 9453.818272715911, 9573.138417542455, 9573.420385636293, 9607.183389411979, 10164.237682174014, 10260.897391025994, 11165.720856978316, 14037.438502459787, 14341.282935853375], "unit": "ms", "name": "jquery.html", "value": 8937.373318566704}, {"lowerIsBetter": false, "replicates": [1472.3595236072304, 1641.802983641963, 1687.2829047654743, 1719.3896166860757, 1735.0457248513314, 6493.740250389985, 7012.158540975084, 7152.213256541776, 7168.390686791616, 7168.462365322602, 7008.719651213945, 7234.059572255599, 7247.528910620806, 7284.963575182117, 7295.343419092281, 1922.9711554326695, 1957.980420195794, 2178.096090122597, 2190.561887622478, 2222.1666874921902, 2224.944376390595, 2236.496788222645, 2241.8206543476563, 2243.416711654965, 2243.7083179186766, 2153.450870028141, 2238.955220895581, 2242.372135801978, 2243.0915479230857, 2243.1363924888956, 1912.4836294200556, 1958.5886963737632, 2163.3185546552863, 2217.46780772615, 2219.2565490560683, 2216.2021672198, 2229.609818281794, 2255.4586899144088, 2255.5827171973237, 2279.851809632385, 7251.927480725178, 7296.0, 7303.6488249673985, 7307.525010874329, 7309.963450182715, 4440.822367105312, 4551.226291530448, 4552.203364411214, 4552.658550608718, 4559.17934771741, 2953.8966136185345, 3040.4223197592387, 3062.219134120807, 3081.245094951729, 3089.98030649885, 966.7003228999033, 972.7179118055826, 990.7770751580908, 1018.8624535687661, 1023.6110278094351, 9591.42451452915, 9679.951600242024, 9728.319017668766, 9739.65911193112, 9740.512974351253, 4029.294873397144, 4082.755034697928, 4118.814653340607, 4226.513950895686, 4228.76741779199, 8303.8339233215, 8366.748997529961, 8375.748727538183, 8379.581020948928, 8392.034915984617, 4506.662000349987, 4538.183127037165, 4563.566461186182, 4569.885752856138, 4600.5169457207185, 4153.1486045361235, 4153.958460415418, 4174.91650166995, 4182.91634167315, 4194.6853985951175, 3393.389189945784, 3430.091025878144, 3442.552468179171, 3446.4657978013447, 3457.481377793301, 1170.0990237517235, 1187.9643610691694, 1194.462491878658, 1195.121585634558, 1201.9338936358583, 929.4051806843626, 1268.3721557828933, 1274.9681257968625, 1290.2387591321165, 1302.550620036086, 6232.065190221412, 6821.761238356634, 7047.400970917427, 7050.435965122778, 7051.89422158668, 597.3727586034646, 617.0990354083089, 619.3837132053635, 619.9008158694677, 621.7326549583632, 5272.182811664198, 5285.656432331963, 5302.257683924254, 5308.203769434538, 5311.946880531223, 8362.414630975894, 8557.187067228604, 8631.6978905738, 8710.869336959951, 8728.170823771732, 8613.22480976715, 8626.827463450696, 8636.352273579507, 8640.395172337876, 8643.135686431433, 6761.3238676132, 6830.0, 6901.792946211622, 6987.0, 6988.405985491188, 6572.638504882278, 6598.802035938929, 6609.900851487136, 6610.735570577315, 6610.9669451652435, 7700.922990770021, 7772.611369431619, 7785.260400261966, 7916.604169791372, 7925.009373828271, 7831.060272767304, 7957.482763620577, 7974.880376794469, 8038.758837234892, 8096.149904259968, 6264.655443950444, 6324.715387807468, 6345.682715864281, 6357.268914074941, 6403.519736019724, 7010.298970103153, 7066.57600543969, 7070.151581810215, 7077.5753454792875, 7080.362767350757, 6454.096426500388, 6707.865842683022, 6733.158355205599, 6771.762988295386, 6821.045053692586, 6376.075469057054, 6430.2926678066315, 6435.678216089271, 6566.146400967844, 6612.669366531751, 5500.9174862377895, 5537.36320323168, 5540.196671482532, 5887.61730487517, 6188.473979711594, 5966.433188847052, 6153.753849845956, 6213.03697926822, 6227.252729672468, 6229.844253893689, 6123.540734444845, 6165.321814600301, 6171.6297022178815, 6176.969115154575, 6180.103884936749], "unit": "ms", "name": "mootools.html", "value": 4193.103954225805}, {"lowerIsBetter": false, "replicates": [326.1845386533664, 327.3387756731402, 332.99001029969077, 336.5708721380241, 339.4331466451025, 5935.940640593592, 6059.909101363478, 6094.26868775747, 6101.389861013897, 6115.969420152904, 5879.970600147004, 6003.549733769963, 6013.308469526006, 6015.428534289235, 6021.789237376693, 4673.532646735325, 4681.180793361165, 4681.9063618727605, 4687.929681054787, 4691.437027556698, 4487.259602165639, 4569.771511424432, 4572.108438854425, 4575.107853968478, 4684.625229981593, 4618.792154353062, 4627.768611569425, 4652.767361631905, 4656.41794775653, 4657.091867085919, 4641.535846415365, 4641.907161856744, 4642.442906851165, 4642.814287428532, 4648.674592778473, 4084.0402505411203, 4084.979575102135, 4087.5708050654553, 4090.877273681794, 4100.91798164038, 5628.183913332585, 5716.542676585863, 5734.369219385864, 5734.942650573483, 5746.712664366765, 5417.349918009864, 5419.430959749248, 5419.458054194589, 5424.593155513313, 5426.8371948841595, 6163.599366041238, 6408.423241908204, 6411.871762564722, 6425.389587989133, 6425.935740642581, 4395.0, 4398.208322501949, 4510.774461276923, 4524.185646583614, 4525.208088584517, 6655.1348324717965, 6675.566088204299, 6678.699458524378, 6687.364700353459, 6688.83277918051, 4608.5621865922685, 4614.700044497097, 4616.8614941551805, 4618.930716039262, 4619.19164146273, 4470.636455880987, 4572.7713614319155, 4603.9079218415445, 4635.351050852883, 4645.721256724607, 5333.120035194227, 5334.653247538898, 5337.466253374709, 5337.572994160458, 5341.813036543703, 6441.355864413521, 6441.677916104176, 6453.225612926385, 6454.935450645527, 6456.257530383972, 1598.057146283698, 1606.807183138031, 1608.2843134805134, 1608.4611655095441, 1609.0265389439455, 1027.6916924922493, 1028.4806172882795, 1040.812653722322, 1083.9349639021684, 1098.6703988803326, 6210.068489726486, 6212.751489940351, 6316.30520642729, 6353.237611486558, 6365.681715914278, 6708.127943367331, 6722.865542689119, 6731.0, 6734.326567343226, 6743.15710536183, 6181.876362472725, 6344.397282258178, 6344.492440604833, 6344.555881088279, 6357.936420635734, 3600.063983364344, 3632.364336241147, 3637.9090522736615, 3639.3449179148006, 3642.8542858285373, 6927.376536111673, 6977.930220697728, 6986.895196572055, 6989.405900498515, 6995.615241161785, 6721.66391680414, 6892.379685828299, 6893.345132212432, 6897.310268973062, 6909.343612356818, 6886.449084073262, 6981.05755722981, 6981.860362792614, 6989.33601307875, 6992.300769922967, 6692.866142677217, 6698.296678848651, 6710.597364158166, 6714.865702685821, 6715.093462382516, 6523.151990241238, 6610.70251838678, 6611.371919667624, 6627.734890604322, 6672.566283191577, 6990.510664253454, 7007.299270073156, 7009.684564194521, 7013.088298521159, 7017.859642807218, 4527.2303708369, 4532.320151977098, 4563.38394316763, 4563.794629241758, 4570.245909425041, 6567.737290508326, 6691.364320389555, 6701.731930722716, 6702.597844129368, 6708.966455167693, 6526.934730652632, 6529.0, 6533.575317604341, 6536.542442029012, 6537.346265373615, 6480.449161821298, 6522.34776522344, 6523.086767852599, 6524.706388212447, 6529.5102867284195, 6136.754529818757, 6261.96869015652, 6373.904391434039, 6405.519586030973, 6424.51816113784, 6397.424231819158, 6402.935970640234, 6525.575837570543, 6531.183602049744, 6543.0, 6311.589746666452, 6350.7459701611415, 6370.458511026614, 6374.872502550016, 6381.904271436025], "unit": "ms", "name": "prototype.html", "value": 4869.855669448882}, {"lowerIsBetter": false, "replicates": [635.2662674610821, 647.6729251727879, 713.9535930164539, 720.0747040053532, 727.3526561360391, 12338.44476998535, 12565.623031309064, 14156.92921535392, 14291.857081429183, 14336.713265734705, 12955.48178072876, 12981.415836287366, 13025.0, 13025.934870325658, 13026.869731302684, 675.395521008697, 679.796061181646, 794.213728408874, 806.379088102161, 808.7492877208055, 812.6789917982394, 816.0248503038871, 818.6193420059672, 818.6807145213371, 819.8975128108987, 851.9574021298941, 852.3053711225363, 852.518327145161, 852.9914700853005, 853.3599800149888, 699.989500157498, 705.3052743048094, 828.225609055532, 835.678263868409, 838.8783626374142, 618.9009758438673, 619.4486906653083, 619.7737825693634, 622.7508996401431, 624.1075262374802, 12642.55751048718, 12653.620391388273, 12663.62009139718, 12666.683332916658, 12668.556600518938, 10900.182486313559, 11083.612073577468, 11146.498407571678, 11305.773884522347, 11446.0, 9293.767655808591, 9480.762980925463, 9513.904860951441, 9653.806923861483, 9889.703308900744, 3467.930641387183, 3475.826208689555, 3501.107217659506, 3504.56192975878, 3601.657842504958, 49905.25142122871, 50152.49847501515, 50717.49282507165, 50785.23822142634, 50798.98402031939, 8598.455694316955, 8671.56642167889, 8786.612956901356, 8807.119288071142, 9040.954795225982, 16068.678626427405, 16130.838691613168, 16205.513834584981, 16206.189690515426, 16214.10822404779, 6913.89629155563, 6967.442604591621, 7257.201707812136, 7328.59692716895, 7423.257674232534, 3917.666998305119, 4070.8575199868455, 4116.279651061112, 4199.9370009449885, 4208.095259519157, 2893.667228268776, 2927.780416468773, 2935.1047930381437, 2939.1182645206354, 2939.147647182293, 513.6840842881676, 534.398801348483, 538.951494365509, 539.5629540072554, 543.5379927061965, 524.590819161055, 530.7929907336141, 540.5540429145892, 579.5653260054959, 588.2382314902212, 7510.737124200736, 7744.4578879477895, 7948.880766788503, 7973.362131029504, 8069.919300807034, 437.79642466253347, 493.3167562925303, 516.7571241516481, 586.7271718650848, 606.6208619612742, 3575.5888072871953, 3615.8553657853918, 3740.831662575191, 3742.0831896185064, 3753.774773513543, 61115.0, 61848.0, 61966.0, 62074.379256207765, 62117.0, 53487.46512534825, 54027.18959215615, 54501.72749136308, 54773.178402323996, 54785.45214547803, 47552.524474755504, 48299.517004829504, 48984.265236021485, 50532.49467505352, 50812.0, 7688.077430708351, 7694.0, 8032.558209298545, 8080.83838323242, 8351.707690230813, 33617.66382336145, 34700.82649586736, 35675.0, 36050.819745901106, 36084.0, 45560.316595250435, 46022.0, 46121.30818037665, 47128.764356178, 47698.76150619225, 9758.316917815684, 9797.657082002095, 9810.607575696893, 10110.39337639744, 10117.342372745748, 10889.401082940514, 11002.834957475805, 11033.83449248278, 11152.944235278772, 11294.322340659586, 9411.0, 9778.0, 10260.0, 10318.380897146195, 10623.946880265548, 9391.0, 9969.601215951281, 10080.495975201356, 10205.234607404325, 10206.591736330463, 9338.95330523343, 9901.603935842486, 10012.249081318785, 10014.549345279625, 10071.34536255141, 8801.515916624647, 8831.558422078999, 8839.646414143363, 9005.14451127142, 9215.86176207344, 8071.273585377344, 8155.755327340189, 8271.669133234604, 8704.869426958474, 8739.38824282294], "unit": "ms", "name": "yui.html", "value": 5942.223050036996}], "name": "dromaeo_css", "value": 6914.923994101481}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [107.0, 109.0, 103.0, 109.0, 109.0, 110.0, 98.0, 109.0, 110.0, 101.0], "unit": "ms", "name": "ai-astar", "value": 106.5}, {"lowerIsBetter": true, "replicates": [146.0, 121.0, 146.0, 167.0, 123.0, 157.0, 152.0, 134.0, 166.0, 125.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.7}, {"lowerIsBetter": true, "replicates": [160.0, 161.0, 160.0, 161.0, 162.0, 159.0, 161.0, 162.0, 160.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.7}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 94.0, 90.0, 71.0, 73.0, 72.0, 130.0, 90.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 83.4}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 137.0, 94.0, 93.0, 92.0, 134.0, 93.0, 93.0, 138.0], "unit": "ms", "name": "audio-oscillator", "value": 106.0}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 147.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.7}, {"lowerIsBetter": true, "replicates": [58.0, 58.0, 57.0, 57.0, 58.0, 57.0, 58.0, 56.0, 60.0, 57.0], "unit": "ms", "name": "json-parse-financial", "value": 57.6}, {"lowerIsBetter": true, "replicates": [70.0, 69.0, 70.0, 69.0, 69.0, 71.0, 70.0, 70.0, 70.0, 71.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.9}, {"lowerIsBetter": true, "replicates": [88.0, 84.0, 85.0, 85.0, 85.0, 85.0, 84.0, 85.0, 86.0, 93.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.0}, {"lowerIsBetter": true, "replicates": [122.0, 113.0, 116.0, 123.0, 130.0, 129.0, 125.0, 115.0, 125.0, 123.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 122.1}, {"lowerIsBetter": true, "replicates": [139.0, 147.0, 147.0, 149.0, 148.0, 136.0, 148.0, 147.0, 146.0, 136.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 144.3}, {"lowerIsBetter": true, "replicates": [65.0, 68.0, 65.0, 65.0, 65.0, 64.0, 65.0, 64.0, 66.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.1}], "name": "kraken", "value": 1495.7999999999997}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.80567139282735], "unit": "score", "name": "Richards", "value": 211.6354900000001}, {"lowerIsBetter": false, "replicates": [194.28793471925394], "unit": "score", "name": "DeltaBlue", "value": 340.309346}, {"lowerIsBetter": false, "replicates": [282.6455624646693], "unit": "score", "name": "Encrypt", "value": 941.7483780000005}, {"lowerIsBetter": false, "replicates": [5181.347150259067], "unit": "score", "name": "Decrypt", "value": 51.372933}, {"lowerIsBetter": false, "replicates": [1921.3051823416506], "unit": "score", "name": "RayTrace", "value": 385.1491198801198}, {"lowerIsBetter": false, "replicates": [762.7765064836003], "unit": "score", "name": "Earley", "value": 873.732993}, {"lowerIsBetter": false, "replicates": [12219.512195121952], "unit": "score", "name": "Boyer", "value": 54.540884231536886}, {"lowerIsBetter": false, "replicates": [25200.0], "unit": "score", "name": "RegExp", "value": 36.150198412698415}, {"lowerIsBetter": false, "replicates": [604.9457177322075], "unit": "score", "name": "Splay", "value": 134.70795413758722}, {"lowerIsBetter": false, "replicates": [4878.048780487805], "unit": "score", "name": "NavierStokes", "value": 304.2200000000002}], "name": "v8_7", "value": 19920.316540825555}]} 08:11:23 INFO - RETURN: dromaeo_css: 6914.92 08:11:23 INFO - RETURN: kraken: 1495.80 08:11:23 INFO - RETURN: v8_7: 19920.32 08:11:23 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "19920.32"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1495.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6914.92"}}} 08:11:23 INFO - Return code: 0 08:11:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 08:11:23 INFO - # TBPL SUCCESS # 08:11:23 INFO - Running post-action listener: _resource_record_post_action 08:11:23 INFO - Running post-run listener: _resource_record_post_run 08:11:24 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 4096; Write bytes: 512221184; Read time: 12; Write time: 700044 08:11:24 INFO - install - Wall time: 15s; CPU: 16.0%; Read bytes: 0; Write bytes: 244408320; Read time: 0; Write time: 498324 08:11:24 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 4096; Write bytes: 262799360; Read time: 12; Write time: 201408 08:11:24 INFO - Running post-run listener: _upload_blobber_files 08:11:24 INFO - Blob upload gear active. 08:11:24 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 08:11:24 INFO - Copying logs to upload dir... 08:11:24 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1257.768323 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2015-11-25 08:11:23.023511) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-25 08:11:23.027567) ========= 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=2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/kR2TulyMTR6wwxFPVGNwJQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010089 build_url: 'https://queue.taskcluster.net/v1/task/kR2TulyMTR6wwxFPVGNwJQ/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-25 08:11:23.079021) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 08:11:23.079351) ========= 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=2c70f4bb59d9561eae6fcf47000001b3-1448462159.796681-1611997495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004771 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 08:11:23.130646) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 08:11:23.130975) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 08:11:23.131279) ========= ========= Total master_lag: 0.22 =========