builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-066 starttime: 1448545493.96 results: success (0) buildid: 20151126030226 builduid: aa5beced8ebf4e78a6824c7503d6afe1 revision: c321d84038519dcf1670d59fd2c5c00ad8a85a55 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:53.958180) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:53.958700) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:53.959042) ========= 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=004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.007644 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:53.981040) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:53.981379) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:53.994605) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:53.994968) ========= 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=004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004577 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:54.045013) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:54.045303) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:54.045684) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:54.045968) ========= 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=004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-26 05:44:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 228M=0s 2015-11-26 05:44:54 (228 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.185708 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:54.244359) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:54.244682) ========= 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=004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018499 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:54.294179) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:54.294531) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c321d84038519dcf1670d59fd2c5c00ad8a85a55 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c321d84038519dcf1670d59fd2c5c00ad8a85a55 --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=004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-26 05:44:54,785 truncating revision to first 12 chars 2015-11-26 05:44:54,785 Setting DEBUG logging. 2015-11-26 05:44:54,785 attempt 1/10 2015-11-26 05:44:54,785 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c321d8403851?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-26 05:44:55,033 unpacking tar archive at: mozilla-central-c321d8403851/testing/mozharness/ program finished with exit code 0 elapsedTime=0.502978 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:54.812421) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:54.812755) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:54.823324) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:54.823683) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-26 05:44:54.824149) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2015-11-26 05:44:54.824444) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:44:55 INFO - MultiFileLogger online at 20151126 05:44:55 in /builds/slave/test-pgo 05:44:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:44:55 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:44:55 INFO - {'append_to_log': False, 05:44:55 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:44:55 INFO - 'blob_upload_branch': 'Firefox', 05:44:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:44:55 INFO - 'branch': 'Firefox', 05:44:55 INFO - 'buildbot_json_path': 'buildprops.json', 05:44:55 INFO - 'config_files': ('talos/linux_config.py',), 05:44:55 INFO - 'default_actions': ('clobber', 05:44:55 INFO - 'read-buildbot-config', 05:44:55 INFO - 'download-and-extract', 05:44:55 INFO - 'populate-webroot', 05:44:55 INFO - 'create-virtualenv', 05:44:55 INFO - 'install', 05:44:55 INFO - 'run-tests'), 05:44:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:44:55 INFO - 'download_minidump_stackwalk': True, 05:44:55 INFO - 'download_symbols': 'ondemand', 05:44:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:44:55 INFO - 'tooltool.py': '/tools/tooltool.py', 05:44:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:44:55 INFO - '/tools/misc-python/virtualenv.py')}, 05:44:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:44:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:44:55 INFO - 'installer_path': 'installer.exe', 05:44:55 INFO - 'log_level': 'info', 05:44:55 INFO - 'log_name': 'talos', 05:44:55 INFO - 'log_to_console': True, 05:44:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:44:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:44:55 INFO - 'opt_config_files': (), 05:44:55 INFO - 'pip_index': False, 05:44:55 INFO - 'sps_profile': False, 05:44:55 INFO - 'sps_profile_interval': 0, 05:44:55 INFO - 'suite': 'dromaeojs', 05:44:55 INFO - 'system_bits': '64', 05:44:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:44:55 INFO - 'title': 'talos-linux64-ix-066', 05:44:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:44:55 INFO - 'use_talos_json': True, 05:44:55 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:44:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:44:55 INFO - 'work_dir': 'build'} 05:44:55 INFO - ##### 05:44:55 INFO - ##### Running clobber step. 05:44:55 INFO - ##### 05:44:55 INFO - Running pre-action listener: _resource_record_pre_action 05:44:55 INFO - Running main action method: clobber 05:44:55 INFO - rmtree: /builds/slave/test-pgo/build 05:44:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:44:56 INFO - Running post-action listener: _resource_record_post_action 05:44:56 INFO - ##### 05:44:56 INFO - ##### Running read-buildbot-config step. 05:44:56 INFO - ##### 05:44:56 INFO - Running pre-action listener: _resource_record_pre_action 05:44:56 INFO - Running main action method: read_buildbot_config 05:44:56 INFO - Using buildbot properties: 05:44:56 INFO - { 05:44:56 INFO - "properties": { 05:44:56 INFO - "buildnumber": 121, 05:44:56 INFO - "product": "firefox", 05:44:56 INFO - "script_repo_revision": "production", 05:44:56 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:44:56 INFO - "repository": "", 05:44:56 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:44:56 INFO - "buildid": "20151126030226", 05:44:56 INFO - "slavename": "talos-linux64-ix-066", 05:44:56 INFO - "stage_platform": "linux64-pgo", 05:44:56 INFO - "basedir": "/builds/slave/test-pgo", 05:44:56 INFO - "nightly_build": "True", 05:44:56 INFO - "project": "", 05:44:56 INFO - "platform": "ubuntu64_hw", 05:44:56 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:44:56 INFO - "slavebuilddir": "test", 05:44:56 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:44:56 INFO - "branch": "mozilla-central", 05:44:56 INFO - "repo_path": "mozilla-central", 05:44:56 INFO - "pgo_build": "True", 05:44:56 INFO - "builduid": "aa5beced8ebf4e78a6824c7503d6afe1", 05:44:56 INFO - "revision": "c321d84038519dcf1670d59fd2c5c00ad8a85a55" 05:44:56 INFO - }, 05:44:56 INFO - "sourcestamp": { 05:44:56 INFO - "repository": "", 05:44:56 INFO - "hasPatch": false, 05:44:56 INFO - "project": "", 05:44:56 INFO - "branch": "mozilla-central-linux64-pgo-talos", 05:44:56 INFO - "changes": [ 05:44:56 INFO - { 05:44:56 INFO - "category": null, 05:44:56 INFO - "files": [ 05:44:56 INFO - { 05:44:56 INFO - "url": null, 05:44:56 INFO - "name": "https://queue.taskcluster.net/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 05:44:56 INFO - } 05:44:56 INFO - ], 05:44:56 INFO - "repository": "", 05:44:56 INFO - "rev": "c321d84038519dcf1670d59fd2c5c00ad8a85a55", 05:44:56 INFO - "who": "sendchange", 05:44:56 INFO - "when": 1448545434, 05:44:56 INFO - "number": 6710818, 05:44:56 INFO - "comments": "", 05:44:56 INFO - "project": "", 05:44:56 INFO - "at": "Thu 26 Nov 2015 05:43:54", 05:44:56 INFO - "branch": "mozilla-central-linux64-pgo-talos", 05:44:56 INFO - "revlink": "", 05:44:56 INFO - "properties": [ 05:44:56 INFO - [ 05:44:56 INFO - "buildid", 05:44:56 INFO - "20151126030226", 05:44:56 INFO - "Change" 05:44:56 INFO - ], 05:44:56 INFO - [ 05:44:56 INFO - "builduid", 05:44:56 INFO - "aa5beced8ebf4e78a6824c7503d6afe1", 05:44:56 INFO - "Change" 05:44:56 INFO - ], 05:44:56 INFO - [ 05:44:56 INFO - "nightly_build", 05:44:56 INFO - "True", 05:44:56 INFO - "Change" 05:44:56 INFO - ], 05:44:56 INFO - [ 05:44:56 INFO - "pgo_build", 05:44:56 INFO - "True", 05:44:56 INFO - "Change" 05:44:56 INFO - ] 05:44:56 INFO - ], 05:44:56 INFO - "revision": "c321d84038519dcf1670d59fd2c5c00ad8a85a55" 05:44:56 INFO - } 05:44:56 INFO - ], 05:44:56 INFO - "revision": "c321d84038519dcf1670d59fd2c5c00ad8a85a55" 05:44:56 INFO - } 05:44:56 INFO - } 05:44:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 05:44:56 INFO - Running post-action listener: _resource_record_post_action 05:44:56 INFO - ##### 05:44:56 INFO - ##### Running download-and-extract step. 05:44:56 INFO - ##### 05:44:56 INFO - Running pre-action listener: _resource_record_pre_action 05:44:56 INFO - Running main action method: download_and_extract 05:44:56 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:56 INFO - https://queue.taskcluster.net/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:44:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/test_packages.json 05:44:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/test_packages.json 05:44:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:44:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:44:56 INFO - Downloaded 1302 bytes. 05:44:56 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:44:56 INFO - Using the following test package requirements: 05:44:56 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:44:56 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:44:56 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:44:56 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:44:56 INFO - u'jsshell-linux-x86_64.zip'], 05:44:56 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:44:56 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:44:56 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:44:56 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:44:56 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:44:56 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:44:56 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:44:56 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:44:56 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:44:56 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:44:56 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:44:56 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:44:56 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:56 INFO - https://queue.taskcluster.net/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:44:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:44:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:44:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:44:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:44:58 INFO - Downloaded 22754498 bytes. 05:44:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:44:58 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:44:58 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:44:59 INFO - Return code: 0 05:44:59 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:59 INFO - https://queue.taskcluster.net/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:44:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:44:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:44:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:44:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:45:00 INFO - Downloaded 22754498 bytes. 05:45:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:45:00 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:45:00 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:45:01 INFO - Return code: 0 05:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:01 INFO - https://queue.taskcluster.net/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:45:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:45:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:45:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:45:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:45:03 INFO - Downloaded 11131717 bytes. 05:45:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 05:45:03 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:45:03 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 05:45:03 INFO - Return code: 0 05:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:03 INFO - https://queue.taskcluster.net/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:45:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:45:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:45:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:45:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:45:05 INFO - Downloaded 58391864 bytes. 05:45:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:45:05 INFO - mkdir: /builds/slave/test-pgo/properties 05:45:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:45:05 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:45:05 INFO - Contents: 05:45:05 INFO - build_url:https://queue.taskcluster.net/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:45:05 INFO - Running post-action listener: _resource_record_post_action 05:45:05 INFO - Running post-action listener: set_extra_try_arguments 05:45:05 INFO - ##### 05:45:05 INFO - ##### Running populate-webroot step. 05:45:05 INFO - ##### 05:45:05 INFO - Running pre-action listener: _resource_record_pre_action 05:45:05 INFO - Running main action method: populate_webroot 05:45:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:45:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:45:05 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 05:45:05 INFO - '../fennec_ids.txt'], 05:45:05 INFO - 'tests': ['tcheck2']}, 05:45:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:45:05 INFO - '--tppagecycles', 05:45:05 INFO - '7'], 05:45:05 INFO - 'tests': ['tsvgm']}}, 05:45:05 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:45:05 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:45:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:45:05 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:45:05 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:45:05 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:45:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:45:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:05 INFO - 'talos_options': ['--e10s'], 05:45:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:45:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:05 INFO - 'tests': ['damp', 'tps']}, 05:45:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:05 INFO - 'talos_options': ['--e10s'], 05:45:05 INFO - 'tests': ['damp', 'tps']}, 05:45:05 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:45:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:45:05 INFO - 'other': {'tests': ['a11yr', 05:45:05 INFO - 'ts_paint', 05:45:05 INFO - 'tpaint', 05:45:05 INFO - 'sessionrestore', 05:45:05 INFO - 'sessionrestore_no_auto_restore']}, 05:45:05 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:45:05 INFO - 'tests': ['a11yr', 05:45:05 INFO - 'ts_paint', 05:45:05 INFO - 'tpaint', 05:45:05 INFO - 'sessionrestore', 05:45:05 INFO - 'sessionrestore_no_auto_restore']}, 05:45:05 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:45:05 INFO - 'tests': ['a11yr', 05:45:05 INFO - 'ts_paint', 05:45:05 INFO - 'tpaint', 05:45:05 INFO - 'sessionrestore', 05:45:05 INFO - 'sessionrestore_no_auto_restore']}, 05:45:05 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:45:05 INFO - 'tests': ['a11yr', 05:45:05 INFO - 'ts_paint', 05:45:05 INFO - 'tpaint', 05:45:05 INFO - 'sessionrestore', 05:45:05 INFO - 'sessionrestore_no_auto_restore']}, 05:45:05 INFO - 'other_l64': {'tests': ['a11yr', 05:45:05 INFO - 'ts_paint', 05:45:05 INFO - 'tpaint', 05:45:05 INFO - 'sessionrestore', 05:45:05 INFO - 'sessionrestore_no_auto_restore']}, 05:45:05 INFO - 'other_nol64': {'tests': ['a11yr', 05:45:05 INFO - 'ts_paint', 05:45:05 INFO - 'tpaint', 05:45:05 INFO - 'sessionrestore', 05:45:05 INFO - 'sessionrestore_no_auto_restore']}, 05:45:05 INFO - 'svgr': {'tests': ['tsvgx', 05:45:05 INFO - 'tsvgr_opacity', 05:45:05 INFO - 'tart', 05:45:05 INFO - 'tscrollx', 05:45:05 INFO - 'cart']}, 05:45:05 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:45:05 INFO - 'tests': ['tsvgx', 05:45:05 INFO - 'tsvgr_opacity', 05:45:05 INFO - 'tart', 05:45:05 INFO - 'tscrollx', 05:45:05 INFO - 'cart']}, 05:45:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:05 INFO - 'tests': ['tp5o']}, 05:45:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:05 INFO - 'talos_options': ['--e10s'], 05:45:05 INFO - 'tests': ['tp5o']}, 05:45:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:05 INFO - 'talos_options': ['--xperf_path', 05:45:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:45:05 INFO - 'tests': ['tp5n']}, 05:45:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:05 INFO - 'talos_options': ['--e10s', 05:45:05 INFO - '--xperf_path', 05:45:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:45:05 INFO - 'tests': ['tp5n']}}, 05:45:05 INFO - 'talos.zip': {'path': '', 05:45:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:45:05 INFO - Running post-action listener: _resource_record_post_action 05:45:05 INFO - ##### 05:45:05 INFO - ##### Running create-virtualenv step. 05:45:05 INFO - ##### 05:45:05 INFO - Running pre-action listener: _resource_record_pre_action 05:45:05 INFO - Running main action method: create_virtualenv 05:45:05 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:45:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 05:45:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:45:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:45:05 INFO - Using real prefix '/usr' 05:45:05 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:45:06 INFO - Installing distribute.............................................................................................................................................................................................done. 05:45:08 INFO - Installing pip.................done. 05:45:08 INFO - Return code: 0 05:45:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:45: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')]} 05:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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')]} 05:45:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2429030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26567d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e5640>, '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 0x26e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e69d0>, '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': '004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:45: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', 'pip>=1.5'] in /builds/slave/test-pgo/build 05:45: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 pip>=1.5 05:45:08 INFO - Using env: {'DISPLAY': ':0', 05:45:08 INFO - 'HOME': '/home/cltbld', 05:45:08 INFO - 'LANG': 'en_US.UTF-8', 05:45:08 INFO - 'LANGUAGE': 'en_US:en', 05:45:08 INFO - 'LOGNAME': 'cltbld', 05:45:08 INFO - 'MAIL': '/var/mail/cltbld', 05:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:08 INFO - 'MOZ_NO_REMOTE': '1', 05:45:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:08 INFO - 'NO_EM_RESTART': '1', 05:45:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:45:08 INFO - 'PWD': '/builds/slave/test-pgo', 05:45:08 INFO - 'SHELL': '/bin/bash', 05:45:08 INFO - 'SHLVL': '1', 05:45:08 INFO - 'TERM': 'linux', 05:45:08 INFO - 'TMOUT': '86400', 05:45:08 INFO - 'USER': 'cltbld', 05:45:08 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014', 05:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:08 INFO - '_': '/tools/buildbot/bin/python'} 05:45:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:08 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:45:08 INFO - Cleaning up... 05:45:08 INFO - Return code: 0 05:45:08 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:45: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')]} 05:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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')]} 05:45:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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', '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 0x2429030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26567d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e5640>, '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 0x26e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e69d0>, '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': '004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:45: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', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 05:45: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 psutil>=0.7.1 05:45:08 INFO - Using env: {'DISPLAY': ':0', 05:45:08 INFO - 'HOME': '/home/cltbld', 05:45:08 INFO - 'LANG': 'en_US.UTF-8', 05:45:08 INFO - 'LANGUAGE': 'en_US:en', 05:45:08 INFO - 'LOGNAME': 'cltbld', 05:45:08 INFO - 'MAIL': '/var/mail/cltbld', 05:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:08 INFO - 'MOZ_NO_REMOTE': '1', 05:45:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:08 INFO - 'NO_EM_RESTART': '1', 05:45:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:45:08 INFO - 'PWD': '/builds/slave/test-pgo', 05:45:08 INFO - 'SHELL': '/bin/bash', 05:45:08 INFO - 'SHLVL': '1', 05:45:08 INFO - 'TERM': 'linux', 05:45:08 INFO - 'TMOUT': '86400', 05:45:08 INFO - 'USER': 'cltbld', 05:45:08 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014', 05:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:08 INFO - '_': '/tools/buildbot/bin/python'} 05:45:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:08 INFO - Downloading/unpacking psutil>=0.7.1 05:45: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 05:45: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 05:45: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 05:45: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 05:45: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 05:45: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 05:45:10 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:45:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:45:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:45:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:45:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:45:10 INFO - Installing collected packages: psutil 05:45:10 INFO - Running setup.py install for psutil 05:45:10 INFO - building 'psutil._psutil_linux' extension 05:45:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:45:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:45:10 INFO - building 'psutil._psutil_posix' extension 05:45:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:45:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:45:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:45:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:45:10 INFO - Successfully installed psutil 05:45:10 INFO - Cleaning up... 05:45:11 INFO - Return code: 0 05:45:11 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:45:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:11 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2429030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26567d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e5640>, '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 0x26e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e69d0>, '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': '004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:45:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 05:45:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:45:11 INFO - Using env: {'DISPLAY': ':0', 05:45:11 INFO - 'HOME': '/home/cltbld', 05:45:11 INFO - 'LANG': 'en_US.UTF-8', 05:45:11 INFO - 'LANGUAGE': 'en_US:en', 05:45:11 INFO - 'LOGNAME': 'cltbld', 05:45:11 INFO - 'MAIL': '/var/mail/cltbld', 05:45:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:11 INFO - 'MOZ_NO_REMOTE': '1', 05:45:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:11 INFO - 'NO_EM_RESTART': '1', 05:45:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:45:11 INFO - 'PWD': '/builds/slave/test-pgo', 05:45:11 INFO - 'SHELL': '/bin/bash', 05:45:11 INFO - 'SHLVL': '1', 05:45:11 INFO - 'TERM': 'linux', 05:45:11 INFO - 'TMOUT': '86400', 05:45:11 INFO - 'USER': 'cltbld', 05:45:11 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014', 05:45:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:11 INFO - '_': '/tools/buildbot/bin/python'} 05:45:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:45:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:45:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:45:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:45:12 INFO - Installing collected packages: mozsystemmonitor 05:45:12 INFO - Running setup.py install for mozsystemmonitor 05:45:13 INFO - Successfully installed mozsystemmonitor 05:45:13 INFO - Cleaning up... 05:45:13 INFO - Return code: 0 05:45:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:45: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')]} 05:45:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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')]} 05:45:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:13 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 0x2429030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26567d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e5640>, '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 0x26e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e69d0>, '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': '004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:45:13 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 05:45:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:45:13 INFO - Using env: {'DISPLAY': ':0', 05:45:13 INFO - 'HOME': '/home/cltbld', 05:45:13 INFO - 'LANG': 'en_US.UTF-8', 05:45:13 INFO - 'LANGUAGE': 'en_US:en', 05:45:13 INFO - 'LOGNAME': 'cltbld', 05:45:13 INFO - 'MAIL': '/var/mail/cltbld', 05:45:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:13 INFO - 'MOZ_NO_REMOTE': '1', 05:45:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:13 INFO - 'NO_EM_RESTART': '1', 05:45:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:45:13 INFO - 'PWD': '/builds/slave/test-pgo', 05:45:13 INFO - 'SHELL': '/bin/bash', 05:45:13 INFO - 'SHLVL': '1', 05:45:13 INFO - 'TERM': 'linux', 05:45:13 INFO - 'TMOUT': '86400', 05:45:13 INFO - 'USER': 'cltbld', 05:45:13 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014', 05:45:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:13 INFO - '_': '/tools/buildbot/bin/python'} 05:45:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:13 INFO - Downloading/unpacking blobuploader==1.2.4 05:45:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:14 INFO - Downloading blobuploader-1.2.4.tar.gz 05:45:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:45:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:45:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:45:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:45:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:15 INFO - Downloading docopt-0.6.1.tar.gz 05:45:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:45:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:45:15 INFO - Installing collected packages: blobuploader, requests, docopt 05:45:15 INFO - Running setup.py install for blobuploader 05:45:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:45:15 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:45:15 INFO - Running setup.py install for requests 05:45:16 INFO - Running setup.py install for docopt 05:45:16 INFO - Successfully installed blobuploader requests docopt 05:45:16 INFO - Cleaning up... 05:45:16 INFO - Return code: 0 05:45:16 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:45: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')]} 05:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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')]} 05:45:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:16 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 0x2429030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26567d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e5640>, '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 0x26e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e69d0>, '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': '004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:45:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 05:45:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:45:16 INFO - Using env: {'DISPLAY': ':0', 05:45:16 INFO - 'HOME': '/home/cltbld', 05:45:16 INFO - 'LANG': 'en_US.UTF-8', 05:45:16 INFO - 'LANGUAGE': 'en_US:en', 05:45:16 INFO - 'LOGNAME': 'cltbld', 05:45:16 INFO - 'MAIL': '/var/mail/cltbld', 05:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:16 INFO - 'MOZ_NO_REMOTE': '1', 05:45:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:16 INFO - 'NO_EM_RESTART': '1', 05:45:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:45:16 INFO - 'PWD': '/builds/slave/test-pgo', 05:45:16 INFO - 'SHELL': '/bin/bash', 05:45:16 INFO - 'SHLVL': '1', 05:45:16 INFO - 'TERM': 'linux', 05:45:16 INFO - 'TMOUT': '86400', 05:45:16 INFO - 'USER': 'cltbld', 05:45:16 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014', 05:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:16 INFO - '_': '/tools/buildbot/bin/python'} 05:45:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:45:16 INFO - Running setup.py (path:/tmp/pip-7eziux-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:45:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:45:16 INFO - Running setup.py (path:/tmp/pip-bHps4h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:45:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:45:16 INFO - Running setup.py (path:/tmp/pip-Cr7pGH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:45:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:45:16 INFO - Running setup.py (path:/tmp/pip-nAwYGk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:45:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:45:16 INFO - Running setup.py (path:/tmp/pip-CThN9h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:45:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:45:16 INFO - Running setup.py (path:/tmp/pip-oU4t10-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:45:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:45:16 INFO - Running setup.py (path:/tmp/pip-GLiAS9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:45:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:45:17 INFO - Running setup.py (path:/tmp/pip-KtRZg7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:45:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:45:17 INFO - Running setup.py (path:/tmp/pip-4Y9LhO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:45:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:45:17 INFO - Running setup.py (path:/tmp/pip-A2fWZW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:45:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:45:17 INFO - Running setup.py (path:/tmp/pip-wSXdOd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:45:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:45:17 INFO - Running setup.py (path:/tmp/pip-spNM1F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:45:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:45:17 INFO - Running setup.py (path:/tmp/pip-A8KbeB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:45:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:45:17 INFO - Running setup.py (path:/tmp/pip-MB6Y9P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:45:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:45:17 INFO - Running setup.py (path:/tmp/pip-uDnlr2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:45:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:45:17 INFO - Running setup.py (path:/tmp/pip-4RQ4ZZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:45:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:45:17 INFO - Running setup.py (path:/tmp/pip-bAG43u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:45:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:45:17 INFO - Running setup.py install for manifestparser 05:45:17 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:45:17 INFO - Running setup.py install for mozcrash 05:45:17 INFO - Running setup.py install for mozdebug 05:45:18 INFO - Running setup.py install for mozdevice 05:45:18 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:45:18 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:45:18 INFO - Running setup.py install for mozfile 05:45:18 INFO - Running setup.py install for mozhttpd 05:45:18 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:45:18 INFO - Running setup.py install for mozinfo 05:45:18 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:45:18 INFO - Running setup.py install for mozInstall 05:45:18 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:45:18 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:45:18 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:45:18 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:45:18 INFO - Running setup.py install for mozleak 05:45:18 INFO - Running setup.py install for mozlog 05:45:18 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:45:18 INFO - Running setup.py install for moznetwork 05:45:18 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:45:18 INFO - Running setup.py install for mozprocess 05:45:18 INFO - Running setup.py install for mozprofile 05:45:19 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:45:19 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:45:19 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:45:19 INFO - Running setup.py install for mozrunner 05:45:19 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:45:19 INFO - Running setup.py install for mozscreenshot 05:45:19 INFO - Running setup.py install for moztest 05:45:19 INFO - Running setup.py install for mozversion 05:45:19 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:45:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:45:19 INFO - Cleaning up... 05:45:19 INFO - Return code: 0 05:45:19 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:19 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 0x2429030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26567d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e5640>, '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 0x26e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e69d0>, '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': '004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:45:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 05:45:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:45:19 INFO - Using env: {'DISPLAY': ':0', 05:45:19 INFO - 'HOME': '/home/cltbld', 05:45:19 INFO - 'LANG': 'en_US.UTF-8', 05:45:19 INFO - 'LANGUAGE': 'en_US:en', 05:45:19 INFO - 'LOGNAME': 'cltbld', 05:45:19 INFO - 'MAIL': '/var/mail/cltbld', 05:45:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:19 INFO - 'MOZ_NO_REMOTE': '1', 05:45:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:19 INFO - 'NO_EM_RESTART': '1', 05:45:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:45:19 INFO - 'PWD': '/builds/slave/test-pgo', 05:45:19 INFO - 'SHELL': '/bin/bash', 05:45:19 INFO - 'SHLVL': '1', 05:45:19 INFO - 'TERM': 'linux', 05:45:19 INFO - 'TMOUT': '86400', 05:45:19 INFO - 'USER': 'cltbld', 05:45:19 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014', 05:45:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:19 INFO - '_': '/tools/buildbot/bin/python'} 05:45:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:45:19 INFO - Running setup.py (path:/tmp/pip-f5CWuU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 05:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:45:19 INFO - Running setup.py (path:/tmp/pip-bdz4E6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:45:19 INFO - Running setup.py (path:/tmp/pip-y8sA1i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 05:45:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:45:19 INFO - Running setup.py (path:/tmp/pip-AQhdq9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:45:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:45:20 INFO - Running setup.py (path:/tmp/pip-EYogNG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 05:45:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:45:20 INFO - Running setup.py (path:/tmp/pip-J64pHZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 05:45:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:45:20 INFO - Running setup.py (path:/tmp/pip-qqvR5x-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 05:45:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:45:20 INFO - Running setup.py (path:/tmp/pip-gYhacX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 05:45:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:45:20 INFO - Running setup.py (path:/tmp/pip-tmvILW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 05:45:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:45:20 INFO - Running setup.py (path:/tmp/pip-IPB65U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:45:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:45:20 INFO - Running setup.py (path:/tmp/pip-8MEaJo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 05:45:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:45:20 INFO - Running setup.py (path:/tmp/pip-jTQZS0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 05:45:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:45:20 INFO - Running setup.py (path:/tmp/pip-_BcneA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 05:45:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:45:20 INFO - Running setup.py (path:/tmp/pip-b62rZc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 05:45:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:45:20 INFO - Running setup.py (path:/tmp/pip-WdxZuQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 05:45:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:45:20 INFO - Running setup.py (path:/tmp/pip-Y6USrx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 05:45:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:45:20 INFO - Running setup.py (path:/tmp/pip-UxNHDI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 05:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:45: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.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 05:45: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 mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:45:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:45: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 05:45: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 05:45: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 05:45: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 05:45: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 05:45: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 05:45:22 INFO - Downloading blessings-1.5.1.tar.gz 05:45:22 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 05:45:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:45:22 INFO - Installing collected packages: blessings 05:45:22 INFO - Running setup.py install for blessings 05:45:22 INFO - Successfully installed blessings 05:45:22 INFO - Cleaning up... 05:45:22 INFO - Return code: 0 05:45:22 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:45:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:45:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:45:23 INFO - Reading from file tmpfile_stdout 05:45:23 INFO - Current package versions: 05:45:23 INFO - argparse == 1.2.1 05:45:23 INFO - blessings == 1.5.1 05:45:23 INFO - blobuploader == 1.2.4 05:45:23 INFO - docopt == 0.6.1 05:45:23 INFO - manifestparser == 1.1 05:45:23 INFO - mozInstall == 1.12 05:45:23 INFO - mozcrash == 0.16 05:45:23 INFO - mozdebug == 0.1 05:45:23 INFO - mozdevice == 0.47 05:45:23 INFO - mozfile == 1.2 05:45:23 INFO - mozhttpd == 0.7 05:45:23 INFO - mozinfo == 0.9 05:45:23 INFO - mozleak == 0.1 05:45:23 INFO - mozlog == 3.1 05:45:23 INFO - moznetwork == 0.27 05:45:23 INFO - mozprocess == 0.22 05:45:23 INFO - mozprofile == 0.27 05:45:23 INFO - mozrunner == 6.11 05:45:23 INFO - mozscreenshot == 0.1 05:45:23 INFO - mozsystemmonitor == 0.0 05:45:23 INFO - moztest == 0.7 05:45:23 INFO - mozversion == 1.4 05:45:23 INFO - psutil == 3.1.1 05:45:23 INFO - requests == 1.2.3 05:45:23 INFO - wsgiref == 0.1.2 05:45:23 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:23 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 0x2429030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26567d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e5640>, '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 0x26e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e69d0>, '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': '004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:45:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 05:45:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:45:23 INFO - Using env: {'DISPLAY': ':0', 05:45:23 INFO - 'HOME': '/home/cltbld', 05:45:23 INFO - 'LANG': 'en_US.UTF-8', 05:45:23 INFO - 'LANGUAGE': 'en_US:en', 05:45:23 INFO - 'LOGNAME': 'cltbld', 05:45:23 INFO - 'MAIL': '/var/mail/cltbld', 05:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:23 INFO - 'MOZ_NO_REMOTE': '1', 05:45:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:23 INFO - 'NO_EM_RESTART': '1', 05:45:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:45:23 INFO - 'PWD': '/builds/slave/test-pgo', 05:45:23 INFO - 'SHELL': '/bin/bash', 05:45:23 INFO - 'SHLVL': '1', 05:45:23 INFO - 'TERM': 'linux', 05:45:23 INFO - 'TMOUT': '86400', 05:45:23 INFO - 'USER': 'cltbld', 05:45:23 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014', 05:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:23 INFO - '_': '/tools/buildbot/bin/python'} 05:45:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 05:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 05:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 05:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 05:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 05:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 05:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 05:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 05:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:45:23 INFO - Cleaning up... 05:45:23 INFO - Return code: 0 05:45:23 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:23 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 0x2429030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26567d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e5640>, '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 0x26e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e69d0>, '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': '004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:45:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 05:45:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:45:23 INFO - Using env: {'DISPLAY': ':0', 05:45:23 INFO - 'HOME': '/home/cltbld', 05:45:23 INFO - 'LANG': 'en_US.UTF-8', 05:45:23 INFO - 'LANGUAGE': 'en_US:en', 05:45:23 INFO - 'LOGNAME': 'cltbld', 05:45:23 INFO - 'MAIL': '/var/mail/cltbld', 05:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:23 INFO - 'MOZ_NO_REMOTE': '1', 05:45:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:23 INFO - 'NO_EM_RESTART': '1', 05:45:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:45:23 INFO - 'PWD': '/builds/slave/test-pgo', 05:45:23 INFO - 'SHELL': '/bin/bash', 05:45:23 INFO - 'SHLVL': '1', 05:45:23 INFO - 'TERM': 'linux', 05:45:23 INFO - 'TMOUT': '86400', 05:45:23 INFO - 'USER': 'cltbld', 05:45:23 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014', 05:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:23 INFO - '_': '/tools/buildbot/bin/python'} 05:45:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:23 INFO - Downloading/unpacking jsonschema 05:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:45:25 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 05:45:25 INFO - Downloading/unpacking functools32 (from jsonschema) 05:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:25 INFO - Downloading functools32-3.2.3-2.tar.gz 05:45:25 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:45:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:45:25 INFO - warning: no files found matching '*.txt' 05:45:25 INFO - no previously-included directories found matching 'build' 05:45:25 INFO - no previously-included directories found matching 'dist' 05:45:25 INFO - no previously-included directories found matching '.git*' 05:45:25 INFO - Installing collected packages: jsonschema, functools32 05:45:25 INFO - Running setup.py install for functools32 05:45:25 INFO - warning: no files found matching '*.txt' 05:45:25 INFO - no previously-included directories found matching 'build' 05:45:25 INFO - no previously-included directories found matching 'dist' 05:45:25 INFO - no previously-included directories found matching '.git*' 05:45:25 INFO - Successfully installed jsonschema functools32 05:45:25 INFO - Cleaning up... 05:45:25 INFO - Return code: 0 05:45:25 INFO - Running post-action listener: _resource_record_post_action 05:45:25 INFO - Running post-action listener: _start_resource_monitoring 05:45:25 INFO - Starting resource monitoring. 05:45:25 INFO - ##### 05:45:25 INFO - ##### Running install step. 05:45:25 INFO - ##### 05:45:25 INFO - Running pre-action listener: _resource_record_pre_action 05:45:25 INFO - Running main action method: install 05:45:25 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:45:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:45:25 INFO - Reading from file tmpfile_stdout 05:45:25 INFO - Detecting whether we're running mozinstall >=1.0... 05:45:25 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:45:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:45:25 INFO - Reading from file tmpfile_stdout 05:45:25 INFO - Output received: 05:45:25 INFO - Usage: mozinstall [options] installer 05:45:25 INFO - Options: 05:45:25 INFO - -h, --help show this help message and exit 05:45:25 INFO - -d DEST, --destination=DEST 05:45:25 INFO - Directory to install application into. [default: 05:45:25 INFO - "/builds/slave/test-pgo"] 05:45:25 INFO - --app=APP Application being installed. [default: firefox] 05:45:25 INFO - mkdir: /builds/slave/test-pgo/build/application 05:45:25 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 05:45:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 05:45:40 INFO - Reading from file tmpfile_stdout 05:45:40 INFO - Output received: 05:45:40 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:45:40 INFO - Running post-action listener: _resource_record_post_action 05:45:40 INFO - ##### 05:45:40 INFO - ##### Running run-tests step. 05:45:40 INFO - ##### 05:45:40 INFO - Running pre-action listener: _resource_record_pre_action 05:45:40 INFO - Running main action method: run_tests 05:45:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:45:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:45:40 INFO - Python 2.7.3 05:45:40 INFO - Return code: 0 05:45:40 INFO - grabbing minidump binary from tooltool 05:45:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:40 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 0x26e5640>, '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 0x26e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e69d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:45:40 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 05:45:40 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:45:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:45:40 INFO - Return code: 0 05:45:40 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:45:40 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:45:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:45:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:45:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:45:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:45:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --webServer localhost 05:45:40 INFO - Using env: {'DISPLAY': ':0', 05:45:40 INFO - 'HOME': '/home/cltbld', 05:45:40 INFO - 'LANG': 'en_US.UTF-8', 05:45:40 INFO - 'LANGUAGE': 'en_US:en', 05:45:40 INFO - 'LOGNAME': 'cltbld', 05:45:40 INFO - 'MAIL': '/var/mail/cltbld', 05:45:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:45:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:45:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:40 INFO - 'MOZ_NO_REMOTE': '1', 05:45:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:45:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:40 INFO - 'NO_EM_RESTART': '1', 05:45:40 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:45:40 INFO - 'PWD': '/builds/slave/test-pgo', 05:45:40 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:45:40 INFO - 'SHELL': '/bin/bash', 05:45:40 INFO - 'SHLVL': '1', 05:45:40 INFO - 'TERM': 'linux', 05:45:40 INFO - 'TMOUT': '86400', 05:45:40 INFO - 'USER': 'cltbld', 05:45:40 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014', 05:45:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:40 INFO - '_': '/tools/buildbot/bin/python'} 05:45:40 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost'] with output_timeout 3600 05:45:40 INFO - mozversion INFO | application_buildid: 20151126030226 05:45:40 INFO - mozversion INFO | application_changeset: c321d84038519dcf1670d59fd2c5c00ad8a85a55 05:45:40 INFO - mozversion INFO | application_display_name: Nightly 05:45:40 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:45:40 INFO - mozversion INFO | application_name: Firefox 05:45:40 INFO - mozversion INFO | application_remotingname: firefox 05:45:40 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 05:45:40 INFO - mozversion INFO | application_vendor: Mozilla 05:45:40 INFO - mozversion INFO | application_version: 45.0a1 05:45:40 INFO - mozversion INFO | platform_buildid: 20151126030226 05:45:40 INFO - mozversion INFO | platform_changeset: c321d84038519dcf1670d59fd2c5c00ad8a85a55 05:45:40 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 05:45:40 INFO - mozversion INFO | platform_version: 45.0a1 05:45:40 INFO - 2015-11-26 05:45:40,891 DEBUG : using testdate: 1448545540 05:45:40 INFO - 2015-11-26 05:45:40,891 DEBUG : actual date: 1448545540 05:45:40 INFO - 2015-11-26 05:45:40,981 INFO : starting webserver on 'localhost:40605' 05:45:40 INFO - 2015-11-26 05:45:40,982 INFO : Starting test suite talos-linux64-ix-066 05:45:40 INFO - 2015-11-26 05:45:40,983 INFO : Starting test dromaeo_css 05:45:40 INFO - 2015-11-26 05:45:40,983 DEBUG : operating with platform_type : linux_ 05:45:40 INFO - 2015-11-26 05:45:40,983 INFO : Initialising browser for dromaeo_css test... 05:45:40 INFO - 2015-11-26 05:45:40,998 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzhCkNj/profile http://localhost:40605/getInfo.html 05:45:45 INFO - 2015-11-26 05:45:45,201 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:45:45 INFO - 2015-11-26 05:45:45,201 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:45:45 INFO - 2015-11-26 05:45:45,201 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:45:45 INFO - 2015-11-26 05:45:45,201 DEBUG : BROWSER_OUTPUT: __metrics 05:45:45 INFO - 2015-11-26 05:45:45,386 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:45:46 INFO - 2015-11-26 05:45:46,619 INFO : Browser initialized. 05:45:46 INFO - 2015-11-26 05:45:46,619 INFO : Running cycle 1/1 for dromaeo_css test... 05:45:46 INFO - 2015-11-26 05:45:46,619 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzhCkNj/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:45:58 INFO - 2015-11-26 05:45:58,392 DEBUG : BROWSER_OUTPUT: RSS: Main: 138903552 05:45:58 INFO - 2015-11-26 05:45:58,392 DEBUG : BROWSER_OUTPUT: 05:46:49 INFO - 2015-11-26 05:46:49,097 DEBUG : BROWSER_OUTPUT: 1448545609093 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:48:58 INFO - 2015-11-26 05:48:58,988 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40605/tests/dromaeo/cssquery-ext.html) 05:49:00 INFO - 2015-11-26 05:49:00,059 DEBUG : BROWSER_OUTPUT: RSS: Main: 247709696 05:49:00 INFO - 2015-11-26 05:49:00,059 DEBUG : BROWSER_OUTPUT: 05:52:02 INFO - 2015-11-26 05:52:02,252 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/dromaeo/cssquery-ext.html (next: http://localhost:40605/tests/dromaeo/cssquery-jquery.html) 05:52:02 INFO - 2015-11-26 05:52:02,825 DEBUG : BROWSER_OUTPUT: RSS: Main: 224612352 05:52:02 INFO - 2015-11-26 05:52:02,825 DEBUG : BROWSER_OUTPUT: 05:55:04 INFO - 2015-11-26 05:55:04,661 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40605/tests/dromaeo/cssquery-mootools.html) 05:55:05 INFO - 2015-11-26 05:55:05,227 DEBUG : BROWSER_OUTPUT: RSS: Main: 224116736 05:55:05 INFO - 2015-11-26 05:55:05,227 DEBUG : BROWSER_OUTPUT: 05:58:07 INFO - 2015-11-26 05:58:07,024 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40605/tests/dromaeo/cssquery-prototype.html) 05:58:07 INFO - 2015-11-26 05:58:07,605 DEBUG : BROWSER_OUTPUT: RSS: Main: 199270400 05:58:07 INFO - 2015-11-26 05:58:07,605 DEBUG : BROWSER_OUTPUT: 06:01:10 INFO - 2015-11-26 06:01:10,061 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40605/tests/dromaeo/cssquery-yui.html) 06:01:10 INFO - 2015-11-26 06:01:10,627 DEBUG : BROWSER_OUTPUT: RSS: Main: 202526720 06:01:10 INFO - 2015-11-26 06:01:10,627 DEBUG : BROWSER_OUTPUT: 06:04:13 INFO - 2015-11-26 06:04:13,202 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/dromaeo/cssquery-yui.html (next: http://localhost:40605/tests/dromaeo/cssquery-dojo.html) 06:04:13 INFO - 2015-11-26 06:04:13,465 DEBUG : BROWSER_OUTPUT: RSS: Main: 240795648 06:04:13 INFO - 2015-11-26 06:04:13,465 DEBUG : BROWSER_OUTPUT: 06:04:13 INFO - 2015-11-26 06:04:13,466 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:04:13 INFO - 2015-11-26 06:04:13,466 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:04:13 INFO - 2015-11-26 06:04:13,466 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:04:13 INFO - 2015-11-26 06:04:13,466 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:04:13 INFO - 2015-11-26 06:04:13,466 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3180.2049487628087;3236.1909522619344;3277.1151789016967;3284.8850290239848;3321.7176539994107;5269.6574722643;5389.649672771267;5396.8920621587595;5462.945370546293;5483.616146869721;5439.401665816757;5464.972675136619;5506.944930550693;5553.16702494626;5578.804741834027;4897.314375987356;5019.247112933052;5021.924671129936;5051.469595692436;5064.16441287189;4889.33993910821;5061.5950723942;5064.848054558369;5106.719130447824;5133.307003554528;4929.285253638221;4934;5029.7736601853;5062.139436295838;5064.873378165538;5018.322526458955;5022.59819214462;5025.522575355335;5025.924611130836;5050.595952323842;4804.6876952997945;4926.704397736147;4984.27727979441;4984.327115839388;5019.4227663818765;5231.24146998683;5296.3379577552805;5306.230596563515;5309.973450132763;5343.064963631387;5957.970210148965;5988.251468566429;5988.610740301867;5994.730237139339;6007.729652165619;5857.912131318034;5978.491828194608;5989.9101513477335;5992.370801065869;6003.0695242237525;5503.724813759296;5551.084071128259;5581.441855814386;5627.6904770237625;5630.746616402272;6002.219711437485;6003.039513677791;6003.969980150158;6022.879542409128;6072.848178795477;5573.498385145356;5841.737121829443;5843.912341314884;5845.649261044351;5906.468417842415;5815.273090863642;5866.6186697864505;5875.4124587541755;5889.263842019747;5904.202932604129;5989.2812862456785;5989.760409583655;6003.849903752353;6014.248218972629;6051.485623721945;5982.641041537521;5990.820275391745;5994.280686317583;5995;5997.100434934795;4427.535108813592;4510.661700372485;4526.32105184225;4556.589906908394;4606.907861842811;4007.6793856491413;4047.0691740899756;4078.021274894004;4083.407905853694;4104.8768536943935;5517.862053448615;5520.751566179451;5539.390667026624;5568.192612071227;5585.106382978704;5734.483896449339;5872.911906321408;5892.027815410409;5894.410558944071;5913.940860591339;5464.0711079116645;5474.507294343528;5475.835724928259;5494.203340515603;5647.350554686182;5073.518015788494;5082.059818933509;5089.516495932954;5096.592272618182;5121.769520371592;5868.911966320508;5926.822195334147;5958.076498142795;5995.400459954144;6077.848053798691;5843.707814609167;5921.61509501881;5968.253968253968;5979.282486101695;5980.5215582754145;6172.382761723792;6222.408871157233;6231.594946328474;6238.875222495615;6271.310155882758;5708.086706127;5918.940810592011;5921.3190483094995;5926.67403292823;5949.494292984971;5794.3626201117;5805.158252053513;5884.0585506318785;5892.970535147297;5962.55280853929;5805.245318108618;5808.5062769665055;5811.4479124483105;5813.476787089182;5820.243368362255;5657.632253903483;5712.171735098471;5722.456366645162;5725.398833122629;5728.971355143198;5527.198556209408;5625.718714064198;5643.209950607001;5656.2929633795775;5698.458646428583;5770.653760774367;5799.333076696234;5831.7667293307795;5832.300123985149;5842.883142337215;5728.054870946247;5729.742161602655;5732.971335143297;5734.913976290442;5791.53667706574 06:04:13 INFO - 2015-11-26 06:04:13,466 DEBUG : BROWSER_OUTPUT: |1;ext.html;567.2738894215403;591.6834493545954;610.8595023144674;616.3158893628072;622.337210870423;30295.24261893451;31131.221719457026;31137.221569460773;31160.688393116063;31178.376432471337;32372.19069523263;32805.52375143118;40643.79678101613;40900.38649420254;41148.794256028756;8739.694110706127;8981.14679105484;8987.910120898772;9000.05499422561;9008.594613242387;7830.882536761953;8727.170918762708;8856.51289179095;8858.379913406065;8864.689735859247;7797.220277972214;10552.736181595445;10834.295770774914;10840.78318433631;10845.186611004166;10376.22178336624;10481.21390895682;10494.68515944523;10498.422586757726;10508.894911050906;7770.49491783038;7965.44241903067;10199.286049976505;10375.84436233457;10477.58089676412;38893;39153;39209;39222.41166382506;39238.80380598079;3164.9208769780707;3204.903852884417;3204.96795032049;3291.96708032919;3339.1985923378456;29925.700742992725;30253.24366890823;30789.846050769604;30804.8459757702;31382;3577.695895848856;3606.7294952878638;3606.9098272543397;3669.3578623741005;3730.1420673245034;260557;261199;262616;262896;263576;56191.43808561944;56912.71543642255;57015.429845701015;57031.71484142553;57067.71466142643;59633.70183149143;59699.40300596938;59736.70131649314;59844.70077649671;59906.400935990954;13958;14288.785668214985;14298.57104286873;14322.57032289033;14323.57029289123;10726.73183170427;10763;10768.3538987661;10775.299605525615;10777.730556736144;18736;19001.429957101034;19223.51941201481;19243.61512769737;19281.22875084981;7773.727919522789;7901.407394445439;8006;8134.267915887599;8172.6322315495945;924.7410724997012;933.5145724223365;948.2366694810744;1067.4289255248405;1085.4626959655022;27674.723252767617;28079.859600701864;28120.85939570289;28259;28289.29276768056;950.6482601437513;1011.3224139826334;1028.321307936773;1036.4196050211906;1142.2118738070872;13324.333783310796;13639.522616708566;13798.448062077405;13824.723505529833;13904.304784760721;303300;336604.7055764993;343888;345824;348474;38892.611073889464;48545.75727121413;48926;48987.75506122518;49060.75469622629;1299.0711641176604;1334.4795529743408;1337.2348499980128;1342.1946831900782;1342.234926092118;25074.3731406709;25864.482710346063;26609.20172394754;28822.279443014093;30673.386532269677;46305.305420418044;46672;46810.29784553302;46906.76546617245;46939.76530117328;267683;268504;268898;269845;270577;31769.71108900269;31868.362632747678;32054.679453205168;32146;32204.19489512687;40424;40800.775976720746;41052.79473602613;41384.37923431211;41501;33479.83260083684;34426;36511.45232821457;36896.26207475889;37222.62777372191;34513.13717157091;36020;36691.63308366882;36828.26343473062;36929.44605830861;32186.83906580546;32902.506462402605;33180.66819331776;33269;33296.66703332936;29234.853825730734;30509.847450762605;30938.381232374777;31295.217619559695;32821.50767738438;24825.852924878942;24926.501469970863;25098;25419.36451588725;25450.872745636152 06:04:13 INFO - 2015-11-26 06:04:13,466 DEBUG : BROWSER_OUTPUT: |2;jquery.html;346.63083815736223;348.19393104961995;348.35901940429613;353.17533559139406;353.6922877096926;14955.47655832046;15131.848681513182;16524.83475165248;17075.82924170758;18041.639167216665;16227.350905963747;16595;16652.83347166528;17747.91126044368;17751.37870174544;4963.677360971544;4997.950020499785;5000.299958005882;5042.369703787027;5080.085584594772;3683.2265224302796;3765.435184722286;4992.750362481879;5041.470645582216;5392.299001129848;5531.170324451345;5566.666000040011;5578.86052848678;5585.469380408855;5592.161175823619;5445.428230035848;5524.364698059714;6033.9396606033815;6037.396260373971;6040.848978775522;5295.549878260314;5321.122014867542;5366.409694933554;5374.435684253136;5388.326459192601;12912.160709553942;13126.227959225478;13145.868541314656;13154.60536183916;15993.120378379184;2099.191811152702;2109.1879626343816;2114.1754715660904;2129.968050479244;2157.6332023556033;10077.596896124147;10498.895011049868;11844.230125041931;12315;13312.134711243834;1679.2863033210836;1686.1147897353865;1690.9746353804703;1742.8344307290913;1760.3486709917413;60950.085748713806;62935.05597416088;63415;63490;64741.67629161824;10177.491125443697;10412.531436085394;10440.321379110334;10444;10461.424621645883;13600.591982240549;13633.863661363259;13638.659033524242;13641.317934103256;13642.86357136416;7347.118345798538;7396.038514993016;7414.888776668355;7447.664855081534;7473.663685134074;6817.250102488722;6885.070515480346;6888.758893438706;6926.480513961473;7060.3292687194635;4455.777211139495;4480.910381792346;4514.142312960527;4528.298113792367;4567.611753001032;1733;1789.5436663650685;1793.5336812428861;1807.641028457875;1821.945341639753;875.0287181228831;899.9730008099767;911.3711539038043;983.8573406855966;999.3703966501058;16371.590710232102;16758;16918.389964863065;16978.57553561146;17029.14854257282;842.3471809347805;902.5712786426526;967.8886928003229;968.660968660963;1019.2202964731986;9066.592003359865;9319.394239374418;9469.573869175903;9582.041795820502;9618.51907404627;559012;562331;575910;578190;578296;75380.62309688417;77547;79792;80129.19870801334;81862;1079.854219680333;1091.1761619976867;1096.1833434091652;1097.4732128578328;1115.403259256294;14482.348294326992;14500.927495362455;14590.370355565276;14690.779638305648;14841.554753357417;32143.35713285768;32203.677963221005;32353;32402.189945251557;32479.18802029968;44813.10373792441;45402;46612.300815488474;47114.52885471102;47445;9639.325247232626;9704.563294651616;9731.172850307803;9875.555599997873;9907.653232137129;17065.146742662568;17306.82693173052;17454.389096382074;17662.293508259525;17789.199486022902;13981.161130332213;14228.288585570886;14278.214698191283;14553.92723036378;14894.925525372304;14098.36557354942;14228.14631122136;14283.143011419348;14358.928205358907;14625.122492650475;11487.48306326234;13199.472021119047;14508.637284067983;14915.254237287874;14952.327145278707;9495.097965693249;9517.095875891779;9685.564149613143;9719.951400242953;9731.318807683394;14159;14360.353784079532;14489.420423182955;14760.188189649672;14997.2501374933 06:04:13 INFO - 2015-11-26 06:04:13,466 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1225.6139316115418;1663.326352827105;1681.0922102064885;1730.9048002359882;1738.1569938579792;6454;7074.434045276378;7289.781306560799;7289.963550182241;7290.599017054074;7067.1872734635535;7183.856322873539;7228.1687605925335;7233.348998590126;7237.27627237276;1820.4083672806325;1845.1604519943394;2118.1315660579166;2162.081115525903;2200;2142.9892850535725;2147.38799442159;2150.3871396651957;2151.150295633224;2151.8493705440624;2019.8485113616464;2076.055394795372;2085.301424022954;2087.9791202087936;2094.465911192644;1801.0724476894409;1837.209999700135;2113.820325272354;2178.455386153462;2178.803907648319;2161.113943283262;2165.8700477971374;2174.662927246279;2195.9121635134575;2198.1756841184556;7124;7189.281071892821;7205.135383753931;7207;7207.13514378278;4355.912881742379;4428.844990425319;4431.490378606437;4446.844360447369;4451.821927122911;2994.326276587772;3036.6659667436566;3074.584931034304;3078.907632770998;3114.76639252057;907.4237858959608;950.6862735297335;1012.9290949633529;1028.9742756431074;1045.8849526552074;9580.568874400598;9585.520723963844;9606;9611.855822162674;9618.086281803147;3867.5358956925347;3966.841326346914;4019.4975628046495;4020.577839326887;4027.375756757707;8242.752717418487;8249.133840946733;8321.209485098905;8327.333813294921;8330.750077497685;4371.322445021026;4377.518472967971;4388.4733831940375;4413.44831896013;4430;4033.5966403360017;4047.5547689754103;4057.350823868167;4070.08423104805;4075.2053349596545;3471.444568868969;3487.8081705505933;3527.7530572859655;3529.4705794131087;3540.681338679531;1160.14729174057;1169.9941500292443;1173.12015988009;1173.6596387047662;1201.7115892185986;991.4348821171862;1013.9493025348703;1014.0873214107362;1136.210333818005;1343.764841152814;6856.554323968926;6891.345322194384;6901.689423975933;6912.377885990285;6921.307869213038;1131.975562116286;1192.791261529229;1197.8500639386114;1214.3503225774168;1229.434460148324;5430;5471.5896307777075;5488.7530061147345;5494.478024587658;5501.284832971768;8222.712205072794;8471.152884711602;8484.787880303042;8489.36329775257;8492.872606910902;8395.244428001388;8396.034456037512;8399.832003360021;8405.117462666454;8411.411201215857;6585.407313341822;6728.461723062143;6750.7299708012115;6760.898586521304;6762.222344430454;6764.932350676627;6796.93203067963;6799.524033317621;6814.1482314710665;6823.078884350549;7543.962280188564;7657.157712651715;7659.080910290801;7736.110347310131;7754.418418618513;7762.41781866351;7894.565798881116;7915.129335773176;7991.24083212094;8011.959940200261;6224.408681175281;6241.625502469867;6308.27454842699;6323.873522529616;6391.296957334783;6827.52207345481;6864.38220560152;6865.656717164022;6883.690233939385;6885.758998435031;6254;6464.997925321582;6495.967520162369;6505.2518960318275;6539.280679125197;5808.564357673107;6463.450606698483;6505.674716264073;6548.410643042149;6573.704183311858;6565.638889861103;6607.570507917162;6608.339166083353;6617;6620.90068648961;5894.734736936763;6070.332263450929;6181.84545386369;6197.442230199304;6222.968885155546;6120.051392034242;6169.907451388143;6176.629402235881;6183.443490085914;6188.938110618837 06:04:13 INFO - 2015-11-26 06:04:13,467 DEBUG : BROWSER_OUTPUT: |4;prototype.html;327.76728522748846;328.1991939667212;328.22047636862453;330.74202122344565;333.80305619684384;6020.879582408349;6030.608010479315;6038;6040.456358927696;6042.305134909487;5720.256366672337;5760.7407666655;5817.505512031472;5903.2916050073945;5924.407559244073;4596.448426188862;4606;4612.400387949562;4620.745858977755;4621.121986822502;4361.978190109045;4604.148232576984;4605.240135377659;4619.584237418631;4627.329037289591;4425.159219748253;4464.843730469434;4524.185646583614;4533.977330113361;4538.795754191069;4533.433320834895;4576.061907308994;4582.610478109332;4601.884952876171;4603.654725895554;4065.329220678614;4066.2883995301;4069.7558146511305;4071.4299998000006;4074.348104303297;5522.585806064561;5651.519620832234;5658.462446067617;5664.122061080539;5666.121751128581;5403.7027963462;5404.3784964728775;5411.431799661018;5417;5419.3225846769155;6287.931051721218;6317.4946004319545;6327.683615819191;6337.2395312562785;6341.8097457076365;4539.546045395467;4540.4551453825425;4541.818327266906;4543.659225558064;4547.18150732868;6395.104685344056;6555.541112122153;6559.8192325381415;6562.573432726857;6587.077809106729;4483.484399294058;4518.096380723869;4522.5025247222775;4523.434570678665;4525.298578720303;4570.245909424908;4575.977120114445;4578.587927086578;4580.198465268565;4582.495925448198;5295.285136506523;5295.417504074549;5305.1776974569;5305.681659100466;5307.469253074739;6200.782972595937;6397.680115994275;6399.9360006400275;6400.167978162809;6400.775972840928;1591.4986779164526;1609.3643011010586;1613.2982152763586;1613.3304678558268;1613.4594910704811;1069.0325255643654;1071.705281047718;1074.693712291993;1083.815751322277;1092.808758467265;6171.290301615282;6173.9073913891325;6183.752649893954;6191.845203869849;6196.132541444202;6582;6616.437602803816;6616.636085015274;6626.204855417284;6635.336466353423;5963.940360596425;6051.304100028465;6081.27024757032;6177.660228687465;6208.4722798561725;3546.2375589247918;3576.1774791798034;3595.8741444049333;3606.549181352331;3609.530761001053;6887.586744795329;6896.137982752156;6896.137982752156;6898.896516552256;6901.068355771907;6648.1025061616065;6799.150106236721;6801.421879140328;6806.421454176351;6812.216595091628;6900.965495172492;6906.378425941589;6911.619860907598;6911.89632155518;6913.066735990677;6527.477801775752;6645.933540664531;6652.467802575879;6658.700358483977;6663.966680166568;6540.50946179029;6549.508786841101;6560.737570497126;6567.671616419256;6572.244191917799;6867.484938629523;6885.345892140239;6889.483288753263;6890.483213758888;6891.448684105159;4479.238529450066;4482.125985432809;4482.417285752831;4485.923378389098;4492.101579684142;6454.644994525158;6605.603663780189;6609.20689517261;6611.801645950629;6612.7186120790175;6417.903731443938;6444.871102577828;6445.097686323825;6449.7420103195345;6452.12896258999;6313.1477250572425;6321.051842223703;6326.114343991937;6327.588706734047;6327.905081423874;6161.507079433724;6348.238211414659;6349.777757778455;6353.9046914295395;6359.395857393541;6426.518011149276;6483.286838447672;6486.2216534016225;6488.156539649815;6493.090967264681;6190.876182476415;6347.174867267226;6348.619082855043;6351.555391122577;6352.364763523796 06:04:13 INFO - 2015-11-26 06:04:13,468 DEBUG : BROWSER_OUTPUT: |5;yui.html;764.7858599592114;839.1398816213381;848.418833099327;853.4751128056247;873.1661263493361;13756.312184390777;14021.298935053244;14032.087914285565;14036.929815350923;14044.719105617882;13063.085584009123;13159.93420032901;13211.735765284688;13213.933930330335;13217.801732974012;669.933006699331;675.3988949834627;738.5384134915664;750.8535835512077;751.1624538639405;850.7915560687609;858.6522458404356;860.5009094725076;861.4099341950753;862.5859587398053;896.5158814240302;901.1214066285352;901.7925877048283;902.5081330674765;902.702108304258;688.3804575881721;692.4183685704032;881.0660699658382;892.3619611977435;893.1068931068932;648.0020768017248;656.934306569344;657.6645910585636;660.8711301296249;662.4634046422414;13778.931105344509;14001.089929154674;14011.299435028208;14013.299335033309;14020.29898505081;12150.149489535735;12190.329531875743;12502.249865008129;12540.372981350987;13253.536126235536;10084.092431681183;10279.331843430227;10437.321574097736;10821.729456763565;10893.618723344724;3461.152017755666;3500.1074725944723;3619.1675914539805;3635.1093981974586;3674.6876515496215;50294.748526257135;50334.999999999636;50631.49368506341;50709.23936140961;50711.49288507141;9897.406155630686;10062.899371006342;10143.747766350245;10370.429626370622;10849;18033.098345082693;18195.181216845274;18206.817931820777;18211.271549137888;18236.085211052912;7760.961195193988;8048.678052877819;8323.95838020806;8399.076101628816;8427.578621068922;4430.180416622935;4608.516105808909;4614.2617181250835;4768.165571025057;4774.665773395898;3009.623797025372;3042.1786117748084;3047.756179505634;3048.34460590974;3049.0700336397167;537.2424880917887;546.0008185021401;550.8402563256691;562.4909456941474;575.0942265931092;506.33417056570494;556.785637529554;601.9465934614425;634.5970308853911;668.0180135201247;8739.694110706221;8805.867911981326;8837.381383303107;8843.911560884437;8859.76824638441;628.5191828251392;664.2759392262457;677.0521270221631;698.3923986132097;713.8929160625844;3695.427208782643;3717.249534720139;3817.8472861085247;3839.9232015359535;3880.243352546283;58079.12881306699;59286.70356648189;59759;59846.70076649676;59903.40096599065;51163;51305.48694513007;51861.48138518567;52211;52519.73740131351;47666;48033.27950080681;48039.03921921542;48260.517394826304;48692.75653621709;7368.447366447645;7651;7962.243586859239;7970.322522585645;8010.639521221676;35588.822055889555;36039.81980090083;36803;37377;37429;42274.577254227064;45540;47190.76404617955;47392.52607474019;47956.28065579089;10158.18734501253;10473.842892356774;10490.160787137163;10757.623483178051;10933.83599246028;11643.126765492452;11691.298522088702;11867.58463453775;11894.09694448884;11906.464209110783;11005.944970275097;11036.944815275872;11062.889371106186;11082.445877706245;11150.665480035612;11037.613683521038;11058.39178845139;11070.667879963614;11088.5564577416;11101.444927753419;10880.129589632968;10889.401082940514;10898.237123401286;10915.181361397768;11009.944950275198;8874.955625221832;9013.18881300686;9212.447253164832;9361;10260.384376937407;9337.787972474482;9347.289355634923;9706.077922597342;9839.65561205383;10012.899871001197 06:04:13 INFO - 2015-11-26 06:04:13,468 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:04:13 INFO - 2015-11-26 06:04:13,468 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:04:13 INFO - 2015-11-26 06:04:13,468 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,829 06:04:13 INFO - 2015-11-26 06:04:13,468 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:04:13 INFO - 2015-11-26 06:04:13,468 DEBUG : BROWSER_OUTPUT: __startTimestamp1448546653461__endTimestamp 06:04:13 INFO - 2015-11-26 06:04:13,468 DEBUG : BROWSER_OUTPUT: 06:04:13 INFO - 2015-11-26 06:04:13,469 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:04:13 INFO - 2015-11-26 06:04:13,469 DEBUG : BROWSER_OUTPUT: Number of tests: 6 06:04:13 INFO - 2015-11-26 06:04:13,469 DEBUG : BROWSER_OUTPUT: 06:04:13 INFO - 2015-11-26 06:04:13,469 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5482.63 Median:5708.09 stddev:621.75 (10.9%) stddev-sans-first:596.85 06:04:13 INFO - 2015-11-26 06:04:13,469 DEBUG : BROWSER_OUTPUT: Values: 3180.2 3236.2 3277.1 3284.9 3321.7 5269.7 5389.6 5396.9 5462.9 5483.6 5439.4 5465.0 5506.9 5553.2 5578.8 4897.3 5019.2 5021.9 5051.5 5064.2 4889.3 5061.6 5064.8 5106.7 5133.3 4929.3 4934.0 5029.8 5062.1 5064.9 5018.3 5022.6 5025.5 5025.9 5050.6 4804.7 4926.7 4984.3 4984.3 5019.4 5231.2 5296.3 5306.2 5310.0 5343.1 5958.0 5988.3 5988.6 5994.7 6007.7 5857.9 5978.5 5989.9 5992.4 6003.1 5503.7 5551.1 5581.4 5627.7 5630.7 6002.2 6003.0 6004.0 6022.9 6072.8 5573.5 5841.7 5843.9 5845.6 5906.5 5815.3 5866.6 5875.4 5889.3 5904.2 5989.3 5989.8 6003.8 6014.2 6051.5 5982.6 5990.8 5994.3 5995.0 5997.1 4427.5 4510.7 4526.3 4556.6 4606.9 4007.7 4047.1 4078.0 4083.4 4104.9 5517.9 5520.8 5539.4 5568.2 5585.1 5734.5 5872.9 5892.0 5894.4 5913.9 5464.1 5474.5 5475.8 5494.2 5647.4 5073.5 5082.1 5089.5 5096.6 5121.8 5868.9 5926.8 5958.1 5995.4 6077.8 5843.7 5921.6 5968.3 5979.3 5980.5 6172.4 6222.4 6231.6 6238.9 6271.3 5708.1 5918.9 5921.3 5926.7 5949.5 5794.4 5805.2 5884.1 5893.0 5962.6 5805.2 5808.5 5811.4 5813.5 5820.2 5657.6 5712.2 5722.5 5725.4 5729.0 5527.2 5625.7 5643.2 5656.3 5698.5 5770.7 5799.3 5831.8 5832.3 5842.9 5728.1 5729.7 5733.0 5734.9 5791.5 06:04:13 INFO - 2015-11-26 06:04:13,469 DEBUG : BROWSER_OUTPUT: 06:04:13 INFO - 2015-11-26 06:04:13,469 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:45254.18 Median:32195.52 stddev:76102.96 (236.4%) stddev-sans-first:76242.48 06:04:13 INFO - 2015-11-26 06:04:13,471 DEBUG : BROWSER_OUTPUT: Values: 567.3 591.7 610.9 616.3 622.3 30295.2 31131.2 31137.2 31160.7 31178.4 32372.2 32805.5 40643.8 40900.4 41148.8 8739.7 8981.1 8987.9 9000.1 9008.6 7830.9 8727.2 8856.5 8858.4 8864.7 7797.2 10552.7 10834.3 10840.8 10845.2 10376.2 10481.2 10494.7 10498.4 10508.9 7770.5 7965.4 10199.3 10375.8 10477.6 38893.0 39153.0 39209.0 39222.4 39238.8 3164.9 3204.9 3205.0 3292.0 3339.2 29925.7 30253.2 30789.8 30804.8 31382.0 3577.7 3606.7 3606.9 3669.4 3730.1 260557.0 261199.0 262616.0 262896.0 263576.0 56191.4 56912.7 57015.4 57031.7 57067.7 59633.7 59699.4 59736.7 59844.7 59906.4 13958.0 14288.8 14298.6 14322.6 14323.6 10726.7 10763.0 10768.4 10775.3 10777.7 18736.0 19001.4 19223.5 19243.6 19281.2 7773.7 7901.4 8006.0 8134.3 8172.6 924.7 933.5 948.2 1067.4 1085.5 27674.7 28079.9 28120.9 28259.0 28289.3 950.6 1011.3 1028.3 1036.4 1142.2 13324.3 13639.5 13798.4 13824.7 13904.3 303300.0 336604.7 343888.0 345824.0 348474.0 38892.6 48545.8 48926.0 48987.8 49060.8 1299.1 1334.5 1337.2 1342.2 1342.2 25074.4 25864.5 26609.2 28822.3 30673.4 46305.3 46672.0 46810.3 46906.8 46939.8 267683.0 268504.0 268898.0 269845.0 270577.0 31769.7 31868.4 32054.7 32146.0 32204.2 40424.0 40800.8 41052.8 41384.4 41501.0 33479.8 34426.0 36511.5 36896.3 37222.6 34513.1 36020.0 36691.6 36828.3 36929.4 32186.8 32902.5 33180.7 33269.0 33296.7 29234.9 30509.8 30938.4 31295.2 32821.5 24825.9 24926.5 25098.0 25419.4 25450.9 06:04:13 INFO - 2015-11-26 06:04:13,471 DEBUG : BROWSER_OUTPUT: 06:04:13 INFO - 2015-11-26 06:04:13,471 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29525.28 Median:16412.91 stddev:93234.13 (568.1%) stddev-sans-first:93469.93 06:04:13 INFO - 2015-11-26 06:04:13,471 DEBUG : BROWSER_OUTPUT: Values: 346.6 348.2 348.4 353.2 353.7 14955.5 15131.8 16524.8 17075.8 18041.6 16227.4 16595.0 16652.8 17747.9 17751.4 4963.7 4998.0 5000.3 5042.4 5080.1 3683.2 3765.4 4992.8 5041.5 5392.3 5531.2 5566.7 5578.9 5585.5 5592.2 5445.4 5524.4 6033.9 6037.4 6040.8 5295.5 5321.1 5366.4 5374.4 5388.3 12912.2 13126.2 13145.9 13154.6 15993.1 2099.2 2109.2 2114.2 2130.0 2157.6 10077.6 10498.9 11844.2 12315.0 13312.1 1679.3 1686.1 1691.0 1742.8 1760.3 60950.1 62935.1 63415.0 63490.0 64741.7 10177.5 10412.5 10440.3 10444.0 10461.4 13600.6 13633.9 13638.7 13641.3 13642.9 7347.1 7396.0 7414.9 7447.7 7473.7 6817.3 6885.1 6888.8 6926.5 7060.3 4455.8 4480.9 4514.1 4528.3 4567.6 1733.0 1789.5 1793.5 1807.6 1821.9 875.0 900.0 911.4 983.9 999.4 16371.6 16758.0 16918.4 16978.6 17029.1 842.3 902.6 967.9 968.7 1019.2 9066.6 9319.4 9469.6 9582.0 9618.5 559012.0 562331.0 575910.0 578190.0 578296.0 75380.6 77547.0 79792.0 80129.2 81862.0 1079.9 1091.2 1096.2 1097.5 1115.4 14482.3 14500.9 14590.4 14690.8 14841.6 32143.4 32203.7 32353.0 32402.2 32479.2 44813.1 45402.0 46612.3 47114.5 47445.0 9639.3 9704.6 9731.2 9875.6 9907.7 17065.1 17306.8 17454.4 17662.3 17789.2 13981.2 14228.3 14278.2 14553.9 14894.9 14098.4 14228.1 14283.1 14358.9 14625.1 11487.5 13199.5 14508.6 14915.3 14952.3 9495.1 9517.1 9685.6 9720.0 9731.3 14159.0 14360.4 14489.4 14760.2 14997.3 06:04:13 INFO - 2015-11-26 06:04:13,471 DEBUG : BROWSER_OUTPUT: 06:04:13 INFO - 2015-11-26 06:04:13,471 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5069.06 Median:6193.19 stddev:2547.57 (41.1%) stddev-sans-first:2538.33 06:04:13 INFO - 2015-11-26 06:04:13,473 DEBUG : BROWSER_OUTPUT: Values: 1225.6 1663.3 1681.1 1730.9 1738.2 6454.0 7074.4 7289.8 7290.0 7290.6 7067.2 7183.9 7228.2 7233.3 7237.3 1820.4 1845.2 2118.1 2162.1 2200.0 2143.0 2147.4 2150.4 2151.2 2151.8 2019.8 2076.1 2085.3 2088.0 2094.5 1801.1 1837.2 2113.8 2178.5 2178.8 2161.1 2165.9 2174.7 2195.9 2198.2 7124.0 7189.3 7205.1 7207.0 7207.1 4355.9 4428.8 4431.5 4446.8 4451.8 2994.3 3036.7 3074.6 3078.9 3114.8 907.4 950.7 1012.9 1029.0 1045.9 9580.6 9585.5 9606.0 9611.9 9618.1 3867.5 3966.8 4019.5 4020.6 4027.4 8242.8 8249.1 8321.2 8327.3 8330.8 4371.3 4377.5 4388.5 4413.4 4430.0 4033.6 4047.6 4057.4 4070.1 4075.2 3471.4 3487.8 3527.8 3529.5 3540.7 1160.1 1170.0 1173.1 1173.7 1201.7 991.4 1013.9 1014.1 1136.2 1343.8 6856.6 6891.3 6901.7 6912.4 6921.3 1132.0 1192.8 1197.9 1214.4 1229.4 5430.0 5471.6 5488.8 5494.5 5501.3 8222.7 8471.2 8484.8 8489.4 8492.9 8395.2 8396.0 8399.8 8405.1 8411.4 6585.4 6728.5 6750.7 6760.9 6762.2 6764.9 6796.9 6799.5 6814.1 6823.1 7544.0 7657.2 7659.1 7736.1 7754.4 7762.4 7894.6 7915.1 7991.2 8012.0 6224.4 6241.6 6308.3 6323.9 6391.3 6827.5 6864.4 6865.7 6883.7 6885.8 6254.0 6465.0 6496.0 6505.3 6539.3 5808.6 6463.5 6505.7 6548.4 6573.7 6565.6 6607.6 6608.3 6617.0 6620.9 5894.7 6070.3 6181.8 6197.4 6223.0 6120.1 6169.9 6176.6 6183.4 6188.9 06:04:13 INFO - 2015-11-26 06:04:13,473 DEBUG : BROWSER_OUTPUT: 06:04:13 INFO - 2015-11-26 06:04:13,473 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5346.15 Median:6046.80 stddev:1616.28 (26.7%) stddev-sans-first:1576.31 06:04:13 INFO - 2015-11-26 06:04:13,473 DEBUG : BROWSER_OUTPUT: Values: 327.8 328.2 328.2 330.7 333.8 6020.9 6030.6 6038.0 6040.5 6042.3 5720.3 5760.7 5817.5 5903.3 5924.4 4596.4 4606.0 4612.4 4620.7 4621.1 4362.0 4604.1 4605.2 4619.6 4627.3 4425.2 4464.8 4524.2 4534.0 4538.8 4533.4 4576.1 4582.6 4601.9 4603.7 4065.3 4066.3 4069.8 4071.4 4074.3 5522.6 5651.5 5658.5 5664.1 5666.1 5403.7 5404.4 5411.4 5417.0 5419.3 6287.9 6317.5 6327.7 6337.2 6341.8 4539.5 4540.5 4541.8 4543.7 4547.2 6395.1 6555.5 6559.8 6562.6 6587.1 4483.5 4518.1 4522.5 4523.4 4525.3 4570.2 4576.0 4578.6 4580.2 4582.5 5295.3 5295.4 5305.2 5305.7 5307.5 6200.8 6397.7 6399.9 6400.2 6400.8 1591.5 1609.4 1613.3 1613.3 1613.5 1069.0 1071.7 1074.7 1083.8 1092.8 6171.3 6173.9 6183.8 6191.8 6196.1 6582.0 6616.4 6616.6 6626.2 6635.3 5963.9 6051.3 6081.3 6177.7 6208.5 3546.2 3576.2 3595.9 3606.5 3609.5 6887.6 6896.1 6896.1 6898.9 6901.1 6648.1 6799.2 6801.4 6806.4 6812.2 6901.0 6906.4 6911.6 6911.9 6913.1 6527.5 6645.9 6652.5 6658.7 6664.0 6540.5 6549.5 6560.7 6567.7 6572.2 6867.5 6885.3 6889.5 6890.5 6891.4 4479.2 4482.1 4482.4 4485.9 4492.1 6454.6 6605.6 6609.2 6611.8 6612.7 6417.9 6444.9 6445.1 6449.7 6452.1 6313.1 6321.1 6326.1 6327.6 6327.9 6161.5 6348.2 6349.8 6353.9 6359.4 6426.5 6483.3 6486.2 6488.2 6493.1 6190.9 6347.2 6348.6 6351.6 6352.4 06:04:13 INFO - 2015-11-26 06:04:13,473 DEBUG : BROWSER_OUTPUT: 06:04:13 INFO - 2015-11-26 06:04:13,473 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14056.52 Median:26365.47 stddev:16520.81 (62.7%) stddev-sans-first:16537.01 06:04:13 INFO - 2015-11-26 06:04:13,473 DEBUG : BROWSER_OUTPUT: Values: 764.8 839.1 848.4 853.5 873.2 13756.3 14021.3 14032.1 14036.9 14044.7 13063.1 13159.9 13211.7 13213.9 13217.8 669.9 675.4 738.5 750.9 751.2 850.8 858.7 860.5 861.4 862.6 896.5 901.1 901.8 902.5 902.7 688.4 692.4 881.1 892.4 893.1 648.0 656.9 657.7 660.9 662.5 13778.9 14001.1 14011.3 14013.3 14020.3 12150.1 12190.3 12502.2 12540.4 13253.5 10084.1 10279.3 10437.3 10821.7 10893.6 3461.2 3500.1 3619.2 3635.1 3674.7 50294.7 50335.0 50631.5 50709.2 50711.5 9897.4 10062.9 10143.7 10370.4 10849.0 18033.1 18195.2 18206.8 18211.3 18236.1 7761.0 8048.7 8324.0 8399.1 8427.6 4430.2 4608.5 4614.3 4768.2 4774.7 3009.6 3042.2 3047.8 3048.3 3049.1 537.2 546.0 550.8 562.5 575.1 506.3 556.8 601.9 634.6 668.0 8739.7 8805.9 8837.4 8843.9 8859.8 628.5 664.3 677.1 698.4 713.9 3695.4 3717.2 3817.8 3839.9 3880.2 58079.1 59286.7 59759.0 59846.7 59903.4 51163.0 51305.5 51861.5 52211.0 52519.7 47666.0 48033.3 48039.0 48260.5 48692.8 7368.4 7651.0 7962.2 7970.3 8010.6 35588.8 36039.8 36803.0 37377.0 37429.0 42274.6 45540.0 47190.8 47392.5 47956.3 10158.2 10473.8 10490.2 10757.6 10933.8 11643.1 11691.3 11867.6 11894.1 11906.5 11005.9 11036.9 11062.9 11082.4 11150.7 11037.6 11058.4 11070.7 11088.6 11101.4 10880.1 10889.4 10898.2 10915.2 11009.9 8875.0 9013.2 9212.4 9361.0 10260.4 9337.8 9347.3 9706.1 9839.7 10012.9 06:04:13 INFO - 2015-11-26 06:04:13,473 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:04:13 INFO - 2015-11-26 06:04:13,473 DEBUG : BROWSER_OUTPUT: 06:04:14 INFO - 2015-11-26 06:04:14,185 INFO : Browser exited with error code: 0 06:04:14 INFO - 2015-11-26 06:04:14,206 INFO : Completed test dromaeo_css (00:18:33) 06:04:14 INFO - 2015-11-26 06:04:14,207 INFO : Starting test kraken 06:04:14 INFO - 2015-11-26 06:04:14,207 DEBUG : operating with platform_type : linux_ 06:04:14 INFO - 2015-11-26 06:04:14,207 INFO : Initialising browser for kraken test... 06:04:14 INFO - 2015-11-26 06:04:14,213 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpSyeiP7/profile http://localhost:40605/getInfo.html 06:04:18 INFO - 2015-11-26 06:04:18,153 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:04:18 INFO - 2015-11-26 06:04:18,153 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:04:18 INFO - 2015-11-26 06:04:18,153 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:04:18 INFO - 2015-11-26 06:04:18,153 DEBUG : BROWSER_OUTPUT: __metrics 06:04:18 INFO - 2015-11-26 06:04:18,270 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] 06:04:19 INFO - 2015-11-26 06:04:19,482 INFO : Browser initialized. 06:04:19 INFO - 2015-11-26 06:04:19,482 INFO : Running cycle 1/1 for kraken test... 06:04:19 INFO - 2015-11-26 06:04:19,482 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpSyeiP7/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:04:31 INFO - 2015-11-26 06:04:31,233 DEBUG : BROWSER_OUTPUT: RSS: Main: 139653120 06:04:31 INFO - 2015-11-26 06:04:31,233 DEBUG : BROWSER_OUTPUT: 06:04:33 INFO - 2015-11-26 06:04:33,680 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40605/tests/kraken/driver.html?testName=audio-beat-detection) 06:04:34 INFO - 2015-11-26 06:04:34,261 DEBUG : BROWSER_OUTPUT: RSS: Main: 178085888 06:04:34 INFO - 2015-11-26 06:04:34,261 DEBUG : BROWSER_OUTPUT: 06:04:36 INFO - 2015-11-26 06:04:36,890 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40605/tests/kraken/driver.html?testName=audio-dft) 06:04:37 INFO - 2015-11-26 06:04:37,456 DEBUG : BROWSER_OUTPUT: RSS: Main: 163569664 06:04:37 INFO - 2015-11-26 06:04:37,456 DEBUG : BROWSER_OUTPUT: 06:04:40 INFO - 2015-11-26 06:04:40,287 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40605/tests/kraken/driver.html?testName=audio-fft) 06:04:40 INFO - 2015-11-26 06:04:40,848 DEBUG : BROWSER_OUTPUT: RSS: Main: 149798912 06:04:40 INFO - 2015-11-26 06:04:40,849 DEBUG : BROWSER_OUTPUT: 06:04:42 INFO - 2015-11-26 06:04:42,815 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40605/tests/kraken/driver.html?testName=audio-oscillator) 06:04:43 INFO - 2015-11-26 06:04:43,376 DEBUG : BROWSER_OUTPUT: RSS: Main: 151052288 06:04:43 INFO - 2015-11-26 06:04:43,376 DEBUG : BROWSER_OUTPUT: 06:04:45 INFO - 2015-11-26 06:04:45,561 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40605/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:04:46 INFO - 2015-11-26 06:04:46,122 DEBUG : BROWSER_OUTPUT: RSS: Main: 155152384 06:04:46 INFO - 2015-11-26 06:04:46,123 DEBUG : BROWSER_OUTPUT: 06:04:49 INFO - 2015-11-26 06:04:49,042 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40605/tests/kraken/driver.html?testName=imaging-darkroom) 06:04:49 INFO - 2015-11-26 06:04:49,625 DEBUG : BROWSER_OUTPUT: RSS: Main: 154894336 06:04:49 INFO - 2015-11-26 06:04:49,625 DEBUG : BROWSER_OUTPUT: 06:04:52 INFO - 2015-11-26 06:04:52,959 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40605/tests/kraken/driver.html?testName=imaging-desaturate) 06:04:53 INFO - 2015-11-26 06:04:53,545 DEBUG : BROWSER_OUTPUT: RSS: Main: 154681344 06:04:53 INFO - 2015-11-26 06:04:53,545 DEBUG : BROWSER_OUTPUT: 06:04:56 INFO - 2015-11-26 06:04:56,269 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40605/tests/kraken/driver.html?testName=json-parse-financial) 06:04:56 INFO - 2015-11-26 06:04:56,843 DEBUG : BROWSER_OUTPUT: RSS: Main: 153817088 06:04:56 INFO - 2015-11-26 06:04:56,844 DEBUG : BROWSER_OUTPUT: 06:04:58 INFO - 2015-11-26 06:04:58,422 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40605/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:04:58 INFO - 2015-11-26 06:04:58,988 DEBUG : BROWSER_OUTPUT: RSS: Main: 170192896 06:04:58 INFO - 2015-11-26 06:04:58,988 DEBUG : BROWSER_OUTPUT: 06:05:00 INFO - 2015-11-26 06:05:00,819 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40605/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:05:01 INFO - 2015-11-26 06:05:01,414 DEBUG : BROWSER_OUTPUT: RSS: Main: 168767488 06:05:01 INFO - 2015-11-26 06:05:01,414 DEBUG : BROWSER_OUTPUT: 06:05:03 INFO - 2015-11-26 06:05:03,458 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40605/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:05:04 INFO - 2015-11-26 06:05:04,031 DEBUG : BROWSER_OUTPUT: RSS: Main: 175910912 06:05:04 INFO - 2015-11-26 06:05:04,031 DEBUG : BROWSER_OUTPUT: 06:05:06 INFO - 2015-11-26 06:05:06,397 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40605/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:05:06 INFO - 2015-11-26 06:05:06,978 DEBUG : BROWSER_OUTPUT: RSS: Main: 182210560 06:05:06 INFO - 2015-11-26 06:05:06,978 DEBUG : BROWSER_OUTPUT: 06:05:09 INFO - 2015-11-26 06:05:09,488 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40605/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:05:10 INFO - 2015-11-26 06:05:10,071 DEBUG : BROWSER_OUTPUT: RSS: Main: 178823168 06:05:10 INFO - 2015-11-26 06:05:10,071 DEBUG : BROWSER_OUTPUT: 06:05:11 INFO - 2015-11-26 06:05:11,701 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40605/tests/kraken/driver.html?testName=ai-astar) 06:05:11 INFO - 2015-11-26 06:05:11,951 DEBUG : BROWSER_OUTPUT: RSS: Main: 238059520 06:05:11 INFO - 2015-11-26 06:05:11,952 DEBUG : BROWSER_OUTPUT: 06:05:11 INFO - 2015-11-26 06:05:11,952 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:05:11 INFO - 2015-11-26 06:05:11,952 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:05:11 INFO - 2015-11-26 06:05:11,952 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:05:11 INFO - 2015-11-26 06:05:11,952 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:05:11 INFO - 2015-11-26 06:05:11,953 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;111;104;110;112;110;101;112;110;101 06:05:11 INFO - 2015-11-26 06:05:11,953 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;147;124;163;167;132;163;157;126;166;133 06:05:11 INFO - 2015-11-26 06:05:11,953 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;159;160;164;159;159;160;158;161 06:05:11 INFO - 2015-11-26 06:05:11,953 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;71;87;93;71;71;70;129;99;71 06:05:11 INFO - 2015-11-26 06:05:11,954 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;141;93;94;93;138;93;93;136 06:05:11 INFO - 2015-11-26 06:05:11,954 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;109;110;109;109;108;109;109;109 06:05:11 INFO - 2015-11-26 06:05:11,954 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;145;145;147;146;145;145;145;146 06:05:11 INFO - 2015-11-26 06:05:11,954 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;96;96;96;96;96;96;95 06:05:11 INFO - 2015-11-26 06:05:11,954 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;59;59;61;59;58;58;58;59;62;57 06:05:11 INFO - 2015-11-26 06:05:11,955 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;69;70;70;70;71;71;76;71;71 06:05:11 INFO - 2015-11-26 06:05:11,955 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;84;85;86;84;85;85;86;85;86 06:05:11 INFO - 2015-11-26 06:05:11,955 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;120;116;116;116;120;118;118;118;122;123 06:05:11 INFO - 2015-11-26 06:05:11,955 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;149;149;140;139;138;147;147;147;144 06:05:11 INFO - 2015-11-26 06:05:11,956 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;66;66;66;66;66;66;65;66;66 06:05:11 INFO - 2015-11-26 06:05:11,956 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:05:11 INFO - 2015-11-26 06:05:11,956 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:05:11 INFO - 2015-11-26 06:05:11,956 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,926 06:05:11 INFO - 2015-11-26 06:05:11,956 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:05:11 INFO - 2015-11-26 06:05:11,957 DEBUG : BROWSER_OUTPUT: __startTimestamp1448546711950__endTimestamp 06:05:11 INFO - 2015-11-26 06:05:11,957 DEBUG : BROWSER_OUTPUT: 06:05:11 INFO - 2015-11-26 06:05:11,958 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:05:11 INFO - 2015-11-26 06:05:11,958 DEBUG : BROWSER_OUTPUT: Number of tests: 14 06:05:11 INFO - 2015-11-26 06:05:11,958 DEBUG : BROWSER_OUTPUT: 06:05:11 INFO - 2015-11-26 06:05:11,959 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.10 Median:110.00 stddev:4.36 (4.0%) stddev-sans-first:4.57 06:05:11 INFO - 2015-11-26 06:05:11,959 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 104.0 110.0 112.0 110.0 101.0 112.0 110.0 101.0 06:05:11 INFO - 2015-11-26 06:05:11,959 DEBUG : BROWSER_OUTPUT: 06:05:11 INFO - 2015-11-26 06:05:11,959 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:147.80 Median:160.00 stddev:17.50 (10.9%) stddev-sans-first:18.56 06:05:11 INFO - 2015-11-26 06:05:11,960 DEBUG : BROWSER_OUTPUT: Values: 147.0 124.0 163.0 167.0 132.0 163.0 157.0 126.0 166.0 133.0 06:05:11 INFO - 2015-11-26 06:05:11,960 DEBUG : BROWSER_OUTPUT: 06:05:11 INFO - 2015-11-26 06:05:11,960 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:1.66 (1.0%) stddev-sans-first:1.73 06:05:11 INFO - 2015-11-26 06:05:11,960 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 159.0 160.0 164.0 159.0 159.0 160.0 158.0 161.0 06:05:11 INFO - 2015-11-26 06:05:11,961 DEBUG : BROWSER_OUTPUT: 06:05:11 INFO - 2015-11-26 06:05:11,961 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:83.20 Median:71.00 stddev:19.44 (27.4%) stddev-sans-first:20.02 06:05:11 INFO - 2015-11-26 06:05:11,961 DEBUG : BROWSER_OUTPUT: Values: 70.0 71.0 87.0 93.0 71.0 71.0 70.0 129.0 99.0 71.0 06:05:11 INFO - 2015-11-26 06:05:11,961 DEBUG : BROWSER_OUTPUT: 06:05:11 INFO - 2015-11-26 06:05:11,961 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.70 Median:93.00 stddev:21.86 (23.5%) stddev-sans-first:22.62 06:05:11 INFO - 2015-11-26 06:05:11,961 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 141.0 93.0 94.0 93.0 138.0 93.0 93.0 136.0 06:05:11 INFO - 2015-11-26 06:05:11,961 DEBUG : BROWSER_OUTPUT: 06:05:11 INFO - 2015-11-26 06:05:11,961 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.63 (0.6%) stddev-sans-first:0.60 06:05:11 INFO - 2015-11-26 06:05:11,961 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 109.0 110.0 109.0 109.0 108.0 109.0 109.0 109.0 06:05:11 INFO - 2015-11-26 06:05:11,961 DEBUG : BROWSER_OUTPUT: 06:05:11 INFO - 2015-11-26 06:05:11,962 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 06:05:11 INFO - 2015-11-26 06:05:11,962 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 145.0 145.0 147.0 146.0 145.0 145.0 145.0 146.0 06:05:11 INFO - 2015-11-26 06:05:11,962 DEBUG : BROWSER_OUTPUT: 06:05:11 INFO - 2015-11-26 06:05:11,962 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 06:05:11 INFO - 2015-11-26 06:05:11,962 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 06:05:11 INFO - 2015-11-26 06:05:11,962 DEBUG : BROWSER_OUTPUT: 06:05:11 INFO - 2015-11-26 06:05:11,962 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:59.00 Median:59.00 stddev:1.49 (2.5%) stddev-sans-first:1.58 06:05:11 INFO - 2015-11-26 06:05:11,962 DEBUG : BROWSER_OUTPUT: Values: 59.0 59.0 61.0 59.0 58.0 58.0 58.0 59.0 62.0 57.0 06:05:11 INFO - 2015-11-26 06:05:11,962 DEBUG : BROWSER_OUTPUT: 06:05:11 INFO - 2015-11-26 06:05:11,963 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.00 stddev:1.99 (2.8%) stddev-sans-first:2.00 06:05:11 INFO - 2015-11-26 06:05:11,963 DEBUG : BROWSER_OUTPUT: Values: 69.0 69.0 70.0 70.0 70.0 71.0 71.0 76.0 71.0 71.0 06:05:11 INFO - 2015-11-26 06:05:11,963 DEBUG : BROWSER_OUTPUT: 06:05:11 INFO - 2015-11-26 06:05:11,963 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:85.00 stddev:0.99 (1.2%) stddev-sans-first:0.78 06:05:11 INFO - 2015-11-26 06:05:11,963 DEBUG : BROWSER_OUTPUT: Values: 83.0 84.0 85.0 86.0 84.0 85.0 85.0 86.0 85.0 86.0 06:05:11 INFO - 2015-11-26 06:05:11,963 DEBUG : BROWSER_OUTPUT: 06:05:11 INFO - 2015-11-26 06:05:11,963 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:118.70 Median:119.00 stddev:2.50 (2.1%) stddev-sans-first:2.60 06:05:11 INFO - 2015-11-26 06:05:11,963 DEBUG : BROWSER_OUTPUT: Values: 120.0 116.0 116.0 116.0 120.0 118.0 118.0 118.0 122.0 123.0 06:05:11 INFO - 2015-11-26 06:05:11,963 DEBUG : BROWSER_OUTPUT: 06:05:11 INFO - 2015-11-26 06:05:11,964 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:144.00 Median:147.00 stddev:4.35 (3.0%) stddev-sans-first:4.36 06:05:11 INFO - 2015-11-26 06:05:11,964 DEBUG : BROWSER_OUTPUT: Values: 140.0 149.0 149.0 140.0 139.0 138.0 147.0 147.0 147.0 144.0 06:05:11 INFO - 2015-11-26 06:05:11,964 DEBUG : BROWSER_OUTPUT: 06:05:11 INFO - 2015-11-26 06:05:11,964 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:0.42 (0.6%) stddev-sans-first:0.33 06:05:11 INFO - 2015-11-26 06:05:11,964 DEBUG : BROWSER_OUTPUT: Values: 65.0 66.0 66.0 66.0 66.0 66.0 66.0 65.0 66.0 66.0 06:05:11 INFO - 2015-11-26 06:05:11,964 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:05:11 INFO - 2015-11-26 06:05:11,964 DEBUG : BROWSER_OUTPUT: 06:05:12 INFO - 2015-11-26 06:05:12,801 INFO : Browser exited with error code: 0 06:05:12 INFO - 2015-11-26 06:05:12,850 INFO : Completed test kraken (00:00:58) 06:05:12 INFO - 2015-11-26 06:05:12,850 INFO : Starting test v8_7 06:05:12 INFO - 2015-11-26 06:05:12,850 DEBUG : operating with platform_type : linux_ 06:05:12 INFO - 2015-11-26 06:05:12,850 INFO : Initialising browser for v8_7 test... 06:05:12 INFO - 2015-11-26 06:05:12,856 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpQLjAgK/profile http://localhost:40605/getInfo.html 06:05:16 INFO - 2015-11-26 06:05:16,757 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:05:16 INFO - 2015-11-26 06:05:16,757 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:05:16 INFO - 2015-11-26 06:05:16,757 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:05:16 INFO - 2015-11-26 06:05:16,757 DEBUG : BROWSER_OUTPUT: __metrics 06:05:16 INFO - 2015-11-26 06:05:16,895 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] 06:05:18 INFO - 2015-11-26 06:05:18,075 INFO : Browser initialized. 06:05:18 INFO - 2015-11-26 06:05:18,075 INFO : Running cycle 1/1 for v8_7 test... 06:05:18 INFO - 2015-11-26 06:05:18,075 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpQLjAgK/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:05:29 INFO - 2015-11-26 06:05:29,826 DEBUG : BROWSER_OUTPUT: RSS: Main: 139042816 06:05:29 INFO - 2015-11-26 06:05:29,827 DEBUG : BROWSER_OUTPUT: 06:05:51 INFO - 2015-11-26 06:05:51,224 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40605/tests/v8_7/run.html (next: http://localhost:40605/tests/v8_7/run.html) 06:05:51 INFO - 2015-11-26 06:05:51,482 DEBUG : BROWSER_OUTPUT: RSS: Main: 166588416 06:05:51 INFO - 2015-11-26 06:05:51,482 DEBUG : BROWSER_OUTPUT: 06:05:51 INFO - 2015-11-26 06:05:51,483 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:05:51 INFO - 2015-11-26 06:05:51,483 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:05:51 INFO - 2015-11-26 06:05:51,483 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:05:51 INFO - 2015-11-26 06:05:51,483 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:05:51 INFO - 2015-11-26 06:05:51,483 DEBUG : BROWSER_OUTPUT: |0;Richards;164.85328058028355 06:05:51 INFO - 2015-11-26 06:05:51,484 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;196.23233908948194 06:05:51 INFO - 2015-11-26 06:05:51,484 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.5270768358378 06:05:51 INFO - 2015-11-26 06:05:51,484 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5133.333333333333 06:05:51 INFO - 2015-11-26 06:05:51,484 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1128.6681715575621 06:05:51 INFO - 2015-11-26 06:05:51,485 DEBUG : BROWSER_OUTPUT: |5;Earley;791.1392405063291 06:05:51 INFO - 2015-11-26 06:05:51,485 DEBUG : BROWSER_OUTPUT: |6;Boyer;12280.487804878048 06:05:51 INFO - 2015-11-26 06:05:51,485 DEBUG : BROWSER_OUTPUT: |7;RegExp;26368.42105263158 06:05:51 INFO - 2015-11-26 06:05:51,485 DEBUG : BROWSER_OUTPUT: |8;Splay;573.7234652897304 06:05:51 INFO - 2015-11-26 06:05:51,485 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 06:05:51 INFO - 2015-11-26 06:05:51,486 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:05:51 INFO - 2015-11-26 06:05:51,486 DEBUG : BROWSER_OUTPUT: __startTimestamp1448546751475__endTimestamp 06:05:51 INFO - 2015-11-26 06:05:51,486 DEBUG : BROWSER_OUTPUT: 06:05:51 INFO - 2015-11-26 06:05:51,486 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:05:51 INFO - 2015-11-26 06:05:51,487 DEBUG : BROWSER_OUTPUT: Number of tests: 10 06:05:51 INFO - 2015-11-26 06:05:51,487 DEBUG : BROWSER_OUTPUT: 06:05:51 INFO - 2015-11-26 06:05:51,487 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.85 Median:164.85 stddev:0.00 (0.0%) 06:05:51 INFO - 2015-11-26 06:05:51,487 DEBUG : BROWSER_OUTPUT: Values: 164.9 06:05:51 INFO - 2015-11-26 06:05:51,488 DEBUG : BROWSER_OUTPUT: 06:05:51 INFO - 2015-11-26 06:05:51,488 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:196.23 Median:196.23 stddev:0.00 (0.0%) 06:05:51 INFO - 2015-11-26 06:05:51,488 DEBUG : BROWSER_OUTPUT: Values: 196.2 06:05:51 INFO - 2015-11-26 06:05:51,488 DEBUG : BROWSER_OUTPUT: 06:05:51 INFO - 2015-11-26 06:05:51,488 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.53 Median:283.53 stddev:0.00 (0.0%) 06:05:51 INFO - 2015-11-26 06:05:51,489 DEBUG : BROWSER_OUTPUT: Values: 283.5 06:05:51 INFO - 2015-11-26 06:05:51,489 DEBUG : BROWSER_OUTPUT: 06:05:51 INFO - 2015-11-26 06:05:51,489 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5133.33 Median:5133.33 stddev:0.00 (0.0%) 06:05:51 INFO - 2015-11-26 06:05:51,489 DEBUG : BROWSER_OUTPUT: Values: 5133.3 06:05:51 INFO - 2015-11-26 06:05:51,490 DEBUG : BROWSER_OUTPUT: 06:05:51 INFO - 2015-11-26 06:05:51,490 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1128.67 Median:1128.67 stddev:0.00 (0.0%) 06:05:51 INFO - 2015-11-26 06:05:51,490 DEBUG : BROWSER_OUTPUT: Values: 1128.7 06:05:51 INFO - 2015-11-26 06:05:51,490 DEBUG : BROWSER_OUTPUT: 06:05:51 INFO - 2015-11-26 06:05:51,491 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:791.14 Median:791.14 stddev:0.00 (0.0%) 06:05:51 INFO - 2015-11-26 06:05:51,491 DEBUG : BROWSER_OUTPUT: Values: 791.1 06:05:51 INFO - 2015-11-26 06:05:51,491 DEBUG : BROWSER_OUTPUT: 06:05:51 INFO - 2015-11-26 06:05:51,491 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12280.49 Median:12280.49 stddev:0.00 (0.0%) 06:05:51 INFO - 2015-11-26 06:05:51,491 DEBUG : BROWSER_OUTPUT: Values: 12280.5 06:05:51 INFO - 2015-11-26 06:05:51,492 DEBUG : BROWSER_OUTPUT: 06:05:51 INFO - 2015-11-26 06:05:51,492 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26368.42 Median:26368.42 stddev:0.00 (0.0%) 06:05:51 INFO - 2015-11-26 06:05:51,492 DEBUG : BROWSER_OUTPUT: Values: 26368.4 06:05:51 INFO - 2015-11-26 06:05:51,492 DEBUG : BROWSER_OUTPUT: 06:05:51 INFO - 2015-11-26 06:05:51,493 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:573.72 Median:573.72 stddev:0.00 (0.0%) 06:05:51 INFO - 2015-11-26 06:05:51,493 DEBUG : BROWSER_OUTPUT: Values: 573.7 06:05:51 INFO - 2015-11-26 06:05:51,493 DEBUG : BROWSER_OUTPUT: 06:05:51 INFO - 2015-11-26 06:05:51,493 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 06:05:51 INFO - 2015-11-26 06:05:51,494 DEBUG : BROWSER_OUTPUT: Values: 4830.9 06:05:51 INFO - 2015-11-26 06:05:51,494 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:05:51 INFO - 2015-11-26 06:05:51,494 DEBUG : BROWSER_OUTPUT: 06:05:52 INFO - 2015-11-26 06:05:52,293 INFO : Browser exited with error code: 0 06:05:52 INFO - 2015-11-26 06:05:52,305 INFO : Completed test v8_7 (00:00:39) 06:05:52 INFO - 2015-11-26 06:05:52,481 INFO : Completed test suite (00:20:11) 06:05:52 INFO - 2015-11-26 06:05:52,481 DEBUG : Working with test: dromaeo_css 06:05:52 INFO - 2015-11-26 06:05:52,481 DEBUG : Generating results file: dromaeo_css 06:05:52 INFO - 2015-11-26 06:05:52,482 DEBUG : Working with test: kraken 06:05:52 INFO - 2015-11-26 06:05:52,482 DEBUG : Generating results file: kraken 06:05:52 INFO - 2015-11-26 06:05:52,483 INFO : javascript benchmark 06:05:52 INFO - 2015-11-26 06:05:52,483 DEBUG : Working with test: v8_7 06:05:52 INFO - 2015-11-26 06:05:52,483 DEBUG : Generating results file: v8_7 06:05:52 INFO - 2015-11-26 06:05:52,483 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:05:52 INFO - 2015-11-26 06:05:52,614 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 06:05:52 INFO - 2015-11-26 06:05:52,614 DEBUG : process_Request line: dromaeo_css 6933.52 graph.html#tests=[[72,1,35]] 06:05:52 INFO - 2015-11-26 06:05:52,614 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:05:52 INFO - 2015-11-26 06:05:52,768 DEBUG : process_Request line: kraken 1499.80 graph.html#tests=[[232,1,35]] 06:05:52 INFO - 2015-11-26 06:05:52,768 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:05:52 INFO - 2015-11-26 06:05:52,813 DEBUG : process_Request line: v8_7 20979.57 graph.html#tests=[[230,1,35]] 06:05:52 INFO - 2015-11-26 06:05:52,814 INFO : javascript benchmark 06:05:52 INFO - 2015-11-26 06:05:52,817 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3180.2049487628087, 3236.1909522619344, 3277.1151789016967, 3284.8850290239848, 3321.7176539994107, 5269.6574722643, 5389.649672771267, 5396.8920621587595, 5462.945370546293, 5483.616146869721, 5439.401665816757, 5464.972675136619, 5506.944930550693, 5553.16702494626, 5578.804741834027, 4897.314375987356, 5019.247112933052, 5021.924671129936, 5051.469595692436, 5064.16441287189, 4889.33993910821, 5061.5950723942, 5064.848054558369, 5106.719130447824, 5133.307003554528, 4929.285253638221, 4934.0, 5029.7736601853, 5062.139436295838, 5064.873378165538, 5018.322526458955, 5022.59819214462, 5025.522575355335, 5025.924611130836, 5050.595952323842, 4804.6876952997945, 4926.704397736147, 4984.27727979441, 4984.327115839388, 5019.4227663818765, 5231.24146998683, 5296.3379577552805, 5306.230596563515, 5309.973450132763, 5343.064963631387, 5957.970210148965, 5988.251468566429, 5988.610740301867, 5994.730237139339, 6007.729652165619, 5857.912131318034, 5978.491828194608, 5989.9101513477335, 5992.370801065869, 6003.0695242237525, 5503.724813759296, 5551.084071128259, 5581.441855814386, 5627.6904770237625, 5630.746616402272, 6002.219711437485, 6003.039513677791, 6003.969980150158, 6022.879542409128, 6072.848178795477, 5573.498385145356, 5841.737121829443, 5843.912341314884, 5845.649261044351, 5906.468417842415, 5815.273090863642, 5866.6186697864505, 5875.4124587541755, 5889.263842019747, 5904.202932604129, 5989.2812862456785, 5989.760409583655, 6003.849903752353, 6014.248218972629, 6051.485623721945, 5982.641041537521, 5990.820275391745, 5994.280686317583, 5995.0, 5997.100434934795, 4427.535108813592, 4510.661700372485, 4526.32105184225, 4556.589906908394, 4606.907861842811, 4007.6793856491413, 4047.0691740899756, 4078.021274894004, 4083.407905853694, 4104.8768536943935, 5517.862053448615, 5520.751566179451, 5539.390667026624, 5568.192612071227, 5585.106382978704, 5734.483896449339, 5872.911906321408, 5892.027815410409, 5894.410558944071, 5913.940860591339, 5464.0711079116645, 5474.507294343528, 5475.835724928259, 5494.203340515603, 5647.350554686182, 5073.518015788494, 5082.059818933509, 5089.516495932954, 5096.592272618182, 5121.769520371592, 5868.911966320508, 5926.822195334147, 5958.076498142795, 5995.400459954144, 6077.848053798691, 5843.707814609167, 5921.61509501881, 5968.253968253968, 5979.282486101695, 5980.5215582754145, 6172.382761723792, 6222.408871157233, 6231.594946328474, 6238.875222495615, 6271.310155882758, 5708.086706127, 5918.940810592011, 5921.3190483094995, 5926.67403292823, 5949.494292984971, 5794.3626201117, 5805.158252053513, 5884.0585506318785, 5892.970535147297, 5962.55280853929, 5805.245318108618, 5808.5062769665055, 5811.4479124483105, 5813.476787089182, 5820.243368362255, 5657.632253903483, 5712.171735098471, 5722.456366645162, 5725.398833122629, 5728.971355143198, 5527.198556209408, 5625.718714064198, 5643.209950607001, 5656.2929633795775, 5698.458646428583, 5770.653760774367, 5799.333076696234, 5831.7667293307795, 5832.300123985149, 5842.883142337215, 5728.054870946247, 5729.742161602655, 5732.971335143297, 5734.913976290442, 5791.53667706574], "unit": "ms", "name": "dojo.html", "value": 5441.415152241894}, {"lowerIsBetter": false, "replicates": [567.2738894215403, 591.6834493545954, 610.8595023144674, 616.3158893628072, 622.337210870423, 30295.24261893451, 31131.221719457026, 31137.221569460773, 31160.688393116063, 31178.376432471337, 32372.19069523263, 32805.52375143118, 40643.79678101613, 40900.38649420254, 41148.794256028756, 8739.694110706127, 8981.14679105484, 8987.910120898772, 9000.05499422561, 9008.594613242387, 7830.882536761953, 8727.170918762708, 8856.51289179095, 8858.379913406065, 8864.689735859247, 7797.220277972214, 10552.736181595445, 10834.295770774914, 10840.78318433631, 10845.186611004166, 10376.22178336624, 10481.21390895682, 10494.68515944523, 10498.422586757726, 10508.894911050906, 7770.49491783038, 7965.44241903067, 10199.286049976505, 10375.84436233457, 10477.58089676412, 38893.0, 39153.0, 39209.0, 39222.41166382506, 39238.80380598079, 3164.9208769780707, 3204.903852884417, 3204.96795032049, 3291.96708032919, 3339.1985923378456, 29925.700742992725, 30253.24366890823, 30789.846050769604, 30804.8459757702, 31382.0, 3577.695895848856, 3606.7294952878638, 3606.9098272543397, 3669.3578623741005, 3730.1420673245034, 260557.0, 261199.0, 262616.0, 262896.0, 263576.0, 56191.43808561944, 56912.71543642255, 57015.429845701015, 57031.71484142553, 57067.71466142643, 59633.70183149143, 59699.40300596938, 59736.70131649314, 59844.70077649671, 59906.400935990954, 13958.0, 14288.785668214985, 14298.57104286873, 14322.57032289033, 14323.57029289123, 10726.73183170427, 10763.0, 10768.3538987661, 10775.299605525615, 10777.730556736144, 18736.0, 19001.429957101034, 19223.51941201481, 19243.61512769737, 19281.22875084981, 7773.727919522789, 7901.407394445439, 8006.0, 8134.267915887599, 8172.6322315495945, 924.7410724997012, 933.5145724223365, 948.2366694810744, 1067.4289255248405, 1085.4626959655022, 27674.723252767617, 28079.859600701864, 28120.85939570289, 28259.0, 28289.29276768056, 950.6482601437513, 1011.3224139826334, 1028.321307936773, 1036.4196050211906, 1142.2118738070872, 13324.333783310796, 13639.522616708566, 13798.448062077405, 13824.723505529833, 13904.304784760721, 303300.0, 336604.7055764993, 343888.0, 345824.0, 348474.0, 38892.611073889464, 48545.75727121413, 48926.0, 48987.75506122518, 49060.75469622629, 1299.0711641176604, 1334.4795529743408, 1337.2348499980128, 1342.1946831900782, 1342.234926092118, 25074.3731406709, 25864.482710346063, 26609.20172394754, 28822.279443014093, 30673.386532269677, 46305.305420418044, 46672.0, 46810.29784553302, 46906.76546617245, 46939.76530117328, 267683.0, 268504.0, 268898.0, 269845.0, 270577.0, 31769.71108900269, 31868.362632747678, 32054.679453205168, 32146.0, 32204.19489512687, 40424.0, 40800.775976720746, 41052.79473602613, 41384.37923431211, 41501.0, 33479.83260083684, 34426.0, 36511.45232821457, 36896.26207475889, 37222.62777372191, 34513.13717157091, 36020.0, 36691.63308366882, 36828.26343473062, 36929.44605830861, 32186.83906580546, 32902.506462402605, 33180.66819331776, 33269.0, 33296.66703332936, 29234.853825730734, 30509.847450762605, 30938.381232374777, 31295.217619559695, 32821.50767738438, 24825.852924878942, 24926.501469970863, 25098.0, 25419.36451588725, 25450.872745636152], "unit": "ms", "name": "ext.html", "value": 18123.060147450316}, {"lowerIsBetter": false, "replicates": [346.63083815736223, 348.19393104961995, 348.35901940429613, 353.17533559139406, 353.6922877096926, 14955.47655832046, 15131.848681513182, 16524.83475165248, 17075.82924170758, 18041.639167216665, 16227.350905963747, 16595.0, 16652.83347166528, 17747.91126044368, 17751.37870174544, 4963.677360971544, 4997.950020499785, 5000.299958005882, 5042.369703787027, 5080.085584594772, 3683.2265224302796, 3765.435184722286, 4992.750362481879, 5041.470645582216, 5392.299001129848, 5531.170324451345, 5566.666000040011, 5578.86052848678, 5585.469380408855, 5592.161175823619, 5445.428230035848, 5524.364698059714, 6033.9396606033815, 6037.396260373971, 6040.848978775522, 5295.549878260314, 5321.122014867542, 5366.409694933554, 5374.435684253136, 5388.326459192601, 12912.160709553942, 13126.227959225478, 13145.868541314656, 13154.60536183916, 15993.120378379184, 2099.191811152702, 2109.1879626343816, 2114.1754715660904, 2129.968050479244, 2157.6332023556033, 10077.596896124147, 10498.895011049868, 11844.230125041931, 12315.0, 13312.134711243834, 1679.2863033210836, 1686.1147897353865, 1690.9746353804703, 1742.8344307290913, 1760.3486709917413, 60950.085748713806, 62935.05597416088, 63415.0, 63490.0, 64741.67629161824, 10177.491125443697, 10412.531436085394, 10440.321379110334, 10444.0, 10461.424621645883, 13600.591982240549, 13633.863661363259, 13638.659033524242, 13641.317934103256, 13642.86357136416, 7347.118345798538, 7396.038514993016, 7414.888776668355, 7447.664855081534, 7473.663685134074, 6817.250102488722, 6885.070515480346, 6888.758893438706, 6926.480513961473, 7060.3292687194635, 4455.777211139495, 4480.910381792346, 4514.142312960527, 4528.298113792367, 4567.611753001032, 1733.0, 1789.5436663650685, 1793.5336812428861, 1807.641028457875, 1821.945341639753, 875.0287181228831, 899.9730008099767, 911.3711539038043, 983.8573406855966, 999.3703966501058, 16371.590710232102, 16758.0, 16918.389964863065, 16978.57553561146, 17029.14854257282, 842.3471809347805, 902.5712786426526, 967.8886928003229, 968.660968660963, 1019.2202964731986, 9066.592003359865, 9319.394239374418, 9469.573869175903, 9582.041795820502, 9618.51907404627, 559012.0, 562331.0, 575910.0, 578190.0, 578296.0, 75380.62309688417, 77547.0, 79792.0, 80129.19870801334, 81862.0, 1079.854219680333, 1091.1761619976867, 1096.1833434091652, 1097.4732128578328, 1115.403259256294, 14482.348294326992, 14500.927495362455, 14590.370355565276, 14690.779638305648, 14841.554753357417, 32143.35713285768, 32203.677963221005, 32353.0, 32402.189945251557, 32479.18802029968, 44813.10373792441, 45402.0, 46612.300815488474, 47114.52885471102, 47445.0, 9639.325247232626, 9704.563294651616, 9731.172850307803, 9875.555599997873, 9907.653232137129, 17065.146742662568, 17306.82693173052, 17454.389096382074, 17662.293508259525, 17789.199486022902, 13981.161130332213, 14228.288585570886, 14278.214698191283, 14553.92723036378, 14894.925525372304, 14098.36557354942, 14228.14631122136, 14283.143011419348, 14358.928205358907, 14625.122492650475, 11487.48306326234, 13199.472021119047, 14508.637284067983, 14915.254237287874, 14952.327145278707, 9495.097965693249, 9517.095875891779, 9685.564149613143, 9719.951400242953, 9731.318807683394, 14159.0, 14360.353784079532, 14489.420423182955, 14760.188189649672, 14997.2501374933], "unit": "ms", "name": "jquery.html", "value": 8758.499949666619}, {"lowerIsBetter": false, "replicates": [1225.6139316115418, 1663.326352827105, 1681.0922102064885, 1730.9048002359882, 1738.1569938579792, 6454.0, 7074.434045276378, 7289.781306560799, 7289.963550182241, 7290.599017054074, 7067.1872734635535, 7183.856322873539, 7228.1687605925335, 7233.348998590126, 7237.27627237276, 1820.4083672806325, 1845.1604519943394, 2118.1315660579166, 2162.081115525903, 2200.0, 2142.9892850535725, 2147.38799442159, 2150.3871396651957, 2151.150295633224, 2151.8493705440624, 2019.8485113616464, 2076.055394795372, 2085.301424022954, 2087.9791202087936, 2094.465911192644, 1801.0724476894409, 1837.209999700135, 2113.820325272354, 2178.455386153462, 2178.803907648319, 2161.113943283262, 2165.8700477971374, 2174.662927246279, 2195.9121635134575, 2198.1756841184556, 7124.0, 7189.281071892821, 7205.135383753931, 7207.0, 7207.13514378278, 4355.912881742379, 4428.844990425319, 4431.490378606437, 4446.844360447369, 4451.821927122911, 2994.326276587772, 3036.6659667436566, 3074.584931034304, 3078.907632770998, 3114.76639252057, 907.4237858959608, 950.6862735297335, 1012.9290949633529, 1028.9742756431074, 1045.8849526552074, 9580.568874400598, 9585.520723963844, 9606.0, 9611.855822162674, 9618.086281803147, 3867.5358956925347, 3966.841326346914, 4019.4975628046495, 4020.577839326887, 4027.375756757707, 8242.752717418487, 8249.133840946733, 8321.209485098905, 8327.333813294921, 8330.750077497685, 4371.322445021026, 4377.518472967971, 4388.4733831940375, 4413.44831896013, 4430.0, 4033.5966403360017, 4047.5547689754103, 4057.350823868167, 4070.08423104805, 4075.2053349596545, 3471.444568868969, 3487.8081705505933, 3527.7530572859655, 3529.4705794131087, 3540.681338679531, 1160.14729174057, 1169.9941500292443, 1173.12015988009, 1173.6596387047662, 1201.7115892185986, 991.4348821171862, 1013.9493025348703, 1014.0873214107362, 1136.210333818005, 1343.764841152814, 6856.554323968926, 6891.345322194384, 6901.689423975933, 6912.377885990285, 6921.307869213038, 1131.975562116286, 1192.791261529229, 1197.8500639386114, 1214.3503225774168, 1229.434460148324, 5430.0, 5471.5896307777075, 5488.7530061147345, 5494.478024587658, 5501.284832971768, 8222.712205072794, 8471.152884711602, 8484.787880303042, 8489.36329775257, 8492.872606910902, 8395.244428001388, 8396.034456037512, 8399.832003360021, 8405.117462666454, 8411.411201215857, 6585.407313341822, 6728.461723062143, 6750.7299708012115, 6760.898586521304, 6762.222344430454, 6764.932350676627, 6796.93203067963, 6799.524033317621, 6814.1482314710665, 6823.078884350549, 7543.962280188564, 7657.157712651715, 7659.080910290801, 7736.110347310131, 7754.418418618513, 7762.41781866351, 7894.565798881116, 7915.129335773176, 7991.24083212094, 8011.959940200261, 6224.408681175281, 6241.625502469867, 6308.27454842699, 6323.873522529616, 6391.296957334783, 6827.52207345481, 6864.38220560152, 6865.656717164022, 6883.690233939385, 6885.758998435031, 6254.0, 6464.997925321582, 6495.967520162369, 6505.2518960318275, 6539.280679125197, 5808.564357673107, 6463.450606698483, 6505.674716264073, 6548.410643042149, 6573.704183311858, 6565.638889861103, 6607.570507917162, 6608.339166083353, 6617.0, 6620.90068648961, 5894.734736936763, 6070.332263450929, 6181.84545386369, 6197.442230199304, 6222.968885155546, 6120.051392034242, 6169.907451388143, 6176.629402235881, 6183.443490085914, 6188.938110618837], "unit": "ms", "name": "mootools.html", "value": 4217.107246374474}, {"lowerIsBetter": false, "replicates": [327.76728522748846, 328.1991939667212, 328.22047636862453, 330.74202122344565, 333.80305619684384, 6020.879582408349, 6030.608010479315, 6038.0, 6040.456358927696, 6042.305134909487, 5720.256366672337, 5760.7407666655, 5817.505512031472, 5903.2916050073945, 5924.407559244073, 4596.448426188862, 4606.0, 4612.400387949562, 4620.745858977755, 4621.121986822502, 4361.978190109045, 4604.148232576984, 4605.240135377659, 4619.584237418631, 4627.329037289591, 4425.159219748253, 4464.843730469434, 4524.185646583614, 4533.977330113361, 4538.795754191069, 4533.433320834895, 4576.061907308994, 4582.610478109332, 4601.884952876171, 4603.654725895554, 4065.329220678614, 4066.2883995301, 4069.7558146511305, 4071.4299998000006, 4074.348104303297, 5522.585806064561, 5651.519620832234, 5658.462446067617, 5664.122061080539, 5666.121751128581, 5403.7027963462, 5404.3784964728775, 5411.431799661018, 5417.0, 5419.3225846769155, 6287.931051721218, 6317.4946004319545, 6327.683615819191, 6337.2395312562785, 6341.8097457076365, 4539.546045395467, 4540.4551453825425, 4541.818327266906, 4543.659225558064, 4547.18150732868, 6395.104685344056, 6555.541112122153, 6559.8192325381415, 6562.573432726857, 6587.077809106729, 4483.484399294058, 4518.096380723869, 4522.5025247222775, 4523.434570678665, 4525.298578720303, 4570.245909424908, 4575.977120114445, 4578.587927086578, 4580.198465268565, 4582.495925448198, 5295.285136506523, 5295.417504074549, 5305.1776974569, 5305.681659100466, 5307.469253074739, 6200.782972595937, 6397.680115994275, 6399.9360006400275, 6400.167978162809, 6400.775972840928, 1591.4986779164526, 1609.3643011010586, 1613.2982152763586, 1613.3304678558268, 1613.4594910704811, 1069.0325255643654, 1071.705281047718, 1074.693712291993, 1083.815751322277, 1092.808758467265, 6171.290301615282, 6173.9073913891325, 6183.752649893954, 6191.845203869849, 6196.132541444202, 6582.0, 6616.437602803816, 6616.636085015274, 6626.204855417284, 6635.336466353423, 5963.940360596425, 6051.304100028465, 6081.27024757032, 6177.660228687465, 6208.4722798561725, 3546.2375589247918, 3576.1774791798034, 3595.8741444049333, 3606.549181352331, 3609.530761001053, 6887.586744795329, 6896.137982752156, 6896.137982752156, 6898.896516552256, 6901.068355771907, 6648.1025061616065, 6799.150106236721, 6801.421879140328, 6806.421454176351, 6812.216595091628, 6900.965495172492, 6906.378425941589, 6911.619860907598, 6911.89632155518, 6913.066735990677, 6527.477801775752, 6645.933540664531, 6652.467802575879, 6658.700358483977, 6663.966680166568, 6540.50946179029, 6549.508786841101, 6560.737570497126, 6567.671616419256, 6572.244191917799, 6867.484938629523, 6885.345892140239, 6889.483288753263, 6890.483213758888, 6891.448684105159, 4479.238529450066, 4482.125985432809, 4482.417285752831, 4485.923378389098, 4492.101579684142, 6454.644994525158, 6605.603663780189, 6609.20689517261, 6611.801645950629, 6612.7186120790175, 6417.903731443938, 6444.871102577828, 6445.097686323825, 6449.7420103195345, 6452.12896258999, 6313.1477250572425, 6321.051842223703, 6326.114343991937, 6327.588706734047, 6327.905081423874, 6161.507079433724, 6348.238211414659, 6349.777757778455, 6353.9046914295395, 6359.395857393541, 6426.518011149276, 6483.286838447672, 6486.2216534016225, 6488.156539649815, 6493.090967264681, 6190.876182476415, 6347.174867267226, 6348.619082855043, 6351.555391122577, 6352.364763523796], "unit": "ms", "name": "prototype.html", "value": 4816.989963052589}, {"lowerIsBetter": false, "replicates": [764.7858599592114, 839.1398816213381, 848.418833099327, 853.4751128056247, 873.1661263493361, 13756.312184390777, 14021.298935053244, 14032.087914285565, 14036.929815350923, 14044.719105617882, 13063.085584009123, 13159.93420032901, 13211.735765284688, 13213.933930330335, 13217.801732974012, 669.933006699331, 675.3988949834627, 738.5384134915664, 750.8535835512077, 751.1624538639405, 850.7915560687609, 858.6522458404356, 860.5009094725076, 861.4099341950753, 862.5859587398053, 896.5158814240302, 901.1214066285352, 901.7925877048283, 902.5081330674765, 902.702108304258, 688.3804575881721, 692.4183685704032, 881.0660699658382, 892.3619611977435, 893.1068931068932, 648.0020768017248, 656.934306569344, 657.6645910585636, 660.8711301296249, 662.4634046422414, 13778.931105344509, 14001.089929154674, 14011.299435028208, 14013.299335033309, 14020.29898505081, 12150.149489535735, 12190.329531875743, 12502.249865008129, 12540.372981350987, 13253.536126235536, 10084.092431681183, 10279.331843430227, 10437.321574097736, 10821.729456763565, 10893.618723344724, 3461.152017755666, 3500.1074725944723, 3619.1675914539805, 3635.1093981974586, 3674.6876515496215, 50294.748526257135, 50334.999999999636, 50631.49368506341, 50709.23936140961, 50711.49288507141, 9897.406155630686, 10062.899371006342, 10143.747766350245, 10370.429626370622, 10849.0, 18033.098345082693, 18195.181216845274, 18206.817931820777, 18211.271549137888, 18236.085211052912, 7760.961195193988, 8048.678052877819, 8323.95838020806, 8399.076101628816, 8427.578621068922, 4430.180416622935, 4608.516105808909, 4614.2617181250835, 4768.165571025057, 4774.665773395898, 3009.623797025372, 3042.1786117748084, 3047.756179505634, 3048.34460590974, 3049.0700336397167, 537.2424880917887, 546.0008185021401, 550.8402563256691, 562.4909456941474, 575.0942265931092, 506.33417056570494, 556.785637529554, 601.9465934614425, 634.5970308853911, 668.0180135201247, 8739.694110706221, 8805.867911981326, 8837.381383303107, 8843.911560884437, 8859.76824638441, 628.5191828251392, 664.2759392262457, 677.0521270221631, 698.3923986132097, 713.8929160625844, 3695.427208782643, 3717.249534720139, 3817.8472861085247, 3839.9232015359535, 3880.243352546283, 58079.12881306699, 59286.70356648189, 59759.0, 59846.70076649676, 59903.40096599065, 51163.0, 51305.48694513007, 51861.48138518567, 52211.0, 52519.73740131351, 47666.0, 48033.27950080681, 48039.03921921542, 48260.517394826304, 48692.75653621709, 7368.447366447645, 7651.0, 7962.243586859239, 7970.322522585645, 8010.639521221676, 35588.822055889555, 36039.81980090083, 36803.0, 37377.0, 37429.0, 42274.577254227064, 45540.0, 47190.76404617955, 47392.52607474019, 47956.28065579089, 10158.18734501253, 10473.842892356774, 10490.160787137163, 10757.623483178051, 10933.83599246028, 11643.126765492452, 11691.298522088702, 11867.58463453775, 11894.09694448884, 11906.464209110783, 11005.944970275097, 11036.944815275872, 11062.889371106186, 11082.445877706245, 11150.665480035612, 11037.613683521038, 11058.39178845139, 11070.667879963614, 11088.5564577416, 11101.444927753419, 10880.129589632968, 10889.401082940514, 10898.237123401286, 10915.181361397768, 11009.944950275198, 8874.955625221832, 9013.18881300686, 9212.447253164832, 9361.0, 10260.384376937407, 9337.787972474482, 9347.289355634923, 9706.077922597342, 9839.65561205383, 10012.899871001197], "unit": "ms", "name": "yui.html", "value": 6331.696085786336}], "name": "dromaeo_css", "value": 6933.520534090624}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 111.0, 104.0, 110.0, 112.0, 110.0, 101.0, 112.0, 110.0, 101.0], "unit": "ms", "name": "ai-astar", "value": 108.1}, {"lowerIsBetter": true, "replicates": [147.0, 124.0, 163.0, 167.0, 132.0, 163.0, 157.0, 126.0, 166.0, 133.0], "unit": "ms", "name": "audio-beat-detection", "value": 147.8}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 159.0, 160.0, 164.0, 159.0, 159.0, 160.0, 158.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.1}, {"lowerIsBetter": true, "replicates": [70.0, 71.0, 87.0, 93.0, 71.0, 71.0, 70.0, 129.0, 99.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 83.2}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 141.0, 93.0, 94.0, 93.0, 138.0, 93.0, 93.0, 136.0], "unit": "ms", "name": "audio-oscillator", "value": 106.7}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 110.0, 109.0, 109.0, 108.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 145.0, 145.0, 147.0, 146.0, 145.0, 145.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [59.0, 59.0, 61.0, 59.0, 58.0, 58.0, 58.0, 59.0, 62.0, 57.0], "unit": "ms", "name": "json-parse-financial", "value": 59.0}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 70.0, 70.0, 70.0, 71.0, 71.0, 76.0, 71.0, 71.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 70.8}, {"lowerIsBetter": true, "replicates": [83.0, 84.0, 85.0, 86.0, 84.0, 85.0, 85.0, 86.0, 85.0, 86.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 84.9}, {"lowerIsBetter": true, "replicates": [120.0, 116.0, 116.0, 116.0, 120.0, 118.0, 118.0, 118.0, 122.0, 123.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 118.7}, {"lowerIsBetter": true, "replicates": [140.0, 149.0, 149.0, 140.0, 139.0, 138.0, 147.0, 147.0, 147.0, 144.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 144.0}, {"lowerIsBetter": true, "replicates": [65.0, 66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0, 66.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.8}], "name": "kraken", "value": 1499.8000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.85328058028355], "unit": "score", "name": "Richards", "value": 214.14193200000003}, {"lowerIsBetter": false, "replicates": [196.23233908948194], "unit": "score", "name": "DeltaBlue", "value": 336.9373280000001}, {"lowerIsBetter": false, "replicates": [283.5270768358378], "unit": "score", "name": "Encrypt", "value": 938.8203869999999}, {"lowerIsBetter": false, "replicates": [5133.333333333333], "unit": "score", "name": "Decrypt", "value": 51.85344155844158}, {"lowerIsBetter": false, "replicates": [1128.6681715575621], "unit": "score", "name": "RayTrace", "value": 655.6302539999998}, {"lowerIsBetter": false, "replicates": [791.1392405063291], "unit": "score", "name": "Earley", "value": 842.4092320000002}, {"lowerIsBetter": false, "replicates": [12280.487804878048], "unit": "score", "name": "Boyer", "value": 54.27007547169812}, {"lowerIsBetter": false, "replicates": [26368.42105263158], "unit": "score", "name": "RegExp", "value": 34.54833333333334}, {"lowerIsBetter": false, "replicates": [573.7234652897304], "unit": "score", "name": "Splay", "value": 142.038813}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 20979.570249206394}]} 06:05:52 INFO - RETURN: dromaeo_css: 6933.52 06:05:52 INFO - RETURN: kraken: 1499.80 06:05:52 INFO - RETURN: v8_7: 20979.57 06:05:52 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "20979.57"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1499.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6933.52"}}} 06:05:52 INFO - Return code: 0 06:05:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 06:05:52 INFO - # TBPL SUCCESS # 06:05:52 INFO - Running post-action listener: _resource_record_post_action 06:05:52 INFO - Running post-run listener: _resource_record_post_run 06:05:53 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 12288; Write bytes: 516997120; Read time: 332; Write time: 623832 06:05:53 INFO - install - Wall time: 15s; CPU: 16.0%; Read bytes: 4096; Write bytes: 250359808; Read time: 144; Write time: 459056 06:05:54 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 8192; Write bytes: 266637312; Read time: 188; Write time: 164776 06:05:54 INFO - Running post-run listener: _upload_blobber_files 06:05:54 INFO - Blob upload gear active. 06:05:54 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 06:05:54 INFO - Copying logs to upload dir... 06:05:54 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1258.766242 ========= master_lag: 37.39 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2015-11-26 06:06:30.977012) ========= ========= Started set props: build_url (results: 0, elapsed: 1 secs) (at 2015-11-26 06:06:30.980526) ========= 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=004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009891 build_url: 'https://queue.taskcluster.net/v1/task/BSnnqITfTGiH3A2lF6X2Dw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 1.81 ========= ========= Finished set props: build_url (results: 0, elapsed: 1 secs) (at 2015-11-26 06:06:32.800654) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 06:06:32.800989) ========= 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=004b26eee9c3b360c8654f40000001e1-1448541389.523362-1293040014 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.003209 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 06:06:32.815144) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-26 06:06:32.815439) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-26 06:06:32.816594) ========= ========= Total master_lag: 39.33 =========