builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-051 starttime: 1448442586.05 results: success (0) buildid: 20151124233051 builduid: a6381db2c0b24e398b57a006e76152e3 revision: 31cf031ca23e4927db55f9e5469ac3e4c6599a35 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.048090) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.048775) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.049251) ========= 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=d333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006447 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.079161) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.079650) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.097806) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.098337) ========= 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=d333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004647 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.149263) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.149733) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.150296) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.150766) ========= 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=d333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-25 01:09:47-- 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% 221M=0s 2015-11-25 01:09:47 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.182101 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.347235) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.347576) ========= 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=d333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019905 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.397622) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.397990) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 31cf031ca23e4927db55f9e5469ac3e4c6599a35 --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 31cf031ca23e4927db55f9e5469ac3e4c6599a35 --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=d333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-25 01:09:47,300 truncating revision to first 12 chars 2015-11-25 01:09:47,300 Setting DEBUG logging. 2015-11-25 01:09:47,301 attempt 1/10 2015-11-25 01:09:47,301 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/31cf031ca23e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-25 01:09:47,554 unpacking tar archive at: fx-team-31cf031ca23e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.545659 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.956313) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.956660) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.964688) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.965015) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 01:09:46.965440) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2015-11-25 01:09:46.965732) ========= /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=d333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:09:47 INFO - MultiFileLogger online at 20151125 01:09:47 in /builds/slave/test-pgo 01:09:47 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 01:09:47 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 01:09:47 INFO - {'append_to_log': False, 01:09:47 INFO - 'base_work_dir': '/builds/slave/test-pgo', 01:09:47 INFO - 'blob_upload_branch': 'Fx-Team', 01:09:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 01:09:47 INFO - 'branch': 'Fx-Team', 01:09:47 INFO - 'buildbot_json_path': 'buildprops.json', 01:09:47 INFO - 'config_files': ('talos/linux_config.py',), 01:09:47 INFO - 'default_actions': ('clobber', 01:09:47 INFO - 'read-buildbot-config', 01:09:47 INFO - 'download-and-extract', 01:09:47 INFO - 'populate-webroot', 01:09:47 INFO - 'create-virtualenv', 01:09:47 INFO - 'install', 01:09:47 INFO - 'run-tests'), 01:09:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:09:47 INFO - 'download_minidump_stackwalk': True, 01:09:47 INFO - 'download_symbols': 'ondemand', 01:09:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:09:47 INFO - 'tooltool.py': '/tools/tooltool.py', 01:09:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:09:47 INFO - '/tools/misc-python/virtualenv.py')}, 01:09:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:09:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:09:47 INFO - 'installer_path': 'installer.exe', 01:09:47 INFO - 'log_level': 'info', 01:09:47 INFO - 'log_name': 'talos', 01:09:47 INFO - 'log_to_console': True, 01:09:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:09:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:09:47 INFO - 'opt_config_files': (), 01:09:47 INFO - 'pip_index': False, 01:09:47 INFO - 'sps_profile': False, 01:09:47 INFO - 'sps_profile_interval': 0, 01:09:47 INFO - 'suite': 'dromaeojs', 01:09:47 INFO - 'system_bits': '64', 01:09:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:09:47 INFO - 'title': 'talos-linux64-ix-051', 01:09:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:09:47 INFO - 'use_talos_json': True, 01:09:47 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 01:09:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:09:47 INFO - 'work_dir': 'build'} 01:09:47 INFO - ##### 01:09:47 INFO - ##### Running clobber step. 01:09:47 INFO - ##### 01:09:47 INFO - Running pre-action listener: _resource_record_pre_action 01:09:47 INFO - Running main action method: clobber 01:09:47 INFO - rmtree: /builds/slave/test-pgo/build 01:09:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 01:09:48 INFO - Running post-action listener: _resource_record_post_action 01:09:48 INFO - ##### 01:09:48 INFO - ##### Running read-buildbot-config step. 01:09:48 INFO - ##### 01:09:48 INFO - Running pre-action listener: _resource_record_pre_action 01:09:48 INFO - Running main action method: read_buildbot_config 01:09:48 INFO - Using buildbot properties: 01:09:48 INFO - { 01:09:48 INFO - "properties": { 01:09:48 INFO - "buildnumber": 338, 01:09:48 INFO - "product": "firefox", 01:09:48 INFO - "script_repo_revision": "production", 01:09:48 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 01:09:48 INFO - "repository": "", 01:09:48 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 01:09:48 INFO - "buildid": "20151124233051", 01:09:48 INFO - "slavename": "talos-linux64-ix-051", 01:09:48 INFO - "pgo_build": "True", 01:09:48 INFO - "basedir": "/builds/slave/test-pgo", 01:09:48 INFO - "project": "", 01:09:48 INFO - "platform": "ubuntu64_hw", 01:09:48 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 01:09:48 INFO - "slavebuilddir": "test", 01:09:48 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 01:09:48 INFO - "branch": "fx-team", 01:09:48 INFO - "repo_path": "integration/fx-team", 01:09:48 INFO - "stage_platform": "linux64-pgo", 01:09:48 INFO - "builduid": "a6381db2c0b24e398b57a006e76152e3", 01:09:48 INFO - "revision": "31cf031ca23e4927db55f9e5469ac3e4c6599a35" 01:09:48 INFO - }, 01:09:48 INFO - "sourcestamp": { 01:09:48 INFO - "repository": "", 01:09:48 INFO - "hasPatch": false, 01:09:48 INFO - "project": "", 01:09:48 INFO - "branch": "fx-team-linux64-pgo-talos", 01:09:48 INFO - "changes": [ 01:09:48 INFO - { 01:09:48 INFO - "category": null, 01:09:48 INFO - "files": [ 01:09:48 INFO - { 01:09:48 INFO - "url": null, 01:09:48 INFO - "name": "https://queue.taskcluster.net/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 01:09:48 INFO - } 01:09:48 INFO - ], 01:09:48 INFO - "repository": "", 01:09:48 INFO - "rev": "31cf031ca23e4927db55f9e5469ac3e4c6599a35", 01:09:48 INFO - "who": "sendchange", 01:09:48 INFO - "when": 1448442566, 01:09:48 INFO - "number": 6704654, 01:09:48 INFO - "comments": "", 01:09:48 INFO - "project": "", 01:09:48 INFO - "at": "Wed 25 Nov 2015 01:09:26", 01:09:48 INFO - "branch": "fx-team-linux64-pgo-talos", 01:09:48 INFO - "revlink": "", 01:09:48 INFO - "properties": [ 01:09:48 INFO - [ 01:09:48 INFO - "buildid", 01:09:48 INFO - "20151124233051", 01:09:48 INFO - "Change" 01:09:48 INFO - ], 01:09:48 INFO - [ 01:09:48 INFO - "builduid", 01:09:48 INFO - "a6381db2c0b24e398b57a006e76152e3", 01:09:48 INFO - "Change" 01:09:48 INFO - ], 01:09:48 INFO - [ 01:09:48 INFO - "pgo_build", 01:09:48 INFO - "True", 01:09:48 INFO - "Change" 01:09:48 INFO - ] 01:09:48 INFO - ], 01:09:48 INFO - "revision": "31cf031ca23e4927db55f9e5469ac3e4c6599a35" 01:09:48 INFO - } 01:09:48 INFO - ], 01:09:48 INFO - "revision": "31cf031ca23e4927db55f9e5469ac3e4c6599a35" 01:09:48 INFO - } 01:09:48 INFO - } 01:09:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 01:09:48 INFO - Running post-action listener: _resource_record_post_action 01:09:48 INFO - ##### 01:09:48 INFO - ##### Running download-and-extract step. 01:09:48 INFO - ##### 01:09:48 INFO - Running pre-action listener: _resource_record_pre_action 01:09:48 INFO - Running main action method: download_and_extract 01:09:48 INFO - mkdir: /builds/slave/test-pgo/build/tests 01:09: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')]} 01:09:48 INFO - https://queue.taskcluster.net/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 01:09:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/test_packages.json 01:09:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/test_packages.json 01:09:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 01:09:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 01:09:52 INFO - Downloaded 1302 bytes. 01:09:52 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 01:09:52 INFO - Using the following test package requirements: 01:09:52 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 01:09:52 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:09:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:09:52 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:09:52 INFO - u'jsshell-linux-x86_64.zip'], 01:09:52 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:09:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:09:52 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 01:09:52 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:09:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:09:52 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:09:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:09:52 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:09:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:09:52 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 01:09:52 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:09:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:09:52 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:09:52 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')]} 01:09:52 INFO - https://queue.taskcluster.net/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:09:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:09:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:09:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/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 01:09:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/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 01:09:53 INFO - Downloaded 22530046 bytes. 01:09:53 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 01:09:53 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:09:53 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 01:09:54 INFO - Return code: 0 01:09:54 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 01:09:54 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')]} 01:09:54 INFO - https://queue.taskcluster.net/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:09:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:09:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:09:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/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 01:09:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/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 01:09:59 INFO - Downloaded 22530046 bytes. 01:09:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 01:09:59 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:09:59 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 01:10:00 INFO - Return code: 0 01:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:00 INFO - https://queue.taskcluster.net/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 01:10:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 01:10:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 01:10:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/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 01:10:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/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 01:10:01 INFO - Downloaded 11131717 bytes. 01:10:01 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 01:10:01 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 01:10:01 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 01:10:01 INFO - Return code: 0 01:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:01 INFO - https://queue.taskcluster.net/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 01:10:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 01:10:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 01:10:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/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 01:10:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BZDVZYwoSWq2XRVh5skJIg/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 01:10:02 INFO - Downloaded 58387091 bytes. 01:10:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 01:10:02 INFO - mkdir: /builds/slave/test-pgo/properties 01:10:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 01:10:02 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 01:10:02 INFO - Contents: 01:10:02 INFO - build_url:https://queue.taskcluster.net/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 01:10:02 INFO - Running post-action listener: _resource_record_post_action 01:10:02 INFO - Running post-action listener: set_extra_try_arguments 01:10:02 INFO - ##### 01:10:02 INFO - ##### Running populate-webroot step. 01:10:02 INFO - ##### 01:10:02 INFO - Running pre-action listener: _resource_record_pre_action 01:10:02 INFO - Running main action method: populate_webroot 01:10:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:10:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:10:02 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 01:10:02 INFO - '../fennec_ids.txt'], 01:10:02 INFO - 'tests': ['tcheck2']}, 01:10:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:10:02 INFO - '--tppagecycles', 01:10:02 INFO - '7'], 01:10:02 INFO - 'tests': ['tsvgm']}}, 01:10:02 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 01:10:02 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 01:10:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:10:02 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:10:02 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 01:10:02 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:10:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:10:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:10:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:10:02 INFO - 'talos_options': ['--e10s'], 01:10:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:10:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:10:02 INFO - 'tests': ['damp', 'tps']}, 01:10:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:10:02 INFO - 'talos_options': ['--e10s'], 01:10:02 INFO - 'tests': ['damp', 'tps']}, 01:10:02 INFO - 'g3': {'tests': ['dromaeo_dom']}, 01:10:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:10:02 INFO - 'other': {'tests': ['a11yr', 01:10:02 INFO - 'ts_paint', 01:10:02 INFO - 'tpaint', 01:10:02 INFO - 'sessionrestore', 01:10:02 INFO - 'sessionrestore_no_auto_restore']}, 01:10:02 INFO - 'other-e10s': {'talos_options': ['--e10s'], 01:10:02 INFO - 'tests': ['a11yr', 01:10:02 INFO - 'ts_paint', 01:10:02 INFO - 'tpaint', 01:10:02 INFO - 'sessionrestore', 01:10:02 INFO - 'sessionrestore_no_auto_restore']}, 01:10:02 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 01:10:02 INFO - 'tests': ['a11yr', 01:10:02 INFO - 'ts_paint', 01:10:02 INFO - 'tpaint', 01:10:02 INFO - 'sessionrestore', 01:10:02 INFO - 'sessionrestore_no_auto_restore']}, 01:10:02 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 01:10:02 INFO - 'tests': ['a11yr', 01:10:02 INFO - 'ts_paint', 01:10:02 INFO - 'tpaint', 01:10:02 INFO - 'sessionrestore', 01:10:02 INFO - 'sessionrestore_no_auto_restore']}, 01:10:02 INFO - 'other_l64': {'tests': ['a11yr', 01:10:02 INFO - 'ts_paint', 01:10:02 INFO - 'tpaint', 01:10:02 INFO - 'sessionrestore', 01:10:02 INFO - 'sessionrestore_no_auto_restore']}, 01:10:02 INFO - 'other_nol64': {'tests': ['a11yr', 01:10:02 INFO - 'ts_paint', 01:10:02 INFO - 'tpaint', 01:10:02 INFO - 'sessionrestore', 01:10:02 INFO - 'sessionrestore_no_auto_restore']}, 01:10:02 INFO - 'svgr': {'tests': ['tsvgx', 01:10:02 INFO - 'tsvgr_opacity', 01:10:02 INFO - 'tart', 01:10:02 INFO - 'tscrollx', 01:10:02 INFO - 'cart']}, 01:10:02 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 01:10:02 INFO - 'tests': ['tsvgx', 01:10:02 INFO - 'tsvgr_opacity', 01:10:02 INFO - 'tart', 01:10:02 INFO - 'tscrollx', 01:10:02 INFO - 'cart']}, 01:10:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:10:02 INFO - 'tests': ['tp5o']}, 01:10:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:10:02 INFO - 'talos_options': ['--e10s'], 01:10:02 INFO - 'tests': ['tp5o']}, 01:10:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:10:02 INFO - 'talos_options': ['--xperf_path', 01:10:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:10:02 INFO - 'tests': ['tp5n']}, 01:10:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:10:02 INFO - 'talos_options': ['--e10s', 01:10:02 INFO - '--xperf_path', 01:10:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:10:02 INFO - 'tests': ['tp5n']}}, 01:10:02 INFO - 'talos.zip': {'path': '', 01:10:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:10:02 INFO - Running post-action listener: _resource_record_post_action 01:10:02 INFO - ##### 01:10:02 INFO - ##### Running create-virtualenv step. 01:10:02 INFO - ##### 01:10:02 INFO - Running pre-action listener: _resource_record_pre_action 01:10:02 INFO - Running main action method: create_virtualenv 01:10:02 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 01:10:02 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 01:10:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 01:10:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:10:02 INFO - Using real prefix '/usr' 01:10:02 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 01:10:03 INFO - Installing distribute.............................................................................................................................................................................................done. 01:10:05 INFO - Installing pip.................done. 01:10:05 INFO - Return code: 0 01:10:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 01:10:05 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')]} 01:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:10:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:05 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')]} 01:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:10:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:05 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 0x23ee030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x261b7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aa640>, '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 0x26ab540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ab9d0>, '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': 'd333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299', '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 01:10:05 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 01:10:05 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 01:10:05 INFO - Using env: {'DISPLAY': ':0', 01:10:05 INFO - 'HOME': '/home/cltbld', 01:10:05 INFO - 'LANG': 'en_US.UTF-8', 01:10:05 INFO - 'LANGUAGE': 'en_US:en', 01:10:05 INFO - 'LOGNAME': 'cltbld', 01:10:05 INFO - 'MAIL': '/var/mail/cltbld', 01:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:10:05 INFO - 'MOZ_NO_REMOTE': '1', 01:10:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:10:05 INFO - 'NO_EM_RESTART': '1', 01:10:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:10:05 INFO - 'PWD': '/builds/slave/test-pgo', 01:10:05 INFO - 'SHELL': '/bin/bash', 01:10:05 INFO - 'SHLVL': '1', 01:10:05 INFO - 'TERM': 'linux', 01:10:05 INFO - 'TMOUT': '86400', 01:10:05 INFO - 'USER': 'cltbld', 01:10:05 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299', 01:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:10:05 INFO - '_': '/tools/buildbot/bin/python'} 01:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:10:05 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 01:10:05 INFO - Cleaning up... 01:10:05 INFO - Return code: 0 01:10:05 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 01:10:05 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')]} 01:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:10:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:05 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')]} 01:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:10:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:05 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 0x23ee030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x261b7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aa640>, '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 0x26ab540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ab9d0>, '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': 'd333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299', '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 01:10:05 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 01:10:05 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 01:10:05 INFO - Using env: {'DISPLAY': ':0', 01:10:05 INFO - 'HOME': '/home/cltbld', 01:10:05 INFO - 'LANG': 'en_US.UTF-8', 01:10:05 INFO - 'LANGUAGE': 'en_US:en', 01:10:05 INFO - 'LOGNAME': 'cltbld', 01:10:05 INFO - 'MAIL': '/var/mail/cltbld', 01:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:10:05 INFO - 'MOZ_NO_REMOTE': '1', 01:10:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:10:05 INFO - 'NO_EM_RESTART': '1', 01:10:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:10:05 INFO - 'PWD': '/builds/slave/test-pgo', 01:10:05 INFO - 'SHELL': '/bin/bash', 01:10:05 INFO - 'SHLVL': '1', 01:10:05 INFO - 'TERM': 'linux', 01:10:05 INFO - 'TMOUT': '86400', 01:10:05 INFO - 'USER': 'cltbld', 01:10:05 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299', 01:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:10:05 INFO - '_': '/tools/buildbot/bin/python'} 01:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:10:05 INFO - Downloading/unpacking psutil>=0.7.1 01:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:07 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 01:10:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 01:10:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 01:10:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:10:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:10:07 INFO - Installing collected packages: psutil 01:10:07 INFO - Running setup.py install for psutil 01:10:07 INFO - building 'psutil._psutil_linux' extension 01:10:07 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 01:10:07 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 01:10:08 INFO - building 'psutil._psutil_posix' extension 01:10:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 01:10:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 01:10:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:10:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:10:08 INFO - Successfully installed psutil 01:10:08 INFO - Cleaning up... 01:10:08 INFO - Return code: 0 01:10:08 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 01:10:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:10:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:10:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:08 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23ee030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x261b7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aa640>, '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 0x26ab540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ab9d0>, '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': 'd333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299', '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 01:10:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 01:10:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 01:10:08 INFO - Using env: {'DISPLAY': ':0', 01:10:08 INFO - 'HOME': '/home/cltbld', 01:10:08 INFO - 'LANG': 'en_US.UTF-8', 01:10:08 INFO - 'LANGUAGE': 'en_US:en', 01:10:08 INFO - 'LOGNAME': 'cltbld', 01:10:08 INFO - 'MAIL': '/var/mail/cltbld', 01:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:10:08 INFO - 'MOZ_NO_REMOTE': '1', 01:10:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:10:08 INFO - 'NO_EM_RESTART': '1', 01:10:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:10:08 INFO - 'PWD': '/builds/slave/test-pgo', 01:10:08 INFO - 'SHELL': '/bin/bash', 01:10:08 INFO - 'SHLVL': '1', 01:10:08 INFO - 'TERM': 'linux', 01:10:08 INFO - 'TMOUT': '86400', 01:10:08 INFO - 'USER': 'cltbld', 01:10:08 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299', 01:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:10:08 INFO - '_': '/tools/buildbot/bin/python'} 01:10:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:10:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:10:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 01:10:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:10:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:10:10 INFO - Installing collected packages: mozsystemmonitor 01:10:10 INFO - Running setup.py install for mozsystemmonitor 01:10:10 INFO - Successfully installed mozsystemmonitor 01:10:10 INFO - Cleaning up... 01:10:10 INFO - Return code: 0 01:10:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 01:10:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:10:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:10:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:10 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23ee030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x261b7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aa640>, '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 0x26ab540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ab9d0>, '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': 'd333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299', '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 01:10:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 01:10:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:10:10 INFO - Using env: {'DISPLAY': ':0', 01:10:10 INFO - 'HOME': '/home/cltbld', 01:10:10 INFO - 'LANG': 'en_US.UTF-8', 01:10:10 INFO - 'LANGUAGE': 'en_US:en', 01:10:10 INFO - 'LOGNAME': 'cltbld', 01:10:10 INFO - 'MAIL': '/var/mail/cltbld', 01:10:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:10:10 INFO - 'MOZ_NO_REMOTE': '1', 01:10:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:10:10 INFO - 'NO_EM_RESTART': '1', 01:10:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:10:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:10:10 INFO - 'PWD': '/builds/slave/test-pgo', 01:10:10 INFO - 'SHELL': '/bin/bash', 01:10:10 INFO - 'SHLVL': '1', 01:10:10 INFO - 'TERM': 'linux', 01:10:10 INFO - 'TMOUT': '86400', 01:10:10 INFO - 'USER': 'cltbld', 01:10:10 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299', 01:10:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:10:10 INFO - '_': '/tools/buildbot/bin/python'} 01:10:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:10:10 INFO - Downloading/unpacking blobuploader==1.2.4 01:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:12 INFO - Downloading blobuploader-1.2.4.tar.gz 01:10:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 01:10:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:10:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 01:10:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 01:10:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:12 INFO - Downloading docopt-0.6.1.tar.gz 01:10:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 01:10:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 01:10:12 INFO - Installing collected packages: blobuploader, requests, docopt 01:10:12 INFO - Running setup.py install for blobuploader 01:10:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:10:13 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 01:10:13 INFO - Running setup.py install for requests 01:10:13 INFO - Running setup.py install for docopt 01:10:13 INFO - Successfully installed blobuploader requests docopt 01:10:13 INFO - Cleaning up... 01:10:13 INFO - Return code: 0 01:10:13 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:10:13 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')]} 01:10:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:10:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:13 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')]} 01:10:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:10:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:13 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 0x23ee030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x261b7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aa640>, '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 0x26ab540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ab9d0>, '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': 'd333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299', '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 01:10:13 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 01:10:13 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 01:10:13 INFO - Using env: {'DISPLAY': ':0', 01:10:13 INFO - 'HOME': '/home/cltbld', 01:10:13 INFO - 'LANG': 'en_US.UTF-8', 01:10:13 INFO - 'LANGUAGE': 'en_US:en', 01:10:13 INFO - 'LOGNAME': 'cltbld', 01:10:13 INFO - 'MAIL': '/var/mail/cltbld', 01:10:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:10:13 INFO - 'MOZ_NO_REMOTE': '1', 01:10:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:10:13 INFO - 'NO_EM_RESTART': '1', 01:10:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:10:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:10:13 INFO - 'PWD': '/builds/slave/test-pgo', 01:10:13 INFO - 'SHELL': '/bin/bash', 01:10:13 INFO - 'SHLVL': '1', 01:10:13 INFO - 'TERM': 'linux', 01:10:13 INFO - 'TMOUT': '86400', 01:10:13 INFO - 'USER': 'cltbld', 01:10:13 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299', 01:10:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:10:13 INFO - '_': '/tools/buildbot/bin/python'} 01:10:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:10:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:10:13 INFO - Running setup.py (path:/tmp/pip-twmGN4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:10:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:10:13 INFO - Running setup.py (path:/tmp/pip-qpkasu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:10:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:10:13 INFO - Running setup.py (path:/tmp/pip-fkqtMz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:10:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:10:13 INFO - Running setup.py (path:/tmp/pip-h5tTRj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:10:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 01:10:14 INFO - Running setup.py (path:/tmp/pip-0fS67C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 01:10:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:10:14 INFO - Running setup.py (path:/tmp/pip-aCzgS2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:10:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:10:14 INFO - Running setup.py (path:/tmp/pip-UyiqIi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:10:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:10:14 INFO - Running setup.py (path:/tmp/pip-TATfeM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:10:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 01:10:14 INFO - Running setup.py (path:/tmp/pip-2MnikI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 01:10:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 01:10:14 INFO - Running setup.py (path:/tmp/pip-J7eIET-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 01:10:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:10:14 INFO - Running setup.py (path:/tmp/pip-08PtWa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:10:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:10:14 INFO - Running setup.py (path:/tmp/pip-AHwnrd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:10:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:10:14 INFO - Running setup.py (path:/tmp/pip-1WquFy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:10:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:10:14 INFO - Running setup.py (path:/tmp/pip-LVabSc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:10:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:10:14 INFO - Running setup.py (path:/tmp/pip-UcqIX3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:10:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 01:10:14 INFO - Running setup.py (path:/tmp/pip-uyzdW9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 01:10:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 01:10:14 INFO - Running setup.py (path:/tmp/pip-PoIhF6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 01:10:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:10:14 INFO - Running setup.py install for manifestparser 01:10:15 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 01:10:15 INFO - Running setup.py install for mozcrash 01:10:15 INFO - Running setup.py install for mozdebug 01:10:15 INFO - Running setup.py install for mozdevice 01:10:15 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 01:10:15 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 01:10:15 INFO - Running setup.py install for mozfile 01:10:15 INFO - Running setup.py install for mozhttpd 01:10:15 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 01:10:15 INFO - Running setup.py install for mozinfo 01:10:15 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 01:10:15 INFO - Running setup.py install for mozInstall 01:10:15 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 01:10:15 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 01:10:15 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 01:10:15 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 01:10:15 INFO - Running setup.py install for mozleak 01:10:15 INFO - Running setup.py install for mozlog 01:10:16 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 01:10:16 INFO - Running setup.py install for moznetwork 01:10:16 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 01:10:16 INFO - Running setup.py install for mozprocess 01:10:16 INFO - Running setup.py install for mozprofile 01:10:16 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 01:10:16 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 01:10:16 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 01:10:16 INFO - Running setup.py install for mozrunner 01:10:16 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 01:10:16 INFO - Running setup.py install for mozscreenshot 01:10:16 INFO - Running setup.py install for moztest 01:10:16 INFO - Running setup.py install for mozversion 01:10:16 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 01:10:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:10:16 INFO - Cleaning up... 01:10:16 INFO - Return code: 0 01:10:16 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:16 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 0x23ee030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x261b7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aa640>, '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 0x26ab540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ab9d0>, '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': 'd333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299', '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 01:10:16 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 01:10:16 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 01:10:16 INFO - Using env: {'DISPLAY': ':0', 01:10:16 INFO - 'HOME': '/home/cltbld', 01:10:16 INFO - 'LANG': 'en_US.UTF-8', 01:10:16 INFO - 'LANGUAGE': 'en_US:en', 01:10:16 INFO - 'LOGNAME': 'cltbld', 01:10:16 INFO - 'MAIL': '/var/mail/cltbld', 01:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:10:16 INFO - 'MOZ_NO_REMOTE': '1', 01:10:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:10:16 INFO - 'NO_EM_RESTART': '1', 01:10:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:10:16 INFO - 'PWD': '/builds/slave/test-pgo', 01:10:16 INFO - 'SHELL': '/bin/bash', 01:10:16 INFO - 'SHLVL': '1', 01:10:16 INFO - 'TERM': 'linux', 01:10:16 INFO - 'TMOUT': '86400', 01:10:16 INFO - 'USER': 'cltbld', 01:10:16 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299', 01:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:10:16 INFO - '_': '/tools/buildbot/bin/python'} 01:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:10:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:10:16 INFO - Running setup.py (path:/tmp/pip-ubgRvU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:10:17 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)) 01:10:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:10:17 INFO - Running setup.py (path:/tmp/pip-xTuu69-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:10:17 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)) 01:10:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:10:17 INFO - Running setup.py (path:/tmp/pip-BanZrD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:10:17 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)) 01:10:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:10:17 INFO - Running setup.py (path:/tmp/pip-Sykj5M-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:10:17 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)) 01:10:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 01:10:17 INFO - Running setup.py (path:/tmp/pip-CBMT28-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 01:10:17 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)) 01:10:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:10:17 INFO - Running setup.py (path:/tmp/pip-kQPUmw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:10:17 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)) 01:10:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:10:17 INFO - Running setup.py (path:/tmp/pip-WrdSu_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:10:17 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)) 01:10:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:10:17 INFO - Running setup.py (path:/tmp/pip-drCdx3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:10:17 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)) 01:10:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 01:10:17 INFO - Running setup.py (path:/tmp/pip-uomNQo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 01:10:17 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)) 01:10:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 01:10:17 INFO - Running setup.py (path:/tmp/pip-hsNwwZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 01:10:17 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)) 01:10:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:10:17 INFO - Running setup.py (path:/tmp/pip-z1kxXy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:10:17 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)) 01:10:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:10:17 INFO - Running setup.py (path:/tmp/pip-bh4DSo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:10:17 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)) 01:10:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:10:17 INFO - Running setup.py (path:/tmp/pip-WpDqxj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:10:17 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)) 01:10:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:10:17 INFO - Running setup.py (path:/tmp/pip-hzaIIz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:10:18 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)) 01:10:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:10:18 INFO - Running setup.py (path:/tmp/pip-Q46E34-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:10:18 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)) 01:10:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 01:10:18 INFO - Running setup.py (path:/tmp/pip-3yuZxg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 01:10:18 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)) 01:10:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 01:10:18 INFO - Running setup.py (path:/tmp/pip-ztAddi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 01:10:18 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)) 01:10:18 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)) 01:10:18 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)) 01:10:18 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)) 01:10:18 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)) 01:10:18 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)) 01:10:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 01:10:18 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 01:10:18 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 01:10:18 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 01:10:18 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 01:10:18 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 01:10:18 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 01:10:19 INFO - Downloading blessings-1.5.1.tar.gz 01:10:19 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 01:10:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 01:10:19 INFO - Installing collected packages: blessings 01:10:19 INFO - Running setup.py install for blessings 01:10:20 INFO - Successfully installed blessings 01:10:20 INFO - Cleaning up... 01:10:20 INFO - Return code: 0 01:10:20 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 01:10:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 01:10:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 01:10:20 INFO - Reading from file tmpfile_stdout 01:10:20 INFO - Current package versions: 01:10:20 INFO - argparse == 1.2.1 01:10:20 INFO - blessings == 1.5.1 01:10:20 INFO - blobuploader == 1.2.4 01:10:20 INFO - docopt == 0.6.1 01:10:20 INFO - manifestparser == 1.1 01:10:20 INFO - mozInstall == 1.12 01:10:20 INFO - mozcrash == 0.16 01:10:20 INFO - mozdebug == 0.1 01:10:20 INFO - mozdevice == 0.47 01:10:20 INFO - mozfile == 1.2 01:10:20 INFO - mozhttpd == 0.7 01:10:20 INFO - mozinfo == 0.9 01:10:20 INFO - mozleak == 0.1 01:10:20 INFO - mozlog == 3.1 01:10:20 INFO - moznetwork == 0.27 01:10:20 INFO - mozprocess == 0.22 01:10:20 INFO - mozprofile == 0.27 01:10:20 INFO - mozrunner == 6.11 01:10:20 INFO - mozscreenshot == 0.1 01:10:20 INFO - mozsystemmonitor == 0.0 01:10:20 INFO - moztest == 0.7 01:10:20 INFO - mozversion == 1.4 01:10:20 INFO - psutil == 3.1.1 01:10:20 INFO - requests == 1.2.3 01:10:20 INFO - wsgiref == 0.1.2 01:10:20 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:10:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:10:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:20 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 0x23ee030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x261b7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aa640>, '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 0x26ab540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ab9d0>, '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': 'd333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299', '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 01:10:20 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 01:10:20 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 01:10:20 INFO - Using env: {'DISPLAY': ':0', 01:10:20 INFO - 'HOME': '/home/cltbld', 01:10:20 INFO - 'LANG': 'en_US.UTF-8', 01:10:20 INFO - 'LANGUAGE': 'en_US:en', 01:10:20 INFO - 'LOGNAME': 'cltbld', 01:10:20 INFO - 'MAIL': '/var/mail/cltbld', 01:10:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:10:20 INFO - 'MOZ_NO_REMOTE': '1', 01:10:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:10:20 INFO - 'NO_EM_RESTART': '1', 01:10:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:10:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:10:20 INFO - 'PWD': '/builds/slave/test-pgo', 01:10:20 INFO - 'SHELL': '/bin/bash', 01:10:20 INFO - 'SHLVL': '1', 01:10:20 INFO - 'TERM': 'linux', 01:10:20 INFO - 'TMOUT': '86400', 01:10:20 INFO - 'USER': 'cltbld', 01:10:20 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299', 01:10:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:10:20 INFO - '_': '/tools/buildbot/bin/python'} 01:10:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:10:20 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)) 01:10:20 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)) 01:10:20 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)) 01:10:20 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)) 01:10:20 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)) 01:10:20 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)) 01:10:20 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)) 01:10:20 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)) 01:10:20 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)) 01:10:20 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)) 01:10:20 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)) 01:10:20 INFO - Cleaning up... 01:10:20 INFO - Return code: 0 01:10:20 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 01:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:10:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:10:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:20 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 0x23ee030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x261b7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aa640>, '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 0x26ab540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ab9d0>, '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': 'd333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299', '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 01:10:20 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 01:10:20 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 01:10:20 INFO - Using env: {'DISPLAY': ':0', 01:10:20 INFO - 'HOME': '/home/cltbld', 01:10:20 INFO - 'LANG': 'en_US.UTF-8', 01:10:20 INFO - 'LANGUAGE': 'en_US:en', 01:10:20 INFO - 'LOGNAME': 'cltbld', 01:10:20 INFO - 'MAIL': '/var/mail/cltbld', 01:10:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:10:20 INFO - 'MOZ_NO_REMOTE': '1', 01:10:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:10:20 INFO - 'NO_EM_RESTART': '1', 01:10:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:10:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:10:20 INFO - 'PWD': '/builds/slave/test-pgo', 01:10:20 INFO - 'SHELL': '/bin/bash', 01:10:20 INFO - 'SHLVL': '1', 01:10:20 INFO - 'TERM': 'linux', 01:10:20 INFO - 'TMOUT': '86400', 01:10:20 INFO - 'USER': 'cltbld', 01:10:20 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299', 01:10:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:10:20 INFO - '_': '/tools/buildbot/bin/python'} 01:10:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:10:20 INFO - Downloading/unpacking jsonschema 01:10:20 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 01:10:20 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 01:10:20 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 01:10:20 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 01:10:20 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 01:10:20 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 01:10:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:10:22 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 01:10:22 INFO - Downloading/unpacking functools32 (from jsonschema) 01:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:22 INFO - Downloading functools32-3.2.3-2.tar.gz 01:10:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 01:10:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 01:10:22 INFO - warning: no files found matching '*.txt' 01:10:22 INFO - no previously-included directories found matching 'build' 01:10:22 INFO - no previously-included directories found matching 'dist' 01:10:22 INFO - no previously-included directories found matching '.git*' 01:10:22 INFO - Installing collected packages: jsonschema, functools32 01:10:22 INFO - Running setup.py install for functools32 01:10:22 INFO - warning: no files found matching '*.txt' 01:10:22 INFO - no previously-included directories found matching 'build' 01:10:22 INFO - no previously-included directories found matching 'dist' 01:10:22 INFO - no previously-included directories found matching '.git*' 01:10:22 INFO - Successfully installed jsonschema functools32 01:10:22 INFO - Cleaning up... 01:10:22 INFO - Return code: 0 01:10:22 INFO - Running post-action listener: _resource_record_post_action 01:10:22 INFO - Running post-action listener: _start_resource_monitoring 01:10:22 INFO - Starting resource monitoring. 01:10:22 INFO - ##### 01:10:22 INFO - ##### Running install step. 01:10:22 INFO - ##### 01:10:22 INFO - Running pre-action listener: _resource_record_pre_action 01:10:22 INFO - Running main action method: install 01:10:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 01:10:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 01:10:23 INFO - Reading from file tmpfile_stdout 01:10:23 INFO - Detecting whether we're running mozinstall >=1.0... 01:10:23 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 01:10:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 01:10:23 INFO - Reading from file tmpfile_stdout 01:10:23 INFO - Output received: 01:10:23 INFO - Usage: mozinstall [options] installer 01:10:23 INFO - Options: 01:10:23 INFO - -h, --help show this help message and exit 01:10:23 INFO - -d DEST, --destination=DEST 01:10:23 INFO - Directory to install application into. [default: 01:10:23 INFO - "/builds/slave/test-pgo"] 01:10:23 INFO - --app=APP Application being installed. [default: firefox] 01:10:23 INFO - mkdir: /builds/slave/test-pgo/build/application 01:10:23 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'] 01:10:23 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 01:10:37 INFO - Reading from file tmpfile_stdout 01:10:37 INFO - Output received: 01:10:37 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 01:10:37 INFO - Running post-action listener: _resource_record_post_action 01:10:37 INFO - ##### 01:10:37 INFO - ##### Running run-tests step. 01:10:37 INFO - ##### 01:10:37 INFO - Running pre-action listener: _resource_record_pre_action 01:10:37 INFO - Running main action method: run_tests 01:10:37 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 01:10:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 01:10:37 INFO - Python 2.7.3 01:10:37 INFO - Return code: 0 01:10:37 INFO - grabbing minidump binary from tooltool 01:10: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')]} 01:10:37 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 0x26aa640>, '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 0x26ab540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ab9d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 01:10:37 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 01:10:37 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 01:10:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:10:37 INFO - Return code: 0 01:10:37 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 01:10:37 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 01:10:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 01:10:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 01:10:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 01:10:37 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/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 01:10:37 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/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --webServer localhost 01:10:37 INFO - Using env: {'DISPLAY': ':0', 01:10:37 INFO - 'HOME': '/home/cltbld', 01:10:37 INFO - 'LANG': 'en_US.UTF-8', 01:10:37 INFO - 'LANGUAGE': 'en_US:en', 01:10:37 INFO - 'LOGNAME': 'cltbld', 01:10:37 INFO - 'MAIL': '/var/mail/cltbld', 01:10:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 01:10:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 01:10:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:10:37 INFO - 'MOZ_NO_REMOTE': '1', 01:10:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 01:10:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:10:37 INFO - 'NO_EM_RESTART': '1', 01:10:37 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:10:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:10:37 INFO - 'PWD': '/builds/slave/test-pgo', 01:10:37 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 01:10:37 INFO - 'SHELL': '/bin/bash', 01:10:37 INFO - 'SHLVL': '1', 01:10:37 INFO - 'TERM': 'linux', 01:10:37 INFO - 'TMOUT': '86400', 01:10:37 INFO - 'USER': 'cltbld', 01:10:37 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299', 01:10:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:10:37 INFO - '_': '/tools/buildbot/bin/python'} 01:10:37 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/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost'] with output_timeout 3600 01:10:38 INFO - mozversion INFO | application_buildid: 20151124233051 01:10:38 INFO - mozversion INFO | application_changeset: 31cf031ca23e4927db55f9e5469ac3e4c6599a35 01:10:38 INFO - mozversion INFO | application_display_name: Nightly 01:10:38 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:10:38 INFO - mozversion INFO | application_name: Firefox 01:10:38 INFO - mozversion INFO | application_remotingname: firefox 01:10:38 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 01:10:38 INFO - mozversion INFO | application_vendor: Mozilla 01:10:38 INFO - mozversion INFO | application_version: 45.0a1 01:10:38 INFO - mozversion INFO | platform_buildid: 20151124233051 01:10:38 INFO - mozversion INFO | platform_changeset: 31cf031ca23e4927db55f9e5469ac3e4c6599a35 01:10:38 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 01:10:38 INFO - mozversion INFO | platform_version: 45.0a1 01:10:38 INFO - 2015-11-25 01:10:38,153 DEBUG : using testdate: 1448442638 01:10:38 INFO - 2015-11-25 01:10:38,153 DEBUG : actual date: 1448442638 01:10:38 INFO - 2015-11-25 01:10:38,237 INFO : starting webserver on 'localhost:35757' 01:10:38 INFO - 2015-11-25 01:10:38,238 INFO : Starting test suite talos-linux64-ix-051 01:10:38 INFO - 2015-11-25 01:10:38,238 INFO : Starting test dromaeo_css 01:10:38 INFO - 2015-11-25 01:10:38,238 DEBUG : operating with platform_type : linux_ 01:10:38 INFO - 2015-11-25 01:10:38,239 INFO : Initialising browser for dromaeo_css test... 01:10:38 INFO - 2015-11-25 01:10:38,249 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpHbfMTV/profile http://localhost:35757/getInfo.html 01:10:42 INFO - 2015-11-25 01:10:42,190 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:10:42 INFO - 2015-11-25 01:10:42,190 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:10:42 INFO - 2015-11-25 01:10:42,190 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:10:42 INFO - 2015-11-25 01:10:42,190 DEBUG : BROWSER_OUTPUT: __metrics 01:10:42 INFO - 2015-11-25 01:10:42,355 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] 01:10:43 INFO - 2015-11-25 01:10:43,518 INFO : Browser initialized. 01:10:43 INFO - 2015-11-25 01:10:43,518 INFO : Running cycle 1/1 for dromaeo_css test... 01:10:43 INFO - 2015-11-25 01:10:43,519 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpHbfMTV/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:10:55 INFO - 2015-11-25 01:10:55,164 DEBUG : BROWSER_OUTPUT: RSS: Main: 139362304 01:10:55 INFO - 2015-11-25 01:10:55,164 DEBUG : BROWSER_OUTPUT: 01:11:46 INFO - 2015-11-25 01:11:46,759 DEBUG : BROWSER_OUTPUT: 1448442706757 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] 01:13:55 INFO - 2015-11-25 01:13:55,733 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35757/tests/dromaeo/cssquery-ext.html) 01:13:56 INFO - 2015-11-25 01:13:56,838 DEBUG : BROWSER_OUTPUT: RSS: Main: 263860224 01:13:56 INFO - 2015-11-25 01:13:56,838 DEBUG : BROWSER_OUTPUT: 01:16:59 INFO - 2015-11-25 01:16:59,213 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/dromaeo/cssquery-ext.html (next: http://localhost:35757/tests/dromaeo/cssquery-jquery.html) 01:16:59 INFO - 2015-11-25 01:16:59,786 DEBUG : BROWSER_OUTPUT: RSS: Main: 228323328 01:16:59 INFO - 2015-11-25 01:16:59,786 DEBUG : BROWSER_OUTPUT: 01:20:01 INFO - 2015-11-25 01:20:01,645 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35757/tests/dromaeo/cssquery-mootools.html) 01:20:02 INFO - 2015-11-25 01:20:02,214 DEBUG : BROWSER_OUTPUT: RSS: Main: 218071040 01:20:02 INFO - 2015-11-25 01:20:02,214 DEBUG : BROWSER_OUTPUT: 01:23:04 INFO - 2015-11-25 01:23:04,087 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35757/tests/dromaeo/cssquery-prototype.html) 01:23:04 INFO - 2015-11-25 01:23:04,654 DEBUG : BROWSER_OUTPUT: RSS: Main: 198283264 01:23:04 INFO - 2015-11-25 01:23:04,654 DEBUG : BROWSER_OUTPUT: 01:26:07 INFO - 2015-11-25 01:26:07,139 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35757/tests/dromaeo/cssquery-yui.html) 01:26:07 INFO - 2015-11-25 01:26:07,708 DEBUG : BROWSER_OUTPUT: RSS: Main: 201203712 01:26:07 INFO - 2015-11-25 01:26:07,708 DEBUG : BROWSER_OUTPUT: 01:29:10 INFO - 2015-11-25 01:29:10,328 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/dromaeo/cssquery-yui.html (next: http://localhost:35757/tests/dromaeo/cssquery-dojo.html) 01:29:10 INFO - 2015-11-25 01:29:10,579 DEBUG : BROWSER_OUTPUT: RSS: Main: 305876992 01:29:10 INFO - 2015-11-25 01:29:10,579 DEBUG : BROWSER_OUTPUT: 01:29:10 INFO - 2015-11-25 01:29:10,580 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:29:10 INFO - 2015-11-25 01:29:10,580 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:29:10 INFO - 2015-11-25 01:29:10,581 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:29:10 INFO - 2015-11-25 01:29:10,581 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:29:10 INFO - 2015-11-25 01:29:10,582 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3209.1656169395956;3248.8538015789286;3297.90106296811;3302.339532093582;3322.534845121683;5370.033393989081;5376.354837419505;5383.973080134599;5442.564594832414;5527.419620939803;5460.918086228709;5507.5593952483805;5523.806666766664;5538.889222215554;5618.325800903897;5018.222175562793;5030.773615187325;5067.898642027158;5113.411957624865;5114;4848.515148485141;5076.84769456915;5092.668976516533;5130.486951304858;5155.664881782691;4980.626453016021;4981.452040275567;5097.311862898536;5103.974480127613;5112.565431938289;5070.112730272224;5071.061853557104;5073.797048118071;5077.517635824591;5130.9230361544605;4862.683925544864;5030.974845125787;5048.6465947383695;5062.696238225682;5102;5239.947600524022;5276.841694749163;5279.63042587019;5294.894102117936;5296.947030529684;5868.207791948075;5871.765129394862;5929.407059294079;5943;5996.97001514994;5944.078667806497;5960.105984102376;5965.433283838029;6009.429104235091;6050.515958723336;5487.75305111271;5534.75093620788;5536.2249285099315;5541.6674999499755;5584.301962254718;5842.503387212049;5899.616524925866;5947.940520594825;5955.047192449188;5989.55078369124;5785.074388097884;5838;5843.2695913010875;5851.180834683148;5920.2895652521975;5659.745311460994;5830.62100963436;5915.674637894871;5934.376890426443;5940.34656187819;5828.825135245949;5854.590178687451;5863.355030946593;5903.734331955073;5910.674912879746;5959;5960.1059841023325;5960.344362120163;5969.194158788508;5974.551908606872;4418.536053714378;4499;4530.139273538017;4553.043860789271;4569.885752856138;4059.1881623674794;4075.833636594762;4075.8777236682945;4096.487939007624;4117.773522456294;5653.773849045992;5655.604107712421;5656.519195868397;5658.547316214693;5710.828675139753;5793.044147715664;5896.439838215363;5903.76384944606;5962.642241465526;6022.247219097613;5678.886422271531;5689.971550142222;5695.658260504383;5716.1997320375185;5742.0238559445;5232.215167724872;5240.371155461293;5244.003639308519;5248.501392367719;5252.579793616502;5994.610350327215;6108.083787431743;6152;6234.812955611339;6238.095476155932;5882.588218824641;5910.379410162043;5995.340512543529;5998.4601385875485;6047.244094488189;6195.349488303663;6196.0705894116245;6196.876062478816;6202.503799695924;6210.720517576629;5757.769689212552;5827.4172582741385;5911.113333000085;5920.023196172756;5923.585349025527;5811.27359080115;5880.882382352243;5883.735231914488;5910.379410161871;5917.408259174221;5861.267341582302;5910.113482977587;5919.023361145535;5919.348871624032;5932.2584676915385;5729.598928075162;5852.7658893645485;5863.853403664943;5864.266966629171;5864.296284445893;5516.420775818642;5608.214849921096;5609.074502707008;5616.129499927518;5656.0667489865355;5856.502197313276;5859.56053296013;5860.706964651836;5864.530837532901;5867.295924489089;5490.396056433869;5607.2149899014985;5666.34836993751;5671.404502527175;5731.168980497888 01:29:10 INFO - 2015-11-25 01:29:10,582 DEBUG : BROWSER_OUTPUT: |1;ext.html;569.2031156381069;606.9180660610816;614.3426533609039;621.3693101501976;634.3751404866204;31287.374252514936;40417;40753;40780;40795.79602101989;42087.158256834846;42546;42651.57348426515;42664.36003459951;42678.573214267846;7638.350740187091;7720.613969301512;8841.690540831072;8994.190522852941;10275;10450.843237351446;10592.629257975972;10600.57597696095;10613.41626210558;10620.256582039261;10550.525226364794;10644.042036216739;10649.467526623677;10659.946700266488;10670.62652807152;7828.412869034817;8014.158513356101;10665.306755060974;10893.618723344724;10904;10445.26883118182;10591.311564748266;10600.416977066257;10606.575736970512;10611.46942652872;39583.80208098941;39902.60097399018;39935.800320998496;40019.59980400217;40023;3258.6904244096772;3339.3154403347257;3355.5302257683943;3969.847493685004;3993.6405723484736;29958.700412995808;30025.699743002508;30152.396952061055;30708.53937190944;31151.532727009115;3643.708503319728;3681.723870709681;3747.4378843173467;3817.10298079951;3948.190620922704;269031;269168;269755;270073;270268;55259.44740552623;57171.71414142902;57863.421365785805;58577.41422585805;58933;60064.399356006754;60171.69914150401;60534.69732651397;60624.05943613651;60638.39361606327;16064.196790160446;16175.838241617668;16184.433544825875;16264.837351626333;16269.755953660704;12359.938200308941;12386.5664701735;12407.193532420364;12441.253524788543;12443.253404795742;18630.16164272611;18825.623487530174;18915.3379631715;18945.242190312234;19099.331523396613;7643.656035478417;7925.960370198112;7959.44283900133;7959.84080318402;7966.522008679498;965.990539885821;989.9703008909601;1070.8916271658795;1095.2388090277182;1124.3135297054419;27957.580636290473;28142.43715125686;28321.858390708327;28362;28431.28921776972;1085.6128877900999;1102.9338239705644;1152.256794367645;1179.5694571481347;1228.7726770547476;14905.180215088058;15428.922855385652;15693.293801778947;15709.607259818597;16043.759343609856;304177;309237;309340;309355;309938;51600.48399515957;51753.22370164375;51783.48216517862;51875.74062129665;51928.22107668388;1371.451419432235;1388.8333399992066;1399.8600139986124;1403.8736513713816;1405.9367328470244;24677.136300229406;27602.447951040464;28006.85996570004;28815.8559207211;29679.703202967696;46220.76889615531;46619.533804662875;46631.766841165576;46670.29994550152;46720.53279467162;265331;266649;266725;266820;351804;27934.30164245829;30685.23286917845;31299.84350078235;31446.842765786023;31521.21196970094;36725.63274367222;38004.809975949946;38185.6181438182;38544;38919;34043.82978085094;34296.82851585726;34615.653843461245;34792.13019674528;34902;34521.65478345285;34602.4091603657;34727.4790878132;34775;35008.47487287642;34516;34544.82727586346;34639.13402164865;34803;34883.30233395369;31085.68914310828;31325.686743132275;31330.843345783127;31613.52579711352;31660.683393165775;23632.645510317016;24092.39769005789;24178.87910560436;24220.394490137183;24460.755392445848 01:29:10 INFO - 2015-11-25 01:29:10,584 DEBUG : BROWSER_OUTPUT: |2;jquery.html;355.1954822327429;358.2548299536962;364.9051246675864;365.14009507609586;365.3004496389414;14416.207108609022;14422.206778627171;16151.75772363414;17157.3994910178;18219.817801821995;11044.447777611107;11361.204715669886;17360.47918562442;17815.55461113473;17834.197461114247;4548.818047278105;4655.604273636745;4657.836975705851;4658.254679251304;4658.998315362196;3831.540215174183;3920.098377373206;4913.115639184945;4920.778564964585;5286.682799032051;5366.624336296461;5375.6774593524315;5394.487523685263;5419.458054194569;5420.783168673248;5367.302250707403;5367.973160134214;5683.687397193153;5895.086261629454;5907.320658124285;5264.894702105975;5272.92090618641;5273.1035723927025;5280.6039547034125;5281;12978.091533592653;13065.412056457388;13073.54242601514;13075.150115242479;17196.656066878717;1980.1782260361933;2042.9897850510802;2128.446603882986;2155.041006751996;2316.66408370787;9570.856437153448;9745.853812192823;9827.164691001273;10437.79124417515;11828.88171118295;1624.959376015609;1705.8208888066822;1812.3022636284993;1863.4409677096817;1886.4717878993904;62041;64516.03225951614;64937;64992;65091.674541627464;10075.395476271447;10279.845802312972;10428.791424171626;10469;10533.9473302633;13728.176309421468;13752.381142848595;13753.79369309461;13767.242801646005;13772.793408098887;7020.92979070213;7042.577445353295;7094.9290507094265;7186.964065179745;7435.628218589049;6600.471962243009;6604.669766511752;6623.768168114093;6639.767608133789;6645.169353830771;4339.153864996296;4399.5380485049245;4462.866114016585;4485.820567177341;4505.932411013838;1721.8318294338478;1764.153206460906;1777.4312220089705;1812.0305636484682;1818.4271954334342;410.34140204971186;413.6525318732219;901.3915606965272;930.9348345615742;997.1225321716843;10385.532651030571;10696.251262411712;10821.513031913582;11038.17213708975;11091.057260132979;401.45001348152636;433.6899117131203;437.78767297860514;450.92785154375144;469.57034313602674;5788.5369170466265;5870.412958704095;5959.702014899238;5974.3428222895445;5979.103134529855;536721;540385;543542;555374;555914;79308.6034569835;79855.60072199602;80719;81482;81868.59065704633;490.94108706955393;493.3438526759401;493.89134390434765;493.9925901111486;494.0785435163466;9995.650152244636;10051.547680354548;10240.795184096127;10267.845982310122;10286.639967601099;34105.147371315914;34223.4866477008;34478.82760586181;35004.47493287654;35144;51734.74132629313;54601.45398545964;57082.7145864268;57336.71331643315;57653.423465764805;12265.938670306592;13826.585202443943;14650.413983440543;14661.340239689027;15111.773323399937;19415.70876436883;19752.802471975097;20074.498137546678;20076.498087547927;20385.592288153857;15582.22088895528;15598.766018509505;15969.92015039964;15990.200489975223;16232.918835405748;15566.766498502759;15719.371225150866;16021.118838463997;16197.51407457778;16304.755428668343;12781.8082728761;15589.064656120669;16114.838851611334;16242.756358654393;16572.088535130682;14420.062695924731;14575.489857854924;14585.781213281596;14680.55958321252;14768.852311476749;8959.776005599651;9330.486823224517;15025;15906.966047206895;16072.678546428771 01:29:10 INFO - 2015-11-25 01:29:10,585 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1362.4345896452974;1666.6999940010796;1723.8536373311745;1729.377424127314;1748.8251174882514;6830.692618832152;7753.147153813083;7810.804729881748;7816.452848300622;7821.139674635786;7583.810404739885;7768.941230573902;7835.92164078359;7843.294103530681;7853.960730196355;2018.7880272571315;2059.4851287178208;2062.5049988002847;2266.54669066187;2297.264875239924;2036.4297996560986;2271.3980795089315;2312.4681323295654;2312.583734927712;2313.641385585237;2256.3005468304796;2264.784845439681;2273.386185729852;2307.6077066898583;2310.849794763343;1953.384683824605;2019.0914088660015;2292.5300313435787;2313.3869524576003;2317.7218733751974;2256.616375216217;2306.6540018997116;2308.1690591387087;2309.3418375762826;2309.6881920940796;7638.923610763877;7772.3393511551585;7772.650230739574;7781.572013539253;7794.2595453432405;4587.036722288323;4640.32715256286;4641.767911604406;4670.159371313162;4675.836345727917;2983.284011837165;3076.384723055398;3096.5974423325047;3113.112762862573;3126.124685088179;998.5955687946393;1019.0726438940602;1034.5241189053045;1040.7086015915556;1069.4919913041322;10685.893141068567;10722.78554428907;10750.086242669382;10761.51573179201;10792.298500597437;4171.395147703566;4247.511536173318;4268.103698223408;4297.226499230169;4333.826646934151;9186.678466253648;9262.90737092634;9268.25853931684;9269;9279.67521136757;4544.409226800495;4556.111558246176;4575.0392417592675;4598.034412773355;4601.976990115028;4083.244599749056;4113.526944401432;4119.9176016479505;4120.21715873989;4123.072308730571;3675.4303083022173;3675.7426980111672;3691.9077023074638;3705.6479634434686;3728.5339332583426;1218.695326168458;1221.9816702749465;1236.0852968803201;1238.3684320996356;1244.7634949359592;1029.330934892311;1031.9948400257952;1221.096388672376;1333.2533781082625;1366.6993261482457;7456.440766942393;7512.774616761506;7540.509866858637;7541.924580754232;7574.734884279023;505.0505050505027;508.7786812736471;511.6086194061546;511.91297479428584;518.9455107213688;5501.587380946445;5564.415736347625;5660.660360378391;6141.140240366352;6268.874622507525;9361.672341468153;9452.95273523628;9475.28935329853;9593.184579310697;9636.518174091267;9335.299852510952;9396.95301523488;9401.623935042659;9411.6705915294;9541.61833526665;7079.752208672775;7108.68010939509;7228.56628602275;7234.312740289664;7366.63166841645;7399.48203625741;7431.219721929119;7436.107667079985;7456.403487721078;7458.701651933862;8131.227533384319;8190.303824175012;8384.916150838657;8437.746867593982;8446.873296900429;8551.097291155853;8597.441166324168;8737.43206691563;8821.161989610977;8823.955880220557;7004;7055.611941343275;7114.57312561248;7119.6084215368655;7121.8961061035625;7712.305892469704;7764.5729484878875;7774.650140743567;7782.766517004499;7790.220977902164;7139.964300178466;7169.139703235645;7254.673539690621;7314.48798584094;7330.486865919548;6958.547694399847;7053.470989675898;7055.929440705732;7074.575525468488;7185.676644551112;7157.749478768218;7237.384822290164;7296.306850849161;7298.56208627484;7311.890321645286;6670.566413183127;6708.932910670831;6709.664516774239;6719.294474080347;6722.865542689216;6528.37980391862;6587.505937054836;6645.335466453316;6737.494687898329;6746.763863264762 01:29:10 INFO - 2015-11-25 01:29:10,585 DEBUG : BROWSER_OUTPUT: |4;prototype.html;333.70633842218854;343.3767711603441;345.21808104642975;347.4406206008328;347.8834590412213;6067.817965461038;6095.420935011167;6106.236720409949;6118.479929206012;6120.877582448348;5773.769049238036;5864.2962844458725;5916.5266778657715;6011.218541589598;6055.394460553943;4678.415198100238;4683.203855344583;4692.41344831896;4695.741734204609;4700.106979673851;4458.086092351076;4684;4686.484486706459;4686.695364801277;4692.9530704693025;4494.97752511237;4519.4124763780665;4613.1696294666945;4630.421197350332;4637.930431043537;4602.286645569942;4614.215583350839;4614.792334344946;4622.676412651116;4629.629629629639;4066.9186616267507;4076.0625056236936;4083.530394004668;4084.9591504084874;4094.5905409458815;5619.325680918316;5675.304230490526;5678.43215678433;5695.088785794253;5695.601307908448;5354.571634269249;5358.169483730028;5367.355917289911;5369.9463005369835;5376.650517716336;6427.228732552124;6429.35706429358;6431.742730290783;6432.678366081677;6434.549581529295;4454.242778727624;4457.977710111461;4459.219636563621;4463.196624607569;4468.061707041526;6482.448991835699;6556.508261880331;6625.834354141137;6626.900596491008;6628.370304821035;4542.273236282179;4543.114092751882;4548.704334218265;4551.908961820745;4560.384348113029;4606.769661516911;4607.378003969488;4608.847788052987;4608.907821843544;4609.746463944448;5304.310439642822;5313;5316.282301889273;5317.229001794718;5327.893442131136;6277.905831412532;6429.710663020175;6444.2266927968;6447.6131432114225;6451.161349024596;1612.8870979031356;1625.1143126995817;1638.90166590005;1639.631083006314;1640.5727017494855;1076.7254350140663;1084.8426978088132;1089.5097206257217;1095.1403148528368;1107.6067995861515;6264.279607845065;6269.498440124779;6275.058741188767;6277.089821975879;6277.403646653561;6458.61248325102;6695.598264104072;6701.463882889358;6704.530682852251;6709.1885190998155;6136.20229370179;6226.968865155736;6263.123162757127;6331.810045698544;6352.587081839665;3627.6553727395853;3659.743817932719;3665;3667.9633203668154;3671.4125739881492;6792.660366981631;6910.032595436643;6912.205096413876;6939.791806245821;6957.8956315655305;6846.383825455733;6858.759943401957;6866.793996180123;6893.310668933066;6895.689693963784;6846.246912839583;6877.4841886858685;6889.655517224219;6948.478864085113;6951.89572156418;6545.607263564201;6674.966625167037;6686.431653309523;6697.7655782047395;6712.966435167793;6567.802965911031;6595.835104122435;6597.80206593803;6601.405873471411;6601.669916504252;6888.276730943179;6943.166819981635;6945.826354340979;6950.6177160256675;6956.652167391711;4490.169318676056;4551.5676010778925;4570.085982803386;4573.862784116482;4575.93136102952;6666.933330666825;6675.766348177791;6695.263521012782;6698.631575263407;6700.49746269022;6551;6558.901616475852;6564.5076619254705;6565.212174538895;6577.539572229898;6416.615003099829;6541.378569035934;6549.901751473636;6560.8359791005605;6560.901586476302;6400.487960963019;6405.775797847239;6412.422881940648;6426.196725409324;6436.452901503425;6548.0832683425315;6552.180977377828;6557.344265573404;6558.049082883051;6573.868522629617;6316.14732011191;6385.8722825544155;6398.360163983564;6400.55196136266;6422.582532135583 01:29:10 INFO - 2015-11-25 01:29:10,586 DEBUG : BROWSER_OUTPUT: |5;yui.html;842.7808769719871;842.8819965204873;860.8665656823192;864.7449002544249;876.4171825735882;13282.203067815937;14292.213928233943;14343.569692909203;14362.42550297988;14376.49682261121;13493.2578708171;13532.729345413085;13596.04827662064;13619.591412257649;13629.386677599507;687.9036934829128;694.2987582541618;834.8705950577655;839.5718183726314;852.8209076094028;835.3233880556769;842.4271495383138;843.371688092369;843.5234092737614;846.7205822078699;880.0715245416084;883.8497455432592;884.1291328041874;886.756142060932;888.4136469929848;716.6201912986099;722.5050840174497;872.8123453457505;883.8630012348096;885.5041176940933;650.391883588846;650.7819880340045;652.7650045983443;652.8824811533922;656.4059526128814;13039.739205215938;13176.53882114131;13182.67043323915;13184.34078296091;13204.339783010812;11773.293602383885;11814.704632384173;12447.253164810141;13188.802167967488;13365.532206372827;9908.653197138066;10079.798404031879;10564.630237941636;10614.946925265402;10724.249302548824;3632.219072899313;3667.5782285037294;3701.444783282502;3807.7905715185652;3808.9619103808886;44491;45194.548054519364;45209.321860171796;45226.09547809058;45267.77366113148;9452.95273523628;9668.661596844076;9763.414195148314;9777.364471309342;9904.504774761233;16869.15654217309;17614.559636009202;17624.20691068905;17627.383041593483;17644.91177544104;7457.552546847207;7559.5464272143845;7566.432517561095;7626.69493220276;7684.269994350528;4226.957730422657;4239.957600424018;4259.5730430305575;4261.169072030924;4281.165172791338;3141.2303985523695;3162.36752649471;3163.1775738307747;3173.317736686577;3177.586913701204;583.6585597425469;588.9676067816312;602.9879402411927;605.068194979735;607.957442978996;541.7291354322839;608.8417011577022;687.2611942162196;690.1132045321738;699.4264702943639;8099.878501822477;8425.241728244488;8825.69110081144;9114.36199466031;9115.726528204164;566.5892228134569;626.5614070150913;680.9863802723918;723.6526467295728;729.1214087025122;3698.537682789651;3838.8272527736317;4077.571854955246;4093.467849179647;4148.481439820022;55767.44232557704;56090;56535.71732141313;56561.71719141378;56609.43390566124;48939.75530122398;49569;50068.248976265386;50097;50241;45214.773926130816;45277.547224527334;45497.31754023692;45761.542384575725;46128;8095.032526013143;8100.594970251582;8104.392170587347;8180.7954801130445;8412.873806893023;33044.026037656724;33657.495137573445;33951.66048339485;34176.31647366989;34927.82536087405;41611.16777664369;42968.35547466853;43828;44003.77998109989;44595.10809783851;10771.461426928465;10834.512446940063;10858.294210876267;11008.119350451785;11321.490532926204;10919.563217471212;11166.274192177483;11174.161937854857;11602.535898563963;11695.824562631396;10700.678979630624;10742.624008159677;11082.168837337393;11087.889121108685;11886.405679715806;10464.424456654653;10818;11009.669709908716;11411.657650270505;11801.40992950332;10383.273170877967;10470.581176752845;10553.683389498327;10661.147108231478;11366.54533818671;9289.349745518019;9501.192398646193;9551.952240239032;9567.856482152634;9769.511524423893;9181.632734690538;9460.858087128561;9467.952660236655;9630.951845240728;9807.264455165749 01:29:10 INFO - 2015-11-25 01:29:10,586 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:29:10 INFO - 2015-11-25 01:29:10,586 DEBUG : BROWSER_OUTPUT: __start_cc_report 01:29:10 INFO - 2015-11-25 01:29:10,587 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,870 01:29:10 INFO - 2015-11-25 01:29:10,587 DEBUG : BROWSER_OUTPUT: __end_cc_report 01:29:10 INFO - 2015-11-25 01:29:10,587 DEBUG : BROWSER_OUTPUT: __startTimestamp1448443750579__endTimestamp 01:29:10 INFO - 2015-11-25 01:29:10,587 DEBUG : BROWSER_OUTPUT: 01:29:10 INFO - 2015-11-25 01:29:10,587 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:29:10 INFO - 2015-11-25 01:29:10,587 DEBUG : BROWSER_OUTPUT: Number of tests: 6 01:29:10 INFO - 2015-11-25 01:29:10,587 DEBUG : BROWSER_OUTPUT: 01:29:10 INFO - 2015-11-25 01:29:10,587 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5507.21 Median:5710.83 stddev:614.82 (10.8%) stddev-sans-first:589.68 01:29:10 INFO - 2015-11-25 01:29:10,587 DEBUG : BROWSER_OUTPUT: Values: 3209.2 3248.9 3297.9 3302.3 3322.5 5370.0 5376.4 5384.0 5442.6 5527.4 5460.9 5507.6 5523.8 5538.9 5618.3 5018.2 5030.8 5067.9 5113.4 5114.0 4848.5 5076.8 5092.7 5130.5 5155.7 4980.6 4981.5 5097.3 5104.0 5112.6 5070.1 5071.1 5073.8 5077.5 5130.9 4862.7 5031.0 5048.6 5062.7 5102.0 5239.9 5276.8 5279.6 5294.9 5296.9 5868.2 5871.8 5929.4 5943.0 5997.0 5944.1 5960.1 5965.4 6009.4 6050.5 5487.8 5534.8 5536.2 5541.7 5584.3 5842.5 5899.6 5947.9 5955.0 5989.6 5785.1 5838.0 5843.3 5851.2 5920.3 5659.7 5830.6 5915.7 5934.4 5940.3 5828.8 5854.6 5863.4 5903.7 5910.7 5959.0 5960.1 5960.3 5969.2 5974.6 4418.5 4499.0 4530.1 4553.0 4569.9 4059.2 4075.8 4075.9 4096.5 4117.8 5653.8 5655.6 5656.5 5658.5 5710.8 5793.0 5896.4 5903.8 5962.6 6022.2 5678.9 5690.0 5695.7 5716.2 5742.0 5232.2 5240.4 5244.0 5248.5 5252.6 5994.6 6108.1 6152.0 6234.8 6238.1 5882.6 5910.4 5995.3 5998.5 6047.2 6195.3 6196.1 6196.9 6202.5 6210.7 5757.8 5827.4 5911.1 5920.0 5923.6 5811.3 5880.9 5883.7 5910.4 5917.4 5861.3 5910.1 5919.0 5919.3 5932.3 5729.6 5852.8 5863.9 5864.3 5864.3 5516.4 5608.2 5609.1 5616.1 5656.1 5856.5 5859.6 5860.7 5864.5 5867.3 5490.4 5607.2 5666.3 5671.4 5731.2 01:29:10 INFO - 2015-11-25 01:29:10,588 DEBUG : BROWSER_OUTPUT: 01:29:10 INFO - 2015-11-25 01:29:10,589 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:45728.17 Median:31312.77 stddev:75206.14 (240.2%) stddev-sans-first:75340.68 01:29:10 INFO - 2015-11-25 01:29:10,589 DEBUG : BROWSER_OUTPUT: Values: 569.2 606.9 614.3 621.4 634.4 31287.4 40417.0 40753.0 40780.0 40795.8 42087.2 42546.0 42651.6 42664.4 42678.6 7638.4 7720.6 8841.7 8994.2 10275.0 10450.8 10592.6 10600.6 10613.4 10620.3 10550.5 10644.0 10649.5 10659.9 10670.6 7828.4 8014.2 10665.3 10893.6 10904.0 10445.3 10591.3 10600.4 10606.6 10611.5 39583.8 39902.6 39935.8 40019.6 40023.0 3258.7 3339.3 3355.5 3969.8 3993.6 29958.7 30025.7 30152.4 30708.5 31151.5 3643.7 3681.7 3747.4 3817.1 3948.2 269031.0 269168.0 269755.0 270073.0 270268.0 55259.4 57171.7 57863.4 58577.4 58933.0 60064.4 60171.7 60534.7 60624.1 60638.4 16064.2 16175.8 16184.4 16264.8 16269.8 12359.9 12386.6 12407.2 12441.3 12443.3 18630.2 18825.6 18915.3 18945.2 19099.3 7643.7 7926.0 7959.4 7959.8 7966.5 966.0 990.0 1070.9 1095.2 1124.3 27957.6 28142.4 28321.9 28362.0 28431.3 1085.6 1102.9 1152.3 1179.6 1228.8 14905.2 15428.9 15693.3 15709.6 16043.8 304177.0 309237.0 309340.0 309355.0 309938.0 51600.5 51753.2 51783.5 51875.7 51928.2 1371.5 1388.8 1399.9 1403.9 1405.9 24677.1 27602.4 28006.9 28815.9 29679.7 46220.8 46619.5 46631.8 46670.3 46720.5 265331.0 266649.0 266725.0 266820.0 351804.0 27934.3 30685.2 31299.8 31446.8 31521.2 36725.6 38004.8 38185.6 38544.0 38919.0 34043.8 34296.8 34615.7 34792.1 34902.0 34521.7 34602.4 34727.5 34775.0 35008.5 34516.0 34544.8 34639.1 34803.0 34883.3 31085.7 31325.7 31330.8 31613.5 31660.7 23632.6 24092.4 24178.9 24220.4 24460.8 01:29:10 INFO - 2015-11-25 01:29:10,589 DEBUG : BROWSER_OUTPUT: 01:29:10 INFO - 2015-11-25 01:29:10,589 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29122.10 Median:361.58 stddev:89437.11 (24735.1%) stddev-sans-first:89661.92 01:29:10 INFO - 2015-11-25 01:29:10,590 DEBUG : BROWSER_OUTPUT: Values: 355.2 358.3 364.9 365.1 365.3 14416.2 14422.2 16151.8 17157.4 18219.8 11044.4 11361.2 17360.5 17815.6 17834.2 4548.8 4655.6 4657.8 4658.3 4659.0 3831.5 3920.1 4913.1 4920.8 5286.7 5366.6 5375.7 5394.5 5419.5 5420.8 5367.3 5368.0 5683.7 5895.1 5907.3 5264.9 5272.9 5273.1 5280.6 5281.0 12978.1 13065.4 13073.5 13075.2 17196.7 1980.2 2043.0 2128.4 2155.0 2316.7 9570.9 9745.9 9827.2 10437.8 11828.9 1625.0 1705.8 1812.3 1863.4 1886.5 62041.0 64516.0 64937.0 64992.0 65091.7 10075.4 10279.8 10428.8 10469.0 10533.9 13728.2 13752.4 13753.8 13767.2 13772.8 7020.9 7042.6 7094.9 7187.0 7435.6 6600.5 6604.7 6623.8 6639.8 6645.2 4339.2 4399.5 4462.9 4485.8 4505.9 1721.8 1764.2 1777.4 1812.0 1818.4 410.3 413.7 901.4 930.9 997.1 10385.5 10696.3 10821.5 11038.2 11091.1 401.5 433.7 437.8 450.9 469.6 5788.5 5870.4 5959.7 5974.3 5979.1 536721.0 540385.0 543542.0 555374.0 555914.0 79308.6 79855.6 80719.0 81482.0 81868.6 490.9 493.3 493.9 494.0 494.1 9995.7 10051.5 10240.8 10267.8 10286.6 34105.1 34223.5 34478.8 35004.5 35144.0 51734.7 54601.5 57082.7 57336.7 57653.4 12265.9 13826.6 14650.4 14661.3 15111.8 19415.7 19752.8 20074.5 20076.5 20385.6 15582.2 15598.8 15969.9 15990.2 16232.9 15566.8 15719.4 16021.1 16197.5 16304.8 12781.8 15589.1 16114.8 16242.8 16572.1 14420.1 14575.5 14585.8 14680.6 14768.9 8959.8 9330.5 15025.0 15907.0 16072.7 01:29:10 INFO - 2015-11-25 01:29:10,590 DEBUG : BROWSER_OUTPUT: 01:29:10 INFO - 2015-11-25 01:29:10,590 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5499.38 Median:6616.42 stddev:2896.00 (43.8%) stddev-sans-first:2887.43 01:29:10 INFO - 2015-11-25 01:29:10,590 DEBUG : BROWSER_OUTPUT: Values: 1362.4 1666.7 1723.9 1729.4 1748.8 6830.7 7753.1 7810.8 7816.5 7821.1 7583.8 7768.9 7835.9 7843.3 7854.0 2018.8 2059.5 2062.5 2266.5 2297.3 2036.4 2271.4 2312.5 2312.6 2313.6 2256.3 2264.8 2273.4 2307.6 2310.8 1953.4 2019.1 2292.5 2313.4 2317.7 2256.6 2306.7 2308.2 2309.3 2309.7 7638.9 7772.3 7772.7 7781.6 7794.3 4587.0 4640.3 4641.8 4670.2 4675.8 2983.3 3076.4 3096.6 3113.1 3126.1 998.6 1019.1 1034.5 1040.7 1069.5 10685.9 10722.8 10750.1 10761.5 10792.3 4171.4 4247.5 4268.1 4297.2 4333.8 9186.7 9262.9 9268.3 9269.0 9279.7 4544.4 4556.1 4575.0 4598.0 4602.0 4083.2 4113.5 4119.9 4120.2 4123.1 3675.4 3675.7 3691.9 3705.6 3728.5 1218.7 1222.0 1236.1 1238.4 1244.8 1029.3 1032.0 1221.1 1333.3 1366.7 7456.4 7512.8 7540.5 7541.9 7574.7 505.1 508.8 511.6 511.9 518.9 5501.6 5564.4 5660.7 6141.1 6268.9 9361.7 9453.0 9475.3 9593.2 9636.5 9335.3 9397.0 9401.6 9411.7 9541.6 7079.8 7108.7 7228.6 7234.3 7366.6 7399.5 7431.2 7436.1 7456.4 7458.7 8131.2 8190.3 8384.9 8437.7 8446.9 8551.1 8597.4 8737.4 8821.2 8824.0 7004.0 7055.6 7114.6 7119.6 7121.9 7712.3 7764.6 7774.7 7782.8 7790.2 7140.0 7169.1 7254.7 7314.5 7330.5 6958.5 7053.5 7055.9 7074.6 7185.7 7157.7 7237.4 7296.3 7298.6 7311.9 6670.6 6708.9 6709.7 6719.3 6722.9 6528.4 6587.5 6645.3 6737.5 6746.8 01:29:10 INFO - 2015-11-25 01:29:10,591 DEBUG : BROWSER_OUTPUT: 01:29:10 INFO - 2015-11-25 01:29:10,591 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5402.88 Median:6181.59 stddev:1634.07 (26.4%) stddev-sans-first:1593.74 01:29:10 INFO - 2015-11-25 01:29:10,592 DEBUG : BROWSER_OUTPUT: Values: 333.7 343.4 345.2 347.4 347.9 6067.8 6095.4 6106.2 6118.5 6120.9 5773.8 5864.3 5916.5 6011.2 6055.4 4678.4 4683.2 4692.4 4695.7 4700.1 4458.1 4684.0 4686.5 4686.7 4693.0 4495.0 4519.4 4613.2 4630.4 4637.9 4602.3 4614.2 4614.8 4622.7 4629.6 4066.9 4076.1 4083.5 4085.0 4094.6 5619.3 5675.3 5678.4 5695.1 5695.6 5354.6 5358.2 5367.4 5369.9 5376.7 6427.2 6429.4 6431.7 6432.7 6434.5 4454.2 4458.0 4459.2 4463.2 4468.1 6482.4 6556.5 6625.8 6626.9 6628.4 4542.3 4543.1 4548.7 4551.9 4560.4 4606.8 4607.4 4608.8 4608.9 4609.7 5304.3 5313.0 5316.3 5317.2 5327.9 6277.9 6429.7 6444.2 6447.6 6451.2 1612.9 1625.1 1638.9 1639.6 1640.6 1076.7 1084.8 1089.5 1095.1 1107.6 6264.3 6269.5 6275.1 6277.1 6277.4 6458.6 6695.6 6701.5 6704.5 6709.2 6136.2 6227.0 6263.1 6331.8 6352.6 3627.7 3659.7 3665.0 3668.0 3671.4 6792.7 6910.0 6912.2 6939.8 6957.9 6846.4 6858.8 6866.8 6893.3 6895.7 6846.2 6877.5 6889.7 6948.5 6951.9 6545.6 6675.0 6686.4 6697.8 6713.0 6567.8 6595.8 6597.8 6601.4 6601.7 6888.3 6943.2 6945.8 6950.6 6956.7 4490.2 4551.6 4570.1 4573.9 4575.9 6666.9 6675.8 6695.3 6698.6 6700.5 6551.0 6558.9 6564.5 6565.2 6577.5 6416.6 6541.4 6549.9 6560.8 6560.9 6400.5 6405.8 6412.4 6426.2 6436.5 6548.1 6552.2 6557.3 6558.0 6573.9 6316.1 6385.9 6398.4 6400.6 6422.6 01:29:10 INFO - 2015-11-25 01:29:10,592 DEBUG : BROWSER_OUTPUT: 01:29:10 INFO - 2015-11-25 01:29:10,592 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13488.48 Median:45387.43 stddev:15430.07 (34.0%) stddev-sans-first:15444.13 01:29:10 INFO - 2015-11-25 01:29:10,592 DEBUG : BROWSER_OUTPUT: Values: 842.8 842.9 860.9 864.7 876.4 13282.2 14292.2 14343.6 14362.4 14376.5 13493.3 13532.7 13596.0 13619.6 13629.4 687.9 694.3 834.9 839.6 852.8 835.3 842.4 843.4 843.5 846.7 880.1 883.8 884.1 886.8 888.4 716.6 722.5 872.8 883.9 885.5 650.4 650.8 652.8 652.9 656.4 13039.7 13176.5 13182.7 13184.3 13204.3 11773.3 11814.7 12447.3 13188.8 13365.5 9908.7 10079.8 10564.6 10614.9 10724.2 3632.2 3667.6 3701.4 3807.8 3809.0 44491.0 45194.5 45209.3 45226.1 45267.8 9453.0 9668.7 9763.4 9777.4 9904.5 16869.2 17614.6 17624.2 17627.4 17644.9 7457.6 7559.5 7566.4 7626.7 7684.3 4227.0 4240.0 4259.6 4261.2 4281.2 3141.2 3162.4 3163.2 3173.3 3177.6 583.7 589.0 603.0 605.1 608.0 541.7 608.8 687.3 690.1 699.4 8099.9 8425.2 8825.7 9114.4 9115.7 566.6 626.6 681.0 723.7 729.1 3698.5 3838.8 4077.6 4093.5 4148.5 55767.4 56090.0 56535.7 56561.7 56609.4 48939.8 49569.0 50068.2 50097.0 50241.0 45214.8 45277.5 45497.3 45761.5 46128.0 8095.0 8100.6 8104.4 8180.8 8412.9 33044.0 33657.5 33951.7 34176.3 34927.8 41611.2 42968.4 43828.0 44003.8 44595.1 10771.5 10834.5 10858.3 11008.1 11321.5 10919.6 11166.3 11174.2 11602.5 11695.8 10700.7 10742.6 11082.2 11087.9 11886.4 10464.4 10818.0 11009.7 11411.7 11801.4 10383.3 10470.6 10553.7 10661.1 11366.5 9289.3 9501.2 9552.0 9567.9 9769.5 9181.6 9460.9 9468.0 9631.0 9807.3 01:29:10 INFO - 2015-11-25 01:29:10,592 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:29:10 INFO - 2015-11-25 01:29:10,592 DEBUG : BROWSER_OUTPUT: 01:29:11 INFO - 2015-11-25 01:29:11,285 INFO : Browser exited with error code: 0 01:29:11 INFO - 2015-11-25 01:29:11,301 INFO : Completed test dromaeo_css (00:18:33) 01:29:11 INFO - 2015-11-25 01:29:11,301 INFO : Starting test kraken 01:29:11 INFO - 2015-11-25 01:29:11,302 DEBUG : operating with platform_type : linux_ 01:29:11 INFO - 2015-11-25 01:29:11,302 INFO : Initialising browser for kraken test... 01:29:11 INFO - 2015-11-25 01:29:11,308 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpEXUJC7/profile http://localhost:35757/getInfo.html 01:29:14 INFO - 2015-11-25 01:29:14,965 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:29:14 INFO - 2015-11-25 01:29:14,965 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:29:14 INFO - 2015-11-25 01:29:14,965 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:29:14 INFO - 2015-11-25 01:29:14,965 DEBUG : BROWSER_OUTPUT: __metrics 01:29:15 INFO - 2015-11-25 01:29:15,069 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] 01:29:16 INFO - 2015-11-25 01:29:16,313 INFO : Browser initialized. 01:29:16 INFO - 2015-11-25 01:29:16,313 INFO : Running cycle 1/1 for kraken test... 01:29:16 INFO - 2015-11-25 01:29:16,313 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpEXUJC7/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:29:28 INFO - 2015-11-25 01:29:28,010 DEBUG : BROWSER_OUTPUT: RSS: Main: 138915840 01:29:28 INFO - 2015-11-25 01:29:28,010 DEBUG : BROWSER_OUTPUT: 01:29:30 INFO - 2015-11-25 01:29:30,456 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35757/tests/kraken/driver.html?testName=audio-beat-detection) 01:29:31 INFO - 2015-11-25 01:29:31,038 DEBUG : BROWSER_OUTPUT: RSS: Main: 175730688 01:29:31 INFO - 2015-11-25 01:29:31,038 DEBUG : BROWSER_OUTPUT: 01:29:33 INFO - 2015-11-25 01:29:33,655 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35757/tests/kraken/driver.html?testName=audio-dft) 01:29:34 INFO - 2015-11-25 01:29:34,222 DEBUG : BROWSER_OUTPUT: RSS: Main: 167063552 01:29:34 INFO - 2015-11-25 01:29:34,222 DEBUG : BROWSER_OUTPUT: 01:29:37 INFO - 2015-11-25 01:29:37,059 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35757/tests/kraken/driver.html?testName=audio-fft) 01:29:37 INFO - 2015-11-25 01:29:37,613 DEBUG : BROWSER_OUTPUT: RSS: Main: 150921216 01:29:37 INFO - 2015-11-25 01:29:37,613 DEBUG : BROWSER_OUTPUT: 01:29:39 INFO - 2015-11-25 01:29:39,626 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35757/tests/kraken/driver.html?testName=audio-oscillator) 01:29:40 INFO - 2015-11-25 01:29:40,187 DEBUG : BROWSER_OUTPUT: RSS: Main: 153325568 01:29:40 INFO - 2015-11-25 01:29:40,187 DEBUG : BROWSER_OUTPUT: 01:29:42 INFO - 2015-11-25 01:29:42,386 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35757/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:29:42 INFO - 2015-11-25 01:29:42,955 DEBUG : BROWSER_OUTPUT: RSS: Main: 153710592 01:29:42 INFO - 2015-11-25 01:29:42,955 DEBUG : BROWSER_OUTPUT: 01:29:45 INFO - 2015-11-25 01:29:45,845 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35757/tests/kraken/driver.html?testName=imaging-darkroom) 01:29:46 INFO - 2015-11-25 01:29:46,418 DEBUG : BROWSER_OUTPUT: RSS: Main: 155987968 01:29:46 INFO - 2015-11-25 01:29:46,418 DEBUG : BROWSER_OUTPUT: 01:29:49 INFO - 2015-11-25 01:29:49,801 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35757/tests/kraken/driver.html?testName=imaging-desaturate) 01:29:50 INFO - 2015-11-25 01:29:50,383 DEBUG : BROWSER_OUTPUT: RSS: Main: 155885568 01:29:50 INFO - 2015-11-25 01:29:50,383 DEBUG : BROWSER_OUTPUT: 01:29:53 INFO - 2015-11-25 01:29:53,126 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35757/tests/kraken/driver.html?testName=json-parse-financial) 01:29:53 INFO - 2015-11-25 01:29:53,707 DEBUG : BROWSER_OUTPUT: RSS: Main: 154107904 01:29:53 INFO - 2015-11-25 01:29:53,707 DEBUG : BROWSER_OUTPUT: 01:29:55 INFO - 2015-11-25 01:29:55,247 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35757/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:29:55 INFO - 2015-11-25 01:29:55,808 DEBUG : BROWSER_OUTPUT: RSS: Main: 165834752 01:29:55 INFO - 2015-11-25 01:29:55,808 DEBUG : BROWSER_OUTPUT: 01:29:57 INFO - 2015-11-25 01:29:57,631 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35757/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:29:58 INFO - 2015-11-25 01:29:58,233 DEBUG : BROWSER_OUTPUT: RSS: Main: 163713024 01:29:58 INFO - 2015-11-25 01:29:58,233 DEBUG : BROWSER_OUTPUT: 01:30:00 INFO - 2015-11-25 01:30:00,265 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35757/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:30:00 INFO - 2015-11-25 01:30:00,837 DEBUG : BROWSER_OUTPUT: RSS: Main: 172589056 01:30:00 INFO - 2015-11-25 01:30:00,837 DEBUG : BROWSER_OUTPUT: 01:30:03 INFO - 2015-11-25 01:30:03,272 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35757/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:30:03 INFO - 2015-11-25 01:30:03,854 DEBUG : BROWSER_OUTPUT: RSS: Main: 181391360 01:30:03 INFO - 2015-11-25 01:30:03,854 DEBUG : BROWSER_OUTPUT: 01:30:06 INFO - 2015-11-25 01:30:06,403 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35757/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:30:06 INFO - 2015-11-25 01:30:06,969 DEBUG : BROWSER_OUTPUT: RSS: Main: 179707904 01:30:06 INFO - 2015-11-25 01:30:06,969 DEBUG : BROWSER_OUTPUT: 01:30:08 INFO - 2015-11-25 01:30:08,642 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35757/tests/kraken/driver.html?testName=ai-astar) 01:30:08 INFO - 2015-11-25 01:30:08,900 DEBUG : BROWSER_OUTPUT: RSS: Main: 238456832 01:30:08 INFO - 2015-11-25 01:30:08,900 DEBUG : BROWSER_OUTPUT: 01:30:08 INFO - 2015-11-25 01:30:08,900 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:30:08 INFO - 2015-11-25 01:30:08,901 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:30:08 INFO - 2015-11-25 01:30:08,901 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:30:08 INFO - 2015-11-25 01:30:08,901 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:30:08 INFO - 2015-11-25 01:30:08,901 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;111;103;108;111;111;100;109;109;99 01:30:08 INFO - 2015-11-25 01:30:08,901 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;153;126;147;169;132;152;157;127;177;133 01:30:08 INFO - 2015-11-25 01:30:08,901 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;159;160;160;160;159;161;161;161 01:30:08 INFO - 2015-11-25 01:30:08,901 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;71;93;93;72;71;72;99;97;72 01:30:08 INFO - 2015-11-25 01:30:08,901 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;93;135;93;94;94;140;93;93;147 01:30:08 INFO - 2015-11-25 01:30:08,901 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;109;110;110;109;110;109;109 01:30:08 INFO - 2015-11-25 01:30:08,901 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;149;146;146;146;146;146;145;146 01:30:08 INFO - 2015-11-25 01:30:08,901 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;96;95;95;95;95;95;95;95 01:30:08 INFO - 2015-11-25 01:30:08,902 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;57;58;56;56;55;56;56;57;54 01:30:08 INFO - 2015-11-25 01:30:08,902 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;70;70;71;71;71;71;71;71;72;71 01:30:08 INFO - 2015-11-25 01:30:08,902 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;84;85;85;85;87;86;88;85;85 01:30:08 INFO - 2015-11-25 01:30:08,902 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;124;132;123;121;123;118;124;132;118;125 01:30:08 INFO - 2015-11-25 01:30:08,902 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;151;151;148;147;147;150;147;149;145 01:30:08 INFO - 2015-11-25 01:30:08,902 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;66;68;67;66;66;65;65;66;68 01:30:08 INFO - 2015-11-25 01:30:08,902 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:30:08 INFO - 2015-11-25 01:30:08,902 DEBUG : BROWSER_OUTPUT: __start_cc_report 01:30:08 INFO - 2015-11-25 01:30:08,902 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,936 01:30:08 INFO - 2015-11-25 01:30:08,902 DEBUG : BROWSER_OUTPUT: __end_cc_report 01:30:08 INFO - 2015-11-25 01:30:08,903 DEBUG : BROWSER_OUTPUT: __startTimestamp1448443808894__endTimestamp 01:30:08 INFO - 2015-11-25 01:30:08,903 DEBUG : BROWSER_OUTPUT: 01:30:08 INFO - 2015-11-25 01:30:08,903 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:30:08 INFO - 2015-11-25 01:30:08,903 DEBUG : BROWSER_OUTPUT: Number of tests: 14 01:30:08 INFO - 2015-11-25 01:30:08,903 DEBUG : BROWSER_OUTPUT: 01:30:08 INFO - 2015-11-25 01:30:08,903 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.20 Median:111.00 stddev:4.73 (4.3%) stddev-sans-first:4.82 01:30:08 INFO - 2015-11-25 01:30:08,903 DEBUG : BROWSER_OUTPUT: Values: 111.0 111.0 103.0 108.0 111.0 111.0 100.0 109.0 109.0 99.0 01:30:08 INFO - 2015-11-25 01:30:08,903 DEBUG : BROWSER_OUTPUT: 01:30:08 INFO - 2015-11-25 01:30:08,903 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:147.30 Median:152.50 stddev:17.66 (11.6%) stddev-sans-first:18.61 01:30:08 INFO - 2015-11-25 01:30:08,903 DEBUG : BROWSER_OUTPUT: Values: 153.0 126.0 147.0 169.0 132.0 152.0 157.0 127.0 177.0 133.0 01:30:08 INFO - 2015-11-25 01:30:08,903 DEBUG : BROWSER_OUTPUT: 01:30:08 INFO - 2015-11-25 01:30:08,903 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.20 Median:160.50 stddev:0.79 (0.5%) stddev-sans-first:0.78 01:30:08 INFO - 2015-11-25 01:30:08,904 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 159.0 160.0 160.0 160.0 159.0 161.0 161.0 161.0 01:30:08 INFO - 2015-11-25 01:30:08,904 DEBUG : BROWSER_OUTPUT: 01:30:08 INFO - 2015-11-25 01:30:08,904 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.30 Median:83.00 stddev:12.36 (14.9%) stddev-sans-first:12.74 01:30:08 INFO - 2015-11-25 01:30:08,904 DEBUG : BROWSER_OUTPUT: Values: 73.0 71.0 93.0 93.0 72.0 71.0 72.0 99.0 97.0 72.0 01:30:08 INFO - 2015-11-25 01:30:08,904 DEBUG : BROWSER_OUTPUT: 01:30:08 INFO - 2015-11-25 01:30:08,904 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.60 Median:93.00 stddev:23.00 (24.7%) stddev-sans-first:23.86 01:30:08 INFO - 2015-11-25 01:30:08,904 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 135.0 93.0 94.0 94.0 140.0 93.0 93.0 147.0 01:30:08 INFO - 2015-11-25 01:30:08,904 DEBUG : BROWSER_OUTPUT: 01:30:08 INFO - 2015-11-25 01:30:08,904 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.40 Median:109.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 01:30:08 INFO - 2015-11-25 01:30:08,904 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 109.0 110.0 110.0 109.0 110.0 109.0 109.0 01:30:08 INFO - 2015-11-25 01:30:08,904 DEBUG : BROWSER_OUTPUT: 01:30:08 INFO - 2015-11-25 01:30:08,904 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:1.06 (0.7%) stddev-sans-first:1.09 01:30:08 INFO - 2015-11-25 01:30:08,905 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 149.0 146.0 146.0 146.0 146.0 146.0 145.0 146.0 01:30:08 INFO - 2015-11-25 01:30:08,905 DEBUG : BROWSER_OUTPUT: 01:30:08 INFO - 2015-11-25 01:30:08,905 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 01:30:08 INFO - 2015-11-25 01:30:08,905 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 01:30:08 INFO - 2015-11-25 01:30:08,905 DEBUG : BROWSER_OUTPUT: 01:30:08 INFO - 2015-11-25 01:30:08,905 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.50 stddev:1.14 (2.0%) stddev-sans-first:1.17 01:30:08 INFO - 2015-11-25 01:30:08,905 DEBUG : BROWSER_OUTPUT: Values: 57.0 57.0 58.0 56.0 56.0 55.0 56.0 56.0 57.0 54.0 01:30:08 INFO - 2015-11-25 01:30:08,905 DEBUG : BROWSER_OUTPUT: 01:30:08 INFO - 2015-11-25 01:30:08,905 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:0.57 (0.8%) stddev-sans-first:0.50 01:30:08 INFO - 2015-11-25 01:30:08,906 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 71.0 71.0 71.0 71.0 71.0 71.0 72.0 71.0 01:30:08 INFO - 2015-11-25 01:30:08,907 DEBUG : BROWSER_OUTPUT: 01:30:08 INFO - 2015-11-25 01:30:08,907 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:85.00 stddev:1.26 (1.5%) stddev-sans-first:1.24 01:30:08 INFO - 2015-11-25 01:30:08,907 DEBUG : BROWSER_OUTPUT: Values: 84.0 84.0 85.0 85.0 85.0 87.0 86.0 88.0 85.0 85.0 01:30:08 INFO - 2015-11-25 01:30:08,907 DEBUG : BROWSER_OUTPUT: 01:30:08 INFO - 2015-11-25 01:30:08,907 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:124.00 Median:124.00 stddev:4.85 (3.9%) stddev-sans-first:5.15 01:30:08 INFO - 2015-11-25 01:30:08,907 DEBUG : BROWSER_OUTPUT: Values: 124.0 132.0 123.0 121.0 123.0 118.0 124.0 132.0 118.0 125.0 01:30:08 INFO - 2015-11-25 01:30:08,907 DEBUG : BROWSER_OUTPUT: 01:30:08 INFO - 2015-11-25 01:30:08,907 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:147.50 Median:148.50 stddev:3.27 (2.2%) stddev-sans-first:2.06 01:30:08 INFO - 2015-11-25 01:30:08,907 DEBUG : BROWSER_OUTPUT: Values: 140.0 151.0 151.0 148.0 147.0 147.0 150.0 147.0 149.0 145.0 01:30:08 INFO - 2015-11-25 01:30:08,907 DEBUG : BROWSER_OUTPUT: 01:30:08 INFO - 2015-11-25 01:30:08,907 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:1.06 (1.6%) stddev-sans-first:1.12 01:30:08 INFO - 2015-11-25 01:30:08,907 DEBUG : BROWSER_OUTPUT: Values: 66.0 66.0 68.0 67.0 66.0 66.0 65.0 65.0 66.0 68.0 01:30:08 INFO - 2015-11-25 01:30:08,907 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:30:08 INFO - 2015-11-25 01:30:08,907 DEBUG : BROWSER_OUTPUT: 01:30:09 INFO - 2015-11-25 01:30:09,641 INFO : Browser exited with error code: 0 01:30:09 INFO - 2015-11-25 01:30:09,695 INFO : Completed test kraken (00:00:58) 01:30:09 INFO - 2015-11-25 01:30:09,696 INFO : Starting test v8_7 01:30:09 INFO - 2015-11-25 01:30:09,696 DEBUG : operating with platform_type : linux_ 01:30:09 INFO - 2015-11-25 01:30:09,696 INFO : Initialising browser for v8_7 test... 01:30:09 INFO - 2015-11-25 01:30:09,702 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpp3ApOF/profile http://localhost:35757/getInfo.html 01:30:13 INFO - 2015-11-25 01:30:13,541 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:30:13 INFO - 2015-11-25 01:30:13,541 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:30:13 INFO - 2015-11-25 01:30:13,541 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:30:13 INFO - 2015-11-25 01:30:13,542 DEBUG : BROWSER_OUTPUT: __metrics 01:30:13 INFO - 2015-11-25 01:30:13,658 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] 01:30:14 INFO - 2015-11-25 01:30:14,971 INFO : Browser initialized. 01:30:14 INFO - 2015-11-25 01:30:14,971 INFO : Running cycle 1/1 for v8_7 test... 01:30:14 INFO - 2015-11-25 01:30:14,972 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpp3ApOF/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:30:26 INFO - 2015-11-25 01:30:26,700 DEBUG : BROWSER_OUTPUT: RSS: Main: 139612160 01:30:26 INFO - 2015-11-25 01:30:26,700 DEBUG : BROWSER_OUTPUT: 01:30:48 INFO - 2015-11-25 01:30:48,126 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35757/tests/v8_7/run.html (next: http://localhost:35757/tests/v8_7/run.html) 01:30:48 INFO - 2015-11-25 01:30:48,376 DEBUG : BROWSER_OUTPUT: RSS: Main: 165371904 01:30:48 INFO - 2015-11-25 01:30:48,376 DEBUG : BROWSER_OUTPUT: 01:30:48 INFO - 2015-11-25 01:30:48,376 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:30:48 INFO - 2015-11-25 01:30:48,376 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:30:48 INFO - 2015-11-25 01:30:48,377 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:30:48 INFO - 2015-11-25 01:30:48,377 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:30:48 INFO - 2015-11-25 01:30:48,377 DEBUG : BROWSER_OUTPUT: |0;Richards;166.5001665001665 01:30:48 INFO - 2015-11-25 01:30:48,377 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;195.88638589618023 01:30:48 INFO - 2015-11-25 01:30:48,377 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.9295854628052 01:30:48 INFO - 2015-11-25 01:30:48,377 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5186.5284974093265 01:30:48 INFO - 2015-11-25 01:30:48,378 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1097.694840834248 01:30:48 INFO - 2015-11-25 01:30:48,378 DEBUG : BROWSER_OUTPUT: |5;Earley;778.816199376947 01:30:48 INFO - 2015-11-25 01:30:48,378 DEBUG : BROWSER_OUTPUT: |6;Boyer;12243.90243902439 01:30:48 INFO - 2015-11-25 01:30:48,378 DEBUG : BROWSER_OUTPUT: |7;RegExp;26605.263157894737 01:30:48 INFO - 2015-11-25 01:30:48,378 DEBUG : BROWSER_OUTPUT: |8;Splay;582.0721769499418 01:30:48 INFO - 2015-11-25 01:30:48,379 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 01:30:48 INFO - 2015-11-25 01:30:48,379 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:30:48 INFO - 2015-11-25 01:30:48,379 DEBUG : BROWSER_OUTPUT: __startTimestamp1448443848375__endTimestamp 01:30:48 INFO - 2015-11-25 01:30:48,379 DEBUG : BROWSER_OUTPUT: 01:30:48 INFO - 2015-11-25 01:30:48,379 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:30:48 INFO - 2015-11-25 01:30:48,380 DEBUG : BROWSER_OUTPUT: Number of tests: 10 01:30:48 INFO - 2015-11-25 01:30:48,380 DEBUG : BROWSER_OUTPUT: 01:30:48 INFO - 2015-11-25 01:30:48,380 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.50 Median:166.50 stddev:0.00 (0.0%) 01:30:48 INFO - 2015-11-25 01:30:48,380 DEBUG : BROWSER_OUTPUT: Values: 166.5 01:30:48 INFO - 2015-11-25 01:30:48,380 DEBUG : BROWSER_OUTPUT: 01:30:48 INFO - 2015-11-25 01:30:48,380 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:195.89 Median:195.89 stddev:0.00 (0.0%) 01:30:48 INFO - 2015-11-25 01:30:48,381 DEBUG : BROWSER_OUTPUT: Values: 195.9 01:30:48 INFO - 2015-11-25 01:30:48,381 DEBUG : BROWSER_OUTPUT: 01:30:48 INFO - 2015-11-25 01:30:48,381 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.93 Median:283.93 stddev:0.00 (0.0%) 01:30:48 INFO - 2015-11-25 01:30:48,381 DEBUG : BROWSER_OUTPUT: Values: 283.9 01:30:48 INFO - 2015-11-25 01:30:48,381 DEBUG : BROWSER_OUTPUT: 01:30:48 INFO - 2015-11-25 01:30:48,381 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5186.53 Median:5186.53 stddev:0.00 (0.0%) 01:30:48 INFO - 2015-11-25 01:30:48,381 DEBUG : BROWSER_OUTPUT: Values: 5186.5 01:30:48 INFO - 2015-11-25 01:30:48,381 DEBUG : BROWSER_OUTPUT: 01:30:48 INFO - 2015-11-25 01:30:48,381 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1097.69 Median:1097.69 stddev:0.00 (0.0%) 01:30:48 INFO - 2015-11-25 01:30:48,382 DEBUG : BROWSER_OUTPUT: Values: 1097.7 01:30:48 INFO - 2015-11-25 01:30:48,382 DEBUG : BROWSER_OUTPUT: 01:30:48 INFO - 2015-11-25 01:30:48,382 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:778.82 Median:778.82 stddev:0.00 (0.0%) 01:30:48 INFO - 2015-11-25 01:30:48,382 DEBUG : BROWSER_OUTPUT: Values: 778.8 01:30:48 INFO - 2015-11-25 01:30:48,383 DEBUG : BROWSER_OUTPUT: 01:30:48 INFO - 2015-11-25 01:30:48,383 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12243.90 Median:12243.90 stddev:0.00 (0.0%) 01:30:48 INFO - 2015-11-25 01:30:48,383 DEBUG : BROWSER_OUTPUT: Values: 12243.9 01:30:48 INFO - 2015-11-25 01:30:48,383 DEBUG : BROWSER_OUTPUT: 01:30:48 INFO - 2015-11-25 01:30:48,383 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26605.26 Median:26605.26 stddev:0.00 (0.0%) 01:30:48 INFO - 2015-11-25 01:30:48,383 DEBUG : BROWSER_OUTPUT: Values: 26605.3 01:30:48 INFO - 2015-11-25 01:30:48,383 DEBUG : BROWSER_OUTPUT: 01:30:48 INFO - 2015-11-25 01:30:48,383 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:582.07 Median:582.07 stddev:0.00 (0.0%) 01:30:48 INFO - 2015-11-25 01:30:48,383 DEBUG : BROWSER_OUTPUT: Values: 582.1 01:30:48 INFO - 2015-11-25 01:30:48,384 DEBUG : BROWSER_OUTPUT: 01:30:48 INFO - 2015-11-25 01:30:48,384 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 01:30:48 INFO - 2015-11-25 01:30:48,384 DEBUG : BROWSER_OUTPUT: Values: 4826.9 01:30:48 INFO - 2015-11-25 01:30:48,384 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:30:48 INFO - 2015-11-25 01:30:48,384 DEBUG : BROWSER_OUTPUT: 01:30:49 INFO - 2015-11-25 01:30:49,269 INFO : Browser exited with error code: 0 01:30:49 INFO - 2015-11-25 01:30:49,284 INFO : Completed test v8_7 (00:00:39) 01:30:49 INFO - 2015-11-25 01:30:49,367 INFO : Completed test suite (00:20:11) 01:30:49 INFO - 2015-11-25 01:30:49,367 DEBUG : Working with test: dromaeo_css 01:30:49 INFO - 2015-11-25 01:30:49,367 DEBUG : Generating results file: dromaeo_css 01:30:49 INFO - 2015-11-25 01:30:49,368 DEBUG : Working with test: kraken 01:30:49 INFO - 2015-11-25 01:30:49,368 DEBUG : Generating results file: kraken 01:30:49 INFO - 2015-11-25 01:30:49,368 INFO : javascript benchmark 01:30:49 INFO - 2015-11-25 01:30:49,369 DEBUG : Working with test: v8_7 01:30:49 INFO - 2015-11-25 01:30:49,369 DEBUG : Generating results file: v8_7 01:30:49 INFO - 2015-11-25 01:30:49,369 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:30:49 INFO - 2015-11-25 01:30:49,424 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 01:30:49 INFO - 2015-11-25 01:30:49,424 DEBUG : process_Request line: dromaeo_css 6973.44 graph.html#tests=[[72,64,35]] 01:30:49 INFO - 2015-11-25 01:30:49,424 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:30:49 INFO - 2015-11-25 01:30:49,457 DEBUG : process_Request line: kraken 1504.70 graph.html#tests=[[232,64,35]] 01:30:49 INFO - 2015-11-25 01:30:49,458 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:30:49 INFO - 2015-11-25 01:30:49,492 DEBUG : process_Request line: v8_7 20989.10 graph.html#tests=[[230,64,35]] 01:30:49 INFO - 2015-11-25 01:30:49,497 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3209.1656169395956, 3248.8538015789286, 3297.90106296811, 3302.339532093582, 3322.534845121683, 5370.033393989081, 5376.354837419505, 5383.973080134599, 5442.564594832414, 5527.419620939803, 5460.918086228709, 5507.5593952483805, 5523.806666766664, 5538.889222215554, 5618.325800903897, 5018.222175562793, 5030.773615187325, 5067.898642027158, 5113.411957624865, 5114.0, 4848.515148485141, 5076.84769456915, 5092.668976516533, 5130.486951304858, 5155.664881782691, 4980.626453016021, 4981.452040275567, 5097.311862898536, 5103.974480127613, 5112.565431938289, 5070.112730272224, 5071.061853557104, 5073.797048118071, 5077.517635824591, 5130.9230361544605, 4862.683925544864, 5030.974845125787, 5048.6465947383695, 5062.696238225682, 5102.0, 5239.947600524022, 5276.841694749163, 5279.63042587019, 5294.894102117936, 5296.947030529684, 5868.207791948075, 5871.765129394862, 5929.407059294079, 5943.0, 5996.97001514994, 5944.078667806497, 5960.105984102376, 5965.433283838029, 6009.429104235091, 6050.515958723336, 5487.75305111271, 5534.75093620788, 5536.2249285099315, 5541.6674999499755, 5584.301962254718, 5842.503387212049, 5899.616524925866, 5947.940520594825, 5955.047192449188, 5989.55078369124, 5785.074388097884, 5838.0, 5843.2695913010875, 5851.180834683148, 5920.2895652521975, 5659.745311460994, 5830.62100963436, 5915.674637894871, 5934.376890426443, 5940.34656187819, 5828.825135245949, 5854.590178687451, 5863.355030946593, 5903.734331955073, 5910.674912879746, 5959.0, 5960.1059841023325, 5960.344362120163, 5969.194158788508, 5974.551908606872, 4418.536053714378, 4499.0, 4530.139273538017, 4553.043860789271, 4569.885752856138, 4059.1881623674794, 4075.833636594762, 4075.8777236682945, 4096.487939007624, 4117.773522456294, 5653.773849045992, 5655.604107712421, 5656.519195868397, 5658.547316214693, 5710.828675139753, 5793.044147715664, 5896.439838215363, 5903.76384944606, 5962.642241465526, 6022.247219097613, 5678.886422271531, 5689.971550142222, 5695.658260504383, 5716.1997320375185, 5742.0238559445, 5232.215167724872, 5240.371155461293, 5244.003639308519, 5248.501392367719, 5252.579793616502, 5994.610350327215, 6108.083787431743, 6152.0, 6234.812955611339, 6238.095476155932, 5882.588218824641, 5910.379410162043, 5995.340512543529, 5998.4601385875485, 6047.244094488189, 6195.349488303663, 6196.0705894116245, 6196.876062478816, 6202.503799695924, 6210.720517576629, 5757.769689212552, 5827.4172582741385, 5911.113333000085, 5920.023196172756, 5923.585349025527, 5811.27359080115, 5880.882382352243, 5883.735231914488, 5910.379410161871, 5917.408259174221, 5861.267341582302, 5910.113482977587, 5919.023361145535, 5919.348871624032, 5932.2584676915385, 5729.598928075162, 5852.7658893645485, 5863.853403664943, 5864.266966629171, 5864.296284445893, 5516.420775818642, 5608.214849921096, 5609.074502707008, 5616.129499927518, 5656.0667489865355, 5856.502197313276, 5859.56053296013, 5860.706964651836, 5864.530837532901, 5867.295924489089, 5490.396056433869, 5607.2149899014985, 5666.34836993751, 5671.404502527175, 5731.168980497888], "unit": "ms", "name": "dojo.html", "value": 5466.861207020024}, {"lowerIsBetter": false, "replicates": [569.2031156381069, 606.9180660610816, 614.3426533609039, 621.3693101501976, 634.3751404866204, 31287.374252514936, 40417.0, 40753.0, 40780.0, 40795.79602101989, 42087.158256834846, 42546.0, 42651.57348426515, 42664.36003459951, 42678.573214267846, 7638.350740187091, 7720.613969301512, 8841.690540831072, 8994.190522852941, 10275.0, 10450.843237351446, 10592.629257975972, 10600.57597696095, 10613.41626210558, 10620.256582039261, 10550.525226364794, 10644.042036216739, 10649.467526623677, 10659.946700266488, 10670.62652807152, 7828.412869034817, 8014.158513356101, 10665.306755060974, 10893.618723344724, 10904.0, 10445.26883118182, 10591.311564748266, 10600.416977066257, 10606.575736970512, 10611.46942652872, 39583.80208098941, 39902.60097399018, 39935.800320998496, 40019.59980400217, 40023.0, 3258.6904244096772, 3339.3154403347257, 3355.5302257683943, 3969.847493685004, 3993.6405723484736, 29958.700412995808, 30025.699743002508, 30152.396952061055, 30708.53937190944, 31151.532727009115, 3643.708503319728, 3681.723870709681, 3747.4378843173467, 3817.10298079951, 3948.190620922704, 269031.0, 269168.0, 269755.0, 270073.0, 270268.0, 55259.44740552623, 57171.71414142902, 57863.421365785805, 58577.41422585805, 58933.0, 60064.399356006754, 60171.69914150401, 60534.69732651397, 60624.05943613651, 60638.39361606327, 16064.196790160446, 16175.838241617668, 16184.433544825875, 16264.837351626333, 16269.755953660704, 12359.938200308941, 12386.5664701735, 12407.193532420364, 12441.253524788543, 12443.253404795742, 18630.16164272611, 18825.623487530174, 18915.3379631715, 18945.242190312234, 19099.331523396613, 7643.656035478417, 7925.960370198112, 7959.44283900133, 7959.84080318402, 7966.522008679498, 965.990539885821, 989.9703008909601, 1070.8916271658795, 1095.2388090277182, 1124.3135297054419, 27957.580636290473, 28142.43715125686, 28321.858390708327, 28362.0, 28431.28921776972, 1085.6128877900999, 1102.9338239705644, 1152.256794367645, 1179.5694571481347, 1228.7726770547476, 14905.180215088058, 15428.922855385652, 15693.293801778947, 15709.607259818597, 16043.759343609856, 304177.0, 309237.0, 309340.0, 309355.0, 309938.0, 51600.48399515957, 51753.22370164375, 51783.48216517862, 51875.74062129665, 51928.22107668388, 1371.451419432235, 1388.8333399992066, 1399.8600139986124, 1403.8736513713816, 1405.9367328470244, 24677.136300229406, 27602.447951040464, 28006.85996570004, 28815.8559207211, 29679.703202967696, 46220.76889615531, 46619.533804662875, 46631.766841165576, 46670.29994550152, 46720.53279467162, 265331.0, 266649.0, 266725.0, 266820.0, 351804.0, 27934.30164245829, 30685.23286917845, 31299.84350078235, 31446.842765786023, 31521.21196970094, 36725.63274367222, 38004.809975949946, 38185.6181438182, 38544.0, 38919.0, 34043.82978085094, 34296.82851585726, 34615.653843461245, 34792.13019674528, 34902.0, 34521.65478345285, 34602.4091603657, 34727.4790878132, 34775.0, 35008.47487287642, 34516.0, 34544.82727586346, 34639.13402164865, 34803.0, 34883.30233395369, 31085.68914310828, 31325.686743132275, 31330.843345783127, 31613.52579711352, 31660.683393165775, 23632.645510317016, 24092.39769005789, 24178.87910560436, 24220.394490137183, 24460.755392445848], "unit": "ms", "name": "ext.html", "value": 18795.714095399726}, {"lowerIsBetter": false, "replicates": [355.1954822327429, 358.2548299536962, 364.9051246675864, 365.14009507609586, 365.3004496389414, 14416.207108609022, 14422.206778627171, 16151.75772363414, 17157.3994910178, 18219.817801821995, 11044.447777611107, 11361.204715669886, 17360.47918562442, 17815.55461113473, 17834.197461114247, 4548.818047278105, 4655.604273636745, 4657.836975705851, 4658.254679251304, 4658.998315362196, 3831.540215174183, 3920.098377373206, 4913.115639184945, 4920.778564964585, 5286.682799032051, 5366.624336296461, 5375.6774593524315, 5394.487523685263, 5419.458054194569, 5420.783168673248, 5367.302250707403, 5367.973160134214, 5683.687397193153, 5895.086261629454, 5907.320658124285, 5264.894702105975, 5272.92090618641, 5273.1035723927025, 5280.6039547034125, 5281.0, 12978.091533592653, 13065.412056457388, 13073.54242601514, 13075.150115242479, 17196.656066878717, 1980.1782260361933, 2042.9897850510802, 2128.446603882986, 2155.041006751996, 2316.66408370787, 9570.856437153448, 9745.853812192823, 9827.164691001273, 10437.79124417515, 11828.88171118295, 1624.959376015609, 1705.8208888066822, 1812.3022636284993, 1863.4409677096817, 1886.4717878993904, 62041.0, 64516.03225951614, 64937.0, 64992.0, 65091.674541627464, 10075.395476271447, 10279.845802312972, 10428.791424171626, 10469.0, 10533.9473302633, 13728.176309421468, 13752.381142848595, 13753.79369309461, 13767.242801646005, 13772.793408098887, 7020.92979070213, 7042.577445353295, 7094.9290507094265, 7186.964065179745, 7435.628218589049, 6600.471962243009, 6604.669766511752, 6623.768168114093, 6639.767608133789, 6645.169353830771, 4339.153864996296, 4399.5380485049245, 4462.866114016585, 4485.820567177341, 4505.932411013838, 1721.8318294338478, 1764.153206460906, 1777.4312220089705, 1812.0305636484682, 1818.4271954334342, 410.34140204971186, 413.6525318732219, 901.3915606965272, 930.9348345615742, 997.1225321716843, 10385.532651030571, 10696.251262411712, 10821.513031913582, 11038.17213708975, 11091.057260132979, 401.45001348152636, 433.6899117131203, 437.78767297860514, 450.92785154375144, 469.57034313602674, 5788.5369170466265, 5870.412958704095, 5959.702014899238, 5974.3428222895445, 5979.103134529855, 536721.0, 540385.0, 543542.0, 555374.0, 555914.0, 79308.6034569835, 79855.60072199602, 80719.0, 81482.0, 81868.59065704633, 490.94108706955393, 493.3438526759401, 493.89134390434765, 493.9925901111486, 494.0785435163466, 9995.650152244636, 10051.547680354548, 10240.795184096127, 10267.845982310122, 10286.639967601099, 34105.147371315914, 34223.4866477008, 34478.82760586181, 35004.47493287654, 35144.0, 51734.74132629313, 54601.45398545964, 57082.7145864268, 57336.71331643315, 57653.423465764805, 12265.938670306592, 13826.585202443943, 14650.413983440543, 14661.340239689027, 15111.773323399937, 19415.70876436883, 19752.802471975097, 20074.498137546678, 20076.498087547927, 20385.592288153857, 15582.22088895528, 15598.766018509505, 15969.92015039964, 15990.200489975223, 16232.918835405748, 15566.766498502759, 15719.371225150866, 16021.118838463997, 16197.51407457778, 16304.755428668343, 12781.8082728761, 15589.064656120669, 16114.838851611334, 16242.756358654393, 16572.088535130682, 14420.062695924731, 14575.489857854924, 14585.781213281596, 14680.55958321252, 14768.852311476749, 8959.776005599651, 9330.486823224517, 15025.0, 15906.966047206895, 16072.678546428771], "unit": "ms", "name": "jquery.html", "value": 8258.78933930998}, {"lowerIsBetter": false, "replicates": [1362.4345896452974, 1666.6999940010796, 1723.8536373311745, 1729.377424127314, 1748.8251174882514, 6830.692618832152, 7753.147153813083, 7810.804729881748, 7816.452848300622, 7821.139674635786, 7583.810404739885, 7768.941230573902, 7835.92164078359, 7843.294103530681, 7853.960730196355, 2018.7880272571315, 2059.4851287178208, 2062.5049988002847, 2266.54669066187, 2297.264875239924, 2036.4297996560986, 2271.3980795089315, 2312.4681323295654, 2312.583734927712, 2313.641385585237, 2256.3005468304796, 2264.784845439681, 2273.386185729852, 2307.6077066898583, 2310.849794763343, 1953.384683824605, 2019.0914088660015, 2292.5300313435787, 2313.3869524576003, 2317.7218733751974, 2256.616375216217, 2306.6540018997116, 2308.1690591387087, 2309.3418375762826, 2309.6881920940796, 7638.923610763877, 7772.3393511551585, 7772.650230739574, 7781.572013539253, 7794.2595453432405, 4587.036722288323, 4640.32715256286, 4641.767911604406, 4670.159371313162, 4675.836345727917, 2983.284011837165, 3076.384723055398, 3096.5974423325047, 3113.112762862573, 3126.124685088179, 998.5955687946393, 1019.0726438940602, 1034.5241189053045, 1040.7086015915556, 1069.4919913041322, 10685.893141068567, 10722.78554428907, 10750.086242669382, 10761.51573179201, 10792.298500597437, 4171.395147703566, 4247.511536173318, 4268.103698223408, 4297.226499230169, 4333.826646934151, 9186.678466253648, 9262.90737092634, 9268.25853931684, 9269.0, 9279.67521136757, 4544.409226800495, 4556.111558246176, 4575.0392417592675, 4598.034412773355, 4601.976990115028, 4083.244599749056, 4113.526944401432, 4119.9176016479505, 4120.21715873989, 4123.072308730571, 3675.4303083022173, 3675.7426980111672, 3691.9077023074638, 3705.6479634434686, 3728.5339332583426, 1218.695326168458, 1221.9816702749465, 1236.0852968803201, 1238.3684320996356, 1244.7634949359592, 1029.330934892311, 1031.9948400257952, 1221.096388672376, 1333.2533781082625, 1366.6993261482457, 7456.440766942393, 7512.774616761506, 7540.509866858637, 7541.924580754232, 7574.734884279023, 505.0505050505027, 508.7786812736471, 511.6086194061546, 511.91297479428584, 518.9455107213688, 5501.587380946445, 5564.415736347625, 5660.660360378391, 6141.140240366352, 6268.874622507525, 9361.672341468153, 9452.95273523628, 9475.28935329853, 9593.184579310697, 9636.518174091267, 9335.299852510952, 9396.95301523488, 9401.623935042659, 9411.6705915294, 9541.61833526665, 7079.752208672775, 7108.68010939509, 7228.56628602275, 7234.312740289664, 7366.63166841645, 7399.48203625741, 7431.219721929119, 7436.107667079985, 7456.403487721078, 7458.701651933862, 8131.227533384319, 8190.303824175012, 8384.916150838657, 8437.746867593982, 8446.873296900429, 8551.097291155853, 8597.441166324168, 8737.43206691563, 8821.161989610977, 8823.955880220557, 7004.0, 7055.611941343275, 7114.57312561248, 7119.6084215368655, 7121.8961061035625, 7712.305892469704, 7764.5729484878875, 7774.650140743567, 7782.766517004499, 7790.220977902164, 7139.964300178466, 7169.139703235645, 7254.673539690621, 7314.48798584094, 7330.486865919548, 6958.547694399847, 7053.470989675898, 7055.929440705732, 7074.575525468488, 7185.676644551112, 7157.749478768218, 7237.384822290164, 7296.306850849161, 7298.56208627484, 7311.890321645286, 6670.566413183127, 6708.932910670831, 6709.664516774239, 6719.294474080347, 6722.865542689216, 6528.37980391862, 6587.505937054836, 6645.335466453316, 6737.494687898329, 6746.763863264762], "unit": "ms", "name": "mootools.html", "value": 4432.072348201252}, {"lowerIsBetter": false, "replicates": [333.70633842218854, 343.3767711603441, 345.21808104642975, 347.4406206008328, 347.8834590412213, 6067.817965461038, 6095.420935011167, 6106.236720409949, 6118.479929206012, 6120.877582448348, 5773.769049238036, 5864.2962844458725, 5916.5266778657715, 6011.218541589598, 6055.394460553943, 4678.415198100238, 4683.203855344583, 4692.41344831896, 4695.741734204609, 4700.106979673851, 4458.086092351076, 4684.0, 4686.484486706459, 4686.695364801277, 4692.9530704693025, 4494.97752511237, 4519.4124763780665, 4613.1696294666945, 4630.421197350332, 4637.930431043537, 4602.286645569942, 4614.215583350839, 4614.792334344946, 4622.676412651116, 4629.629629629639, 4066.9186616267507, 4076.0625056236936, 4083.530394004668, 4084.9591504084874, 4094.5905409458815, 5619.325680918316, 5675.304230490526, 5678.43215678433, 5695.088785794253, 5695.601307908448, 5354.571634269249, 5358.169483730028, 5367.355917289911, 5369.9463005369835, 5376.650517716336, 6427.228732552124, 6429.35706429358, 6431.742730290783, 6432.678366081677, 6434.549581529295, 4454.242778727624, 4457.977710111461, 4459.219636563621, 4463.196624607569, 4468.061707041526, 6482.448991835699, 6556.508261880331, 6625.834354141137, 6626.900596491008, 6628.370304821035, 4542.273236282179, 4543.114092751882, 4548.704334218265, 4551.908961820745, 4560.384348113029, 4606.769661516911, 4607.378003969488, 4608.847788052987, 4608.907821843544, 4609.746463944448, 5304.310439642822, 5313.0, 5316.282301889273, 5317.229001794718, 5327.893442131136, 6277.905831412532, 6429.710663020175, 6444.2266927968, 6447.6131432114225, 6451.161349024596, 1612.8870979031356, 1625.1143126995817, 1638.90166590005, 1639.631083006314, 1640.5727017494855, 1076.7254350140663, 1084.8426978088132, 1089.5097206257217, 1095.1403148528368, 1107.6067995861515, 6264.279607845065, 6269.498440124779, 6275.058741188767, 6277.089821975879, 6277.403646653561, 6458.61248325102, 6695.598264104072, 6701.463882889358, 6704.530682852251, 6709.1885190998155, 6136.20229370179, 6226.968865155736, 6263.123162757127, 6331.810045698544, 6352.587081839665, 3627.6553727395853, 3659.743817932719, 3665.0, 3667.9633203668154, 3671.4125739881492, 6792.660366981631, 6910.032595436643, 6912.205096413876, 6939.791806245821, 6957.8956315655305, 6846.383825455733, 6858.759943401957, 6866.793996180123, 6893.310668933066, 6895.689693963784, 6846.246912839583, 6877.4841886858685, 6889.655517224219, 6948.478864085113, 6951.89572156418, 6545.607263564201, 6674.966625167037, 6686.431653309523, 6697.7655782047395, 6712.966435167793, 6567.802965911031, 6595.835104122435, 6597.80206593803, 6601.405873471411, 6601.669916504252, 6888.276730943179, 6943.166819981635, 6945.826354340979, 6950.6177160256675, 6956.652167391711, 4490.169318676056, 4551.5676010778925, 4570.085982803386, 4573.862784116482, 4575.93136102952, 6666.933330666825, 6675.766348177791, 6695.263521012782, 6698.631575263407, 6700.49746269022, 6551.0, 6558.901616475852, 6564.5076619254705, 6565.212174538895, 6577.539572229898, 6416.615003099829, 6541.378569035934, 6549.901751473636, 6560.8359791005605, 6560.901586476302, 6400.487960963019, 6405.775797847239, 6412.422881940648, 6426.196725409324, 6436.452901503425, 6548.0832683425315, 6552.180977377828, 6557.344265573404, 6558.049082883051, 6573.868522629617, 6316.14732011191, 6385.8722825544155, 6398.360163983564, 6400.55196136266, 6422.582532135583], "unit": "ms", "name": "prototype.html", "value": 4871.825188220632}, {"lowerIsBetter": false, "replicates": [842.7808769719871, 842.8819965204873, 860.8665656823192, 864.7449002544249, 876.4171825735882, 13282.203067815937, 14292.213928233943, 14343.569692909203, 14362.42550297988, 14376.49682261121, 13493.2578708171, 13532.729345413085, 13596.04827662064, 13619.591412257649, 13629.386677599507, 687.9036934829128, 694.2987582541618, 834.8705950577655, 839.5718183726314, 852.8209076094028, 835.3233880556769, 842.4271495383138, 843.371688092369, 843.5234092737614, 846.7205822078699, 880.0715245416084, 883.8497455432592, 884.1291328041874, 886.756142060932, 888.4136469929848, 716.6201912986099, 722.5050840174497, 872.8123453457505, 883.8630012348096, 885.5041176940933, 650.391883588846, 650.7819880340045, 652.7650045983443, 652.8824811533922, 656.4059526128814, 13039.739205215938, 13176.53882114131, 13182.67043323915, 13184.34078296091, 13204.339783010812, 11773.293602383885, 11814.704632384173, 12447.253164810141, 13188.802167967488, 13365.532206372827, 9908.653197138066, 10079.798404031879, 10564.630237941636, 10614.946925265402, 10724.249302548824, 3632.219072899313, 3667.5782285037294, 3701.444783282502, 3807.7905715185652, 3808.9619103808886, 44491.0, 45194.548054519364, 45209.321860171796, 45226.09547809058, 45267.77366113148, 9452.95273523628, 9668.661596844076, 9763.414195148314, 9777.364471309342, 9904.504774761233, 16869.15654217309, 17614.559636009202, 17624.20691068905, 17627.383041593483, 17644.91177544104, 7457.552546847207, 7559.5464272143845, 7566.432517561095, 7626.69493220276, 7684.269994350528, 4226.957730422657, 4239.957600424018, 4259.5730430305575, 4261.169072030924, 4281.165172791338, 3141.2303985523695, 3162.36752649471, 3163.1775738307747, 3173.317736686577, 3177.586913701204, 583.6585597425469, 588.9676067816312, 602.9879402411927, 605.068194979735, 607.957442978996, 541.7291354322839, 608.8417011577022, 687.2611942162196, 690.1132045321738, 699.4264702943639, 8099.878501822477, 8425.241728244488, 8825.69110081144, 9114.36199466031, 9115.726528204164, 566.5892228134569, 626.5614070150913, 680.9863802723918, 723.6526467295728, 729.1214087025122, 3698.537682789651, 3838.8272527736317, 4077.571854955246, 4093.467849179647, 4148.481439820022, 55767.44232557704, 56090.0, 56535.71732141313, 56561.71719141378, 56609.43390566124, 48939.75530122398, 49569.0, 50068.248976265386, 50097.0, 50241.0, 45214.773926130816, 45277.547224527334, 45497.31754023692, 45761.542384575725, 46128.0, 8095.032526013143, 8100.594970251582, 8104.392170587347, 8180.7954801130445, 8412.873806893023, 33044.026037656724, 33657.495137573445, 33951.66048339485, 34176.31647366989, 34927.82536087405, 41611.16777664369, 42968.35547466853, 43828.0, 44003.77998109989, 44595.10809783851, 10771.461426928465, 10834.512446940063, 10858.294210876267, 11008.119350451785, 11321.490532926204, 10919.563217471212, 11166.274192177483, 11174.161937854857, 11602.535898563963, 11695.824562631396, 10700.678979630624, 10742.624008159677, 11082.168837337393, 11087.889121108685, 11886.405679715806, 10464.424456654653, 10818.0, 11009.669709908716, 11411.657650270505, 11801.40992950332, 10383.273170877967, 10470.581176752845, 10553.683389498327, 10661.147108231478, 11366.54533818671, 9289.349745518019, 9501.192398646193, 9551.952240239032, 9567.856482152634, 9769.511524423893, 9181.632734690538, 9460.858087128561, 9467.952660236655, 9630.951845240728, 9807.264455165749], "unit": "ms", "name": "yui.html", "value": 6275.26348167381}], "name": "dromaeo_css"}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 111.0, 103.0, 108.0, 111.0, 111.0, 100.0, 109.0, 109.0, 99.0], "unit": "ms", "name": "ai-astar", "value": 107.2}, {"lowerIsBetter": true, "replicates": [153.0, 126.0, 147.0, 169.0, 132.0, 152.0, 157.0, 127.0, 177.0, 133.0], "unit": "ms", "name": "audio-beat-detection", "value": 147.3}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 159.0, 160.0, 160.0, 160.0, 159.0, 161.0, 161.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.2}, {"lowerIsBetter": true, "replicates": [73.0, 71.0, 93.0, 93.0, 72.0, 71.0, 72.0, 99.0, 97.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 81.3}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 135.0, 93.0, 94.0, 94.0, 140.0, 93.0, 93.0, 147.0], "unit": "ms", "name": "audio-oscillator", "value": 107.6}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 109.0, 110.0, 110.0, 109.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.4}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 149.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.3}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.1}, {"lowerIsBetter": true, "replicates": [57.0, 57.0, 58.0, 56.0, 56.0, 55.0, 56.0, 56.0, 57.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 56.2}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 71.0, 71.0, 71.0, 71.0, 71.0, 71.0, 72.0, 71.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 70.9}, {"lowerIsBetter": true, "replicates": [84.0, 84.0, 85.0, 85.0, 85.0, 87.0, 86.0, 88.0, 85.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.4}, {"lowerIsBetter": true, "replicates": [124.0, 132.0, 123.0, 121.0, 123.0, 118.0, 124.0, 132.0, 118.0, 125.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 124.0}, {"lowerIsBetter": true, "replicates": [140.0, 151.0, 151.0, 148.0, 147.0, 147.0, 150.0, 147.0, 149.0, 145.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 147.5}, {"lowerIsBetter": true, "replicates": [66.0, 66.0, 68.0, 67.0, 66.0, 66.0, 65.0, 65.0, 66.0, 68.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.3}], "name": "kraken"}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.5001665001665], "unit": "score", "name": "Richards", "value": 212.02381199999994}, {"lowerIsBetter": false, "replicates": [195.88638589618023], "unit": "score", "name": "DeltaBlue", "value": 337.5323900000001}, {"lowerIsBetter": false, "replicates": [283.9295854628052], "unit": "score", "name": "Encrypt", "value": 937.4894820000002}, {"lowerIsBetter": false, "replicates": [5186.5284974093265], "unit": "score", "name": "Decrypt", "value": 51.32161138861136}, {"lowerIsBetter": false, "replicates": [1097.694840834248], "unit": "score", "name": "RayTrace", "value": 674.1299790000003}, {"lowerIsBetter": false, "replicates": [778.816199376947], "unit": "score", "name": "Earley", "value": 855.7384919999998}, {"lowerIsBetter": false, "replicates": [12243.90243902439], "unit": "score", "name": "Boyer", "value": 54.4322370517928}, {"lowerIsBetter": false, "replicates": [26605.263157894737], "unit": "score", "name": "RegExp", "value": 34.24078140454997}, {"lowerIsBetter": false, "replicates": [582.0721769499418], "unit": "score", "name": "Splay", "value": 140.001538}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7"}]} 01:30:49 INFO - RETURN: dromaeo_css: 6973.44 01:30:49 INFO - RETURN: kraken: 1504.70 01:30:49 INFO - RETURN: v8_7: 20989.10 01:30:49 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "20989.10"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1504.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6973.44"}}} 01:30:49 INFO - Return code: 0 01:30:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 01:30:49 INFO - # TBPL SUCCESS # 01:30:49 INFO - Running post-action listener: _resource_record_post_action 01:30:49 INFO - Running post-run listener: _resource_record_post_run 01:30:50 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 8192; Write bytes: 511016960; Read time: 88; Write time: 519316 01:30:50 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 241938432; Read time: 0; Write time: 278304 01:30:50 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 8192; Write bytes: 267993088; Read time: 88; Write time: 240784 01:30:50 INFO - Running post-run listener: _upload_blobber_files 01:30:50 INFO - Blob upload gear active. 01:30:50 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 01:30:50 INFO - Copying logs to upload dir... 01:30:50 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1262.964109 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2015-11-25 01:30:49.938125) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-25 01:30:49.941748) ========= 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=d333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/BZDVZYwoSWq2XRVh5skJIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010031 build_url: 'https://queue.taskcluster.net/v1/task/BZDVZYwoSWq2XRVh5skJIg/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 01:30:49.993381) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:30:49.993695) ========= 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=d333f129428bd91e5d9d687c000001bd-1448442261.611358-1858712299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004517 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:30:50.043187) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 01:30:50.043512) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 01:30:50.043833) ========= ========= Total master_lag: 0.22 =========