builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-046 starttime: 1451946148.3 results: success (0) buildid: 20160104120315 builduid: 1971fa072b874cb6a4e6e819b20da22a revision: de0d8df586dc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:28.299820) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:28.300580) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:28.301104) ========= 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=652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006250 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:28.325158) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:28.325508) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:28.341807) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:28.342433) ========= 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=652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004592 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:28.394107) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:28.394479) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:28.394880) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:28.395151) ========= 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=652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-04 14:22:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.3M=0s 2016-01-04 14:22:28 (43.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.170052 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:28.578092) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:28.578423) ========= 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=652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021501 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:28.628880) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:28.629226) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev de0d8df586dc --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev de0d8df586dc --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=652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-04 14:22:28,974 Setting DEBUG logging. 2016-01-04 14:22:28,974 attempt 1/10 2016-01-04 14:22:28,974 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/de0d8df586dc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-04 14:22:29,223 unpacking tar archive at: mozilla-beta-de0d8df586dc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.531050 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:29.173408) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:29.173953) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:29.184708) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:29.185116) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-04 14:22:29.185636) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-01-04 14:22:29.185959) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:22:29 INFO - MultiFileLogger online at 20160104 14:22:29 in /builds/slave/test-pgo 14:22:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 14:22:29 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 14:22:29 INFO - {'append_to_log': False, 14:22:29 INFO - 'base_work_dir': '/builds/slave/test-pgo', 14:22:29 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:22:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 14:22:29 INFO - 'branch': 'Mozilla-Beta', 14:22:29 INFO - 'buildbot_json_path': 'buildprops.json', 14:22:29 INFO - 'config_files': ('talos/linux_config.py',), 14:22:29 INFO - 'default_actions': ('clobber', 14:22:29 INFO - 'read-buildbot-config', 14:22:29 INFO - 'download-and-extract', 14:22:29 INFO - 'populate-webroot', 14:22:29 INFO - 'create-virtualenv', 14:22:29 INFO - 'install', 14:22:29 INFO - 'run-tests'), 14:22:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:22:29 INFO - 'download_minidump_stackwalk': True, 14:22:29 INFO - 'download_symbols': 'ondemand', 14:22:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:22:29 INFO - 'tooltool.py': '/tools/tooltool.py', 14:22:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:22:29 INFO - '/tools/misc-python/virtualenv.py')}, 14:22:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:22:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:22:29 INFO - 'installer_path': 'installer.exe', 14:22:29 INFO - 'log_level': 'info', 14:22:29 INFO - 'log_name': 'talos', 14:22:29 INFO - 'log_to_console': True, 14:22:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:22:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:22:29 INFO - 'opt_config_files': (), 14:22:29 INFO - 'pip_index': False, 14:22:29 INFO - 'sps_profile': False, 14:22:29 INFO - 'sps_profile_interval': 0, 14:22:29 INFO - 'suite': 'dromaeojs', 14:22:29 INFO - 'system_bits': '32', 14:22:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:22:29 INFO - 'title': 'talos-linux64-ix-046', 14:22:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:22:29 INFO - 'use_talos_json': True, 14:22:29 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 14:22:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:22:29 INFO - 'work_dir': 'build'} 14:22:29 INFO - ##### 14:22:29 INFO - ##### Running clobber step. 14:22:29 INFO - ##### 14:22:29 INFO - Running pre-action listener: _resource_record_pre_action 14:22:29 INFO - Running main action method: clobber 14:22:29 INFO - rmtree: /builds/slave/test-pgo/build 14:22:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 14:22:30 INFO - Running post-action listener: _resource_record_post_action 14:22:30 INFO - ##### 14:22:30 INFO - ##### Running read-buildbot-config step. 14:22:30 INFO - ##### 14:22:30 INFO - Running pre-action listener: _resource_record_pre_action 14:22:30 INFO - Running main action method: read_buildbot_config 14:22:30 INFO - Using buildbot properties: 14:22:30 INFO - { 14:22:30 INFO - "properties": { 14:22:30 INFO - "buildnumber": 60, 14:22:30 INFO - "product": "firefox", 14:22:30 INFO - "script_repo_revision": "production", 14:22:30 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 14:22:30 INFO - "repository": "", 14:22:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 14:22:30 INFO - "buildid": "20160104120315", 14:22:30 INFO - "slavename": "talos-linux64-ix-046", 14:22:30 INFO - "pgo_build": "True", 14:22:30 INFO - "basedir": "/builds/slave/test-pgo", 14:22:30 INFO - "project": "", 14:22:30 INFO - "platform": "ubuntu64_hw", 14:22:30 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:22:30 INFO - "slavebuilddir": "test", 14:22:30 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 14:22:30 INFO - "branch": "mozilla-beta", 14:22:30 INFO - "repo_path": "releases/mozilla-beta", 14:22:30 INFO - "stage_platform": "linux64-pgo", 14:22:30 INFO - "builduid": "1971fa072b874cb6a4e6e819b20da22a", 14:22:30 INFO - "revision": "de0d8df586dc" 14:22:30 INFO - }, 14:22:30 INFO - "sourcestamp": { 14:22:30 INFO - "repository": "", 14:22:30 INFO - "hasPatch": false, 14:22:30 INFO - "project": "", 14:22:30 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:22:30 INFO - "changes": [ 14:22:30 INFO - { 14:22:30 INFO - "category": null, 14:22:30 INFO - "files": [ 14:22:30 INFO - { 14:22:30 INFO - "url": null, 14:22:30 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.tar.bz2" 14:22:30 INFO - } 14:22:30 INFO - ], 14:22:30 INFO - "repository": "", 14:22:30 INFO - "rev": "de0d8df586dc", 14:22:30 INFO - "who": "sendchange", 14:22:30 INFO - "when": 1451946043, 14:22:30 INFO - "number": 7414625, 14:22:30 INFO - "comments": "Bug 1234458 P2 Lock the CacheChild actor while Cache DOM methods are running. r=ehsan a=ritu", 14:22:30 INFO - "project": "", 14:22:30 INFO - "at": "Mon 04 Jan 2016 14:20:43", 14:22:30 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:22:30 INFO - "revlink": "", 14:22:30 INFO - "properties": [ 14:22:30 INFO - [ 14:22:30 INFO - "buildid", 14:22:30 INFO - "20160104120315", 14:22:30 INFO - "Change" 14:22:30 INFO - ], 14:22:30 INFO - [ 14:22:30 INFO - "builduid", 14:22:30 INFO - "1971fa072b874cb6a4e6e819b20da22a", 14:22:30 INFO - "Change" 14:22:30 INFO - ], 14:22:30 INFO - [ 14:22:30 INFO - "pgo_build", 14:22:30 INFO - "True", 14:22:30 INFO - "Change" 14:22:30 INFO - ] 14:22:30 INFO - ], 14:22:30 INFO - "revision": "de0d8df586dc" 14:22:30 INFO - } 14:22:30 INFO - ], 14:22:30 INFO - "revision": "de0d8df586dc" 14:22:30 INFO - } 14:22:30 INFO - } 14:22:30 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.tar.bz2. 14:22:30 INFO - Running post-action listener: _resource_record_post_action 14:22:30 INFO - ##### 14:22:30 INFO - ##### Running download-and-extract step. 14:22:30 INFO - ##### 14:22:30 INFO - Running pre-action listener: _resource_record_pre_action 14:22:30 INFO - Running main action method: download_and_extract 14:22:30 INFO - mkdir: /builds/slave/test-pgo/build/tests 14:22:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/test_packages.json 14:22:30 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 14:22:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 14:22:30 INFO - Downloaded 1270 bytes. 14:22:30 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 14:22:30 INFO - Using the following test package requirements: 14:22:30 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:22:30 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:22:30 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 14:22:30 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:22:30 INFO - u'jsshell-linux-x86_64.zip'], 14:22:30 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:22:30 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 14:22:30 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:22:30 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:22:30 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 14:22:30 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:22:30 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 14:22:30 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:22:30 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 14:22:30 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:22:30 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:22:30 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 14:22:30 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:22:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:22:30 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:22:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:22:30 INFO - Downloaded 22572188 bytes. 14:22:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 14:22:30 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:22:30 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 14:22:31 INFO - Return code: 0 14:22:31 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:22:31 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')]} 14:22:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:22:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:22:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:22:31 INFO - Downloaded 22572188 bytes. 14:22:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 14:22:31 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:22:31 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 14:22:33 INFO - Return code: 0 14:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:33 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:22:33 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:22:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:22:33 INFO - Downloaded 11131970 bytes. 14:22:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 14:22:33 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:22:33 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 14:22:33 INFO - Return code: 0 14:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:33 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:22:33 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:22:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:22:34 INFO - Downloaded 56630385 bytes. 14:22:34 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:22:34 INFO - mkdir: /builds/slave/test-pgo/properties 14:22:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 14:22:34 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 14:22:34 INFO - Contents: 14:22:34 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:22:34 INFO - Running post-action listener: _resource_record_post_action 14:22:34 INFO - Running post-action listener: set_extra_try_arguments 14:22:34 INFO - ##### 14:22:34 INFO - ##### Running populate-webroot step. 14:22:34 INFO - ##### 14:22:34 INFO - Running pre-action listener: _resource_record_pre_action 14:22:34 INFO - Running main action method: populate_webroot 14:22:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:22:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:22:34 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 14:22:34 INFO - '../fennec_ids.txt'], 14:22:34 INFO - 'tests': ['tcheck2']}, 14:22:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:22:34 INFO - '--tppagecycles', 14:22:34 INFO - '7'], 14:22:34 INFO - 'tests': ['tsvgm']}}, 14:22:34 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 14:22:34 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 14:22:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:22:34 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:22:34 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 14:22:34 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:22:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:22:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:34 INFO - 'talos_options': ['--e10s'], 14:22:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:22:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:34 INFO - 'tests': ['damp', 'tps']}, 14:22:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:34 INFO - 'talos_options': ['--e10s'], 14:22:34 INFO - 'tests': ['damp', 'tps']}, 14:22:34 INFO - 'g3': {'tests': ['dromaeo_dom']}, 14:22:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:22:34 INFO - 'other': {'tests': ['a11yr', 14:22:34 INFO - 'ts_paint', 14:22:34 INFO - 'tpaint', 14:22:34 INFO - 'sessionrestore', 14:22:34 INFO - 'sessionrestore_no_auto_restore']}, 14:22:34 INFO - 'other-e10s': {'talos_options': ['--e10s'], 14:22:34 INFO - 'tests': ['a11yr', 14:22:34 INFO - 'ts_paint', 14:22:34 INFO - 'tpaint', 14:22:34 INFO - 'sessionrestore', 14:22:34 INFO - 'sessionrestore_no_auto_restore']}, 14:22:34 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 14:22:34 INFO - 'tests': ['a11yr', 14:22:34 INFO - 'ts_paint', 14:22:34 INFO - 'tpaint', 14:22:34 INFO - 'sessionrestore', 14:22:34 INFO - 'sessionrestore_no_auto_restore']}, 14:22:34 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 14:22:34 INFO - 'tests': ['a11yr', 14:22:34 INFO - 'ts_paint', 14:22:34 INFO - 'tpaint', 14:22:34 INFO - 'sessionrestore', 14:22:34 INFO - 'sessionrestore_no_auto_restore']}, 14:22:34 INFO - 'other_l64': {'tests': ['a11yr', 14:22:34 INFO - 'ts_paint', 14:22:34 INFO - 'tpaint', 14:22:34 INFO - 'sessionrestore', 14:22:34 INFO - 'sessionrestore_no_auto_restore']}, 14:22:34 INFO - 'other_nol64': {'tests': ['a11yr', 14:22:34 INFO - 'ts_paint', 14:22:34 INFO - 'tpaint', 14:22:34 INFO - 'sessionrestore', 14:22:34 INFO - 'sessionrestore_no_auto_restore']}, 14:22:34 INFO - 'svgr': {'tests': ['tsvgx', 14:22:34 INFO - 'tsvgr_opacity', 14:22:34 INFO - 'tart', 14:22:34 INFO - 'tscrollx', 14:22:34 INFO - 'cart']}, 14:22:34 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 14:22:34 INFO - 'tests': ['tsvgx', 14:22:34 INFO - 'tsvgr_opacity', 14:22:34 INFO - 'tart', 14:22:34 INFO - 'tscrollx', 14:22:34 INFO - 'cart']}, 14:22:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:34 INFO - 'tests': ['tp5o']}, 14:22:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:34 INFO - 'talos_options': ['--e10s'], 14:22:34 INFO - 'tests': ['tp5o']}, 14:22:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:34 INFO - 'talos_options': ['--xperf_path', 14:22:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:22:34 INFO - 'tests': ['tp5n']}, 14:22:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:34 INFO - 'talos_options': ['--e10s', 14:22:34 INFO - '--xperf_path', 14:22:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:22:34 INFO - 'tests': ['tp5n']}}, 14:22:34 INFO - 'talos.zip': {'path': '', 14:22:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:22:34 INFO - Running post-action listener: _resource_record_post_action 14:22:34 INFO - ##### 14:22:34 INFO - ##### Running create-virtualenv step. 14:22:34 INFO - ##### 14:22:34 INFO - Running pre-action listener: _resource_record_pre_action 14:22:34 INFO - Running main action method: create_virtualenv 14:22:34 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 14:22:34 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 14:22:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 14:22:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:22:34 INFO - Using real prefix '/usr' 14:22:34 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 14:22:35 INFO - Installing distribute.............................................................................................................................................................................................done. 14:22:37 INFO - Installing pip.................done. 14:22:37 INFO - Return code: 0 14:22:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 14:22:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:37 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafb70a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169d350>, '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 0x169e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x169e6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', '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 14:22:37 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 14:22:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:22:37 INFO - Using env: {'DISPLAY': ':0', 14:22:37 INFO - 'HOME': '/home/cltbld', 14:22:37 INFO - 'LANG': 'en_US.UTF-8', 14:22:37 INFO - 'LANGUAGE': 'en_US:en', 14:22:37 INFO - 'LOGNAME': 'cltbld', 14:22:37 INFO - 'MAIL': '/var/mail/cltbld', 14:22:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:37 INFO - 'MOZ_NO_REMOTE': '1', 14:22:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:37 INFO - 'NO_EM_RESTART': '1', 14:22:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:22:37 INFO - 'PWD': '/builds/slave/test-pgo', 14:22:37 INFO - 'SHELL': '/bin/bash', 14:22:37 INFO - 'SHLVL': '1', 14:22:37 INFO - 'TERM': 'linux', 14:22:37 INFO - 'TMOUT': '86400', 14:22:37 INFO - 'USER': 'cltbld', 14:22:37 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', 14:22:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:37 INFO - '_': '/tools/buildbot/bin/python'} 14:22:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:37 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:22:37 INFO - Cleaning up... 14:22:37 INFO - Return code: 0 14:22:37 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 14:22:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:37 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafb70a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169d350>, '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 0x169e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x169e6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', '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 14:22:37 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 14:22:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 14:22:37 INFO - Using env: {'DISPLAY': ':0', 14:22:37 INFO - 'HOME': '/home/cltbld', 14:22:37 INFO - 'LANG': 'en_US.UTF-8', 14:22:37 INFO - 'LANGUAGE': 'en_US:en', 14:22:37 INFO - 'LOGNAME': 'cltbld', 14:22:37 INFO - 'MAIL': '/var/mail/cltbld', 14:22:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:37 INFO - 'MOZ_NO_REMOTE': '1', 14:22:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:37 INFO - 'NO_EM_RESTART': '1', 14:22:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:22:37 INFO - 'PWD': '/builds/slave/test-pgo', 14:22:37 INFO - 'SHELL': '/bin/bash', 14:22:37 INFO - 'SHLVL': '1', 14:22:37 INFO - 'TERM': 'linux', 14:22:37 INFO - 'TMOUT': '86400', 14:22:37 INFO - 'USER': 'cltbld', 14:22:37 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', 14:22:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:37 INFO - '_': '/tools/buildbot/bin/python'} 14:22:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:37 INFO - Downloading/unpacking psutil>=0.7.1 14:22:37 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 14:22:37 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 14:22:37 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 14:22:37 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 14:22:37 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 14:22:37 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 14:22:39 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 14:22:39 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 14:22:39 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 14:22:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:22:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:22:39 INFO - Installing collected packages: psutil 14:22:39 INFO - Running setup.py install for psutil 14:22:39 INFO - building 'psutil._psutil_linux' extension 14:22:39 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 14:22:39 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 14:22:39 INFO - building 'psutil._psutil_posix' extension 14:22:39 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 14:22:39 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 14:22:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:22:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:22:39 INFO - Successfully installed psutil 14:22:39 INFO - Cleaning up... 14:22:39 INFO - Return code: 0 14:22:39 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 14:22:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:39 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 0x7fafb70a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169d350>, '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 0x169e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x169e6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', '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 14:22:39 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 14:22:39 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 14:22:39 INFO - Using env: {'DISPLAY': ':0', 14:22:39 INFO - 'HOME': '/home/cltbld', 14:22:39 INFO - 'LANG': 'en_US.UTF-8', 14:22:39 INFO - 'LANGUAGE': 'en_US:en', 14:22:39 INFO - 'LOGNAME': 'cltbld', 14:22:39 INFO - 'MAIL': '/var/mail/cltbld', 14:22:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:39 INFO - 'MOZ_NO_REMOTE': '1', 14:22:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:39 INFO - 'NO_EM_RESTART': '1', 14:22:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:22:39 INFO - 'PWD': '/builds/slave/test-pgo', 14:22:39 INFO - 'SHELL': '/bin/bash', 14:22:39 INFO - 'SHLVL': '1', 14:22:39 INFO - 'TERM': 'linux', 14:22:39 INFO - 'TMOUT': '86400', 14:22:39 INFO - 'USER': 'cltbld', 14:22:39 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', 14:22:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:39 INFO - '_': '/tools/buildbot/bin/python'} 14:22:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:40 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:41 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:22:41 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 14:22:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:22:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:22:41 INFO - Installing collected packages: mozsystemmonitor 14:22:41 INFO - Running setup.py install for mozsystemmonitor 14:22:41 INFO - Successfully installed mozsystemmonitor 14:22:41 INFO - Cleaning up... 14:22:42 INFO - Return code: 0 14:22:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 14:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:42 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafb70a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169d350>, '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 0x169e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x169e6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', '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 14:22:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 14:22:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:22:42 INFO - Using env: {'DISPLAY': ':0', 14:22:42 INFO - 'HOME': '/home/cltbld', 14:22:42 INFO - 'LANG': 'en_US.UTF-8', 14:22:42 INFO - 'LANGUAGE': 'en_US:en', 14:22:42 INFO - 'LOGNAME': 'cltbld', 14:22:42 INFO - 'MAIL': '/var/mail/cltbld', 14:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:42 INFO - 'MOZ_NO_REMOTE': '1', 14:22:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:42 INFO - 'NO_EM_RESTART': '1', 14:22:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:22:42 INFO - 'PWD': '/builds/slave/test-pgo', 14:22:42 INFO - 'SHELL': '/bin/bash', 14:22:42 INFO - 'SHLVL': '1', 14:22:42 INFO - 'TERM': 'linux', 14:22:42 INFO - 'TMOUT': '86400', 14:22:42 INFO - 'USER': 'cltbld', 14:22:42 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', 14:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:42 INFO - '_': '/tools/buildbot/bin/python'} 14:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:42 INFO - Downloading/unpacking blobuploader==1.2.4 14:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:43 INFO - Downloading blobuploader-1.2.4.tar.gz 14:22:43 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 14:22:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:22:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:44 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 14:22:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 14:22:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:44 INFO - Downloading docopt-0.6.1.tar.gz 14:22:44 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 14:22:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 14:22:44 INFO - Installing collected packages: blobuploader, requests, docopt 14:22:44 INFO - Running setup.py install for blobuploader 14:22:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:22:44 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 14:22:44 INFO - Running setup.py install for requests 14:22:45 INFO - Running setup.py install for docopt 14:22:45 INFO - Successfully installed blobuploader requests docopt 14:22:45 INFO - Cleaning up... 14:22:45 INFO - Return code: 0 14:22:45 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafb70a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169d350>, '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 0x169e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x169e6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', '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 14:22:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 14:22:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:22:45 INFO - Using env: {'DISPLAY': ':0', 14:22:45 INFO - 'HOME': '/home/cltbld', 14:22:45 INFO - 'LANG': 'en_US.UTF-8', 14:22:45 INFO - 'LANGUAGE': 'en_US:en', 14:22:45 INFO - 'LOGNAME': 'cltbld', 14:22:45 INFO - 'MAIL': '/var/mail/cltbld', 14:22:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:45 INFO - 'MOZ_NO_REMOTE': '1', 14:22:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:45 INFO - 'NO_EM_RESTART': '1', 14:22:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:22:45 INFO - 'PWD': '/builds/slave/test-pgo', 14:22:45 INFO - 'SHELL': '/bin/bash', 14:22:45 INFO - 'SHLVL': '1', 14:22:45 INFO - 'TERM': 'linux', 14:22:45 INFO - 'TMOUT': '86400', 14:22:45 INFO - 'USER': 'cltbld', 14:22:45 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', 14:22:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:45 INFO - '_': '/tools/buildbot/bin/python'} 14:22:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:22:45 INFO - Running setup.py (path:/tmp/pip-ZSNHkz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:22:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:22:45 INFO - Running setup.py (path:/tmp/pip-MfR3DM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:22:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:22:45 INFO - Running setup.py (path:/tmp/pip-ateoST-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:22:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:22:45 INFO - Running setup.py (path:/tmp/pip-Wgo0wJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:22:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:22:45 INFO - Running setup.py (path:/tmp/pip-7ISEHh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:22:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:22:45 INFO - Running setup.py (path:/tmp/pip-prIbFM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:22:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:22:45 INFO - Running setup.py (path:/tmp/pip-O7b6TQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:22:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:22:46 INFO - Running setup.py (path:/tmp/pip-ftFiob-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:22:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:22:46 INFO - Running setup.py (path:/tmp/pip-xldhdw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:22:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:22:46 INFO - Running setup.py (path:/tmp/pip-vHuf0b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:22:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:22:46 INFO - Running setup.py (path:/tmp/pip-FskBwE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:22:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:22:46 INFO - Running setup.py (path:/tmp/pip-Cmj_Ir-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:22:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:22:46 INFO - Running setup.py (path:/tmp/pip-aaAWDO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:22:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:22:46 INFO - Running setup.py (path:/tmp/pip-QQr9hb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:22:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:22:46 INFO - Running setup.py (path:/tmp/pip-a9ew86-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:22:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:22:46 INFO - Running setup.py (path:/tmp/pip-EYbvYB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:22:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:22:46 INFO - Running setup.py (path:/tmp/pip-5BGT_R-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:22:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:22:46 INFO - Running setup.py install for manifestparser 14:22:46 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 14:22:46 INFO - Running setup.py install for mozcrash 14:22:46 INFO - Running setup.py install for mozdebug 14:22:47 INFO - Running setup.py install for mozdevice 14:22:47 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 14:22:47 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 14:22:47 INFO - Running setup.py install for mozfile 14:22:47 INFO - Running setup.py install for mozhttpd 14:22:47 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 14:22:47 INFO - Running setup.py install for mozinfo 14:22:47 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 14:22:47 INFO - Running setup.py install for mozInstall 14:22:47 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 14:22:47 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 14:22:47 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 14:22:47 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 14:22:47 INFO - Running setup.py install for mozleak 14:22:47 INFO - Running setup.py install for mozlog 14:22:47 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 14:22:47 INFO - Running setup.py install for moznetwork 14:22:47 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 14:22:47 INFO - Running setup.py install for mozprocess 14:22:47 INFO - Running setup.py install for mozprofile 14:22:48 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 14:22:48 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 14:22:48 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 14:22:48 INFO - Running setup.py install for mozrunner 14:22:48 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 14:22:48 INFO - Running setup.py install for mozscreenshot 14:22:48 INFO - Running setup.py install for moztest 14:22:48 INFO - Running setup.py install for mozversion 14:22:48 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 14:22:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:22:48 INFO - Cleaning up... 14:22:48 INFO - Return code: 0 14:22:48 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:22:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafb70a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169d350>, '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 0x169e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x169e6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', '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 14:22:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 14:22:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:22:48 INFO - Using env: {'DISPLAY': ':0', 14:22:48 INFO - 'HOME': '/home/cltbld', 14:22:48 INFO - 'LANG': 'en_US.UTF-8', 14:22:48 INFO - 'LANGUAGE': 'en_US:en', 14:22:48 INFO - 'LOGNAME': 'cltbld', 14:22:48 INFO - 'MAIL': '/var/mail/cltbld', 14:22:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:48 INFO - 'MOZ_NO_REMOTE': '1', 14:22:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:48 INFO - 'NO_EM_RESTART': '1', 14:22:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:22:48 INFO - 'PWD': '/builds/slave/test-pgo', 14:22:48 INFO - 'SHELL': '/bin/bash', 14:22:48 INFO - 'SHLVL': '1', 14:22:48 INFO - 'TERM': 'linux', 14:22:48 INFO - 'TMOUT': '86400', 14:22:48 INFO - 'USER': 'cltbld', 14:22:48 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', 14:22:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:48 INFO - '_': '/tools/buildbot/bin/python'} 14:22:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:22:48 INFO - Running setup.py (path:/tmp/pip-ek6Z6b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:22:48 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)) 14:22:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:22:48 INFO - Running setup.py (path:/tmp/pip-9zUigi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:22:48 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)) 14:22:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:22:48 INFO - Running setup.py (path:/tmp/pip-6ZdV_J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:22:48 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)) 14:22:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:22:48 INFO - Running setup.py (path:/tmp/pip-rgzxdO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:22:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 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)) 14:22:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:22:49 INFO - Running setup.py (path:/tmp/pip-Q50byz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 14:22:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:22:49 INFO - Running setup.py (path:/tmp/pip-gFbabQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 14:22:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:22:49 INFO - Running setup.py (path:/tmp/pip-tKpD60-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 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)) 14:22:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:22:49 INFO - Running setup.py (path:/tmp/pip-sqq1YP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 14:22:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:22:49 INFO - Running setup.py (path:/tmp/pip-3Eu5Pr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 14:22:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:22:49 INFO - Running setup.py (path:/tmp/pip-XMUPlZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 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)) 14:22:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:22:49 INFO - Running setup.py (path:/tmp/pip-M1kMgx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 14:22:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:22:49 INFO - Running setup.py (path:/tmp/pip-n_Br44-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:22:49 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)) 14:22:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:22:49 INFO - Running setup.py (path:/tmp/pip-NwEdXy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:22:49 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)) 14:22:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:22:49 INFO - Running setup.py (path:/tmp/pip-5IHRt8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:22:49 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)) 14:22:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:22:49 INFO - Running setup.py (path:/tmp/pip-hUS7hv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:22:49 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)) 14:22:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:22:49 INFO - Running setup.py (path:/tmp/pip-LE8aIk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:22:49 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)) 14:22:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:22:49 INFO - Running setup.py (path:/tmp/pip-pXUMk6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:22:49 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)) 14:22:49 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)) 14:22:49 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)) 14:22:49 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)) 14:22:49 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.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 14:22:49 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.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 14:22:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 14:22:49 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 14:22:49 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 14:22:49 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 14:22:49 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 14:22:49 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 14:22:49 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 14:22:51 INFO - Downloading blessings-1.5.1.tar.gz 14:22:51 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 14:22:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 14:22:51 INFO - Installing collected packages: blessings 14:22:51 INFO - Running setup.py install for blessings 14:22:51 INFO - Successfully installed blessings 14:22:51 INFO - Cleaning up... 14:22:51 INFO - Return code: 0 14:22:51 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 14:22:51 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:22:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:22:52 INFO - Reading from file tmpfile_stdout 14:22:52 INFO - Current package versions: 14:22:52 INFO - argparse == 1.2.1 14:22:52 INFO - blessings == 1.5.1 14:22:52 INFO - blobuploader == 1.2.4 14:22:52 INFO - docopt == 0.6.1 14:22:52 INFO - manifestparser == 1.1 14:22:52 INFO - mozInstall == 1.12 14:22:52 INFO - mozcrash == 0.16 14:22:52 INFO - mozdebug == 0.1 14:22:52 INFO - mozdevice == 0.46 14:22:52 INFO - mozfile == 1.2 14:22:52 INFO - mozhttpd == 0.7 14:22:52 INFO - mozinfo == 0.8 14:22:52 INFO - mozleak == 0.1 14:22:52 INFO - mozlog == 3.0 14:22:52 INFO - moznetwork == 0.27 14:22:52 INFO - mozprocess == 0.22 14:22:52 INFO - mozprofile == 0.27 14:22:52 INFO - mozrunner == 6.11 14:22:52 INFO - mozscreenshot == 0.1 14:22:52 INFO - mozsystemmonitor == 0.0 14:22:52 INFO - moztest == 0.7 14:22:52 INFO - mozversion == 1.4 14:22:52 INFO - psutil == 3.1.1 14:22:52 INFO - requests == 1.2.3 14:22:52 INFO - wsgiref == 0.1.2 14:22:52 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:22:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:52 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 0x7fafb70a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169d350>, '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 0x169e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x169e6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', '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 14:22:52 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 14:22:52 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 14:22:52 INFO - Using env: {'DISPLAY': ':0', 14:22:52 INFO - 'HOME': '/home/cltbld', 14:22:52 INFO - 'LANG': 'en_US.UTF-8', 14:22:52 INFO - 'LANGUAGE': 'en_US:en', 14:22:52 INFO - 'LOGNAME': 'cltbld', 14:22:52 INFO - 'MAIL': '/var/mail/cltbld', 14:22:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:52 INFO - 'MOZ_NO_REMOTE': '1', 14:22:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:52 INFO - 'NO_EM_RESTART': '1', 14:22:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:22:52 INFO - 'PWD': '/builds/slave/test-pgo', 14:22:52 INFO - 'SHELL': '/bin/bash', 14:22:52 INFO - 'SHLVL': '1', 14:22:52 INFO - 'TERM': 'linux', 14:22:52 INFO - 'TMOUT': '86400', 14:22:52 INFO - 'USER': 'cltbld', 14:22:52 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', 14:22:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:52 INFO - '_': '/tools/buildbot/bin/python'} 14:22:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:52 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)) 14:22:52 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)) 14:22:52 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)) 14:22:52 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)) 14:22:52 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)) 14:22:52 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)) 14:22:52 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)) 14:22:52 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)) 14:22:52 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)) 14:22:52 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)) 14:22:52 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)) 14:22:52 INFO - Cleaning up... 14:22:52 INFO - Return code: 0 14:22:52 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 14:22:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:52 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 0x7fafb70a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169d350>, '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 0x169e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x169e6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', '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 14:22:52 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 14:22:52 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 14:22:52 INFO - Using env: {'DISPLAY': ':0', 14:22:52 INFO - 'HOME': '/home/cltbld', 14:22:52 INFO - 'LANG': 'en_US.UTF-8', 14:22:52 INFO - 'LANGUAGE': 'en_US:en', 14:22:52 INFO - 'LOGNAME': 'cltbld', 14:22:52 INFO - 'MAIL': '/var/mail/cltbld', 14:22:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:52 INFO - 'MOZ_NO_REMOTE': '1', 14:22:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:52 INFO - 'NO_EM_RESTART': '1', 14:22:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:22:52 INFO - 'PWD': '/builds/slave/test-pgo', 14:22:52 INFO - 'SHELL': '/bin/bash', 14:22:52 INFO - 'SHLVL': '1', 14:22:52 INFO - 'TERM': 'linux', 14:22:52 INFO - 'TMOUT': '86400', 14:22:52 INFO - 'USER': 'cltbld', 14:22:52 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', 14:22:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:52 INFO - '_': '/tools/buildbot/bin/python'} 14:22:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:52 INFO - Downloading/unpacking jsonschema 14:22:52 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 14:22:52 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 14:22:52 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 14:22:52 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 14:22:52 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 14:22:52 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 14:22:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:22:54 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 14:22:54 INFO - Downloading/unpacking functools32 (from jsonschema) 14:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:54 INFO - Downloading functools32-3.2.3-2.tar.gz 14:22:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 14:22:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 14:22:54 INFO - warning: no files found matching '*.txt' 14:22:54 INFO - no previously-included directories found matching 'build' 14:22:54 INFO - no previously-included directories found matching 'dist' 14:22:54 INFO - no previously-included directories found matching '.git*' 14:22:54 INFO - Installing collected packages: jsonschema, functools32 14:22:54 INFO - Running setup.py install for functools32 14:22:54 INFO - warning: no files found matching '*.txt' 14:22:54 INFO - no previously-included directories found matching 'build' 14:22:54 INFO - no previously-included directories found matching 'dist' 14:22:54 INFO - no previously-included directories found matching '.git*' 14:22:54 INFO - Successfully installed jsonschema functools32 14:22:54 INFO - Cleaning up... 14:22:54 INFO - Return code: 0 14:22:54 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 14:22:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:54 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafb70a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169d350>, '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 0x169e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x169e6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', '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 14:22:54 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', 'flake8'] in /builds/slave/test-pgo/build 14:22:54 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 flake8 14:22:54 INFO - Using env: {'DISPLAY': ':0', 14:22:54 INFO - 'HOME': '/home/cltbld', 14:22:54 INFO - 'LANG': 'en_US.UTF-8', 14:22:54 INFO - 'LANGUAGE': 'en_US:en', 14:22:54 INFO - 'LOGNAME': 'cltbld', 14:22:54 INFO - 'MAIL': '/var/mail/cltbld', 14:22:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:54 INFO - 'MOZ_NO_REMOTE': '1', 14:22:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:54 INFO - 'NO_EM_RESTART': '1', 14:22:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:22:54 INFO - 'PWD': '/builds/slave/test-pgo', 14:22:54 INFO - 'SHELL': '/bin/bash', 14:22:54 INFO - 'SHLVL': '1', 14:22:54 INFO - 'TERM': 'linux', 14:22:54 INFO - 'TMOUT': '86400', 14:22:54 INFO - 'USER': 'cltbld', 14:22:54 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', 14:22:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:54 INFO - '_': '/tools/buildbot/bin/python'} 14:22:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:54 INFO - Downloading/unpacking flake8 14:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:22:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 14:22:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:22:56 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 14:22:56 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 14:22:56 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 14:22:56 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 14:22:56 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 14:22:56 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 14:22:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:22:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 14:22:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:22:56 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 14:22:56 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 14:22:56 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 14:22:56 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 14:22:56 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 14:22:56 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 14:22:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 14:22:56 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:22:56 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 14:22:56 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 14:22:56 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 14:22:56 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 14:22:56 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 14:22:56 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 14:22:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 14:22:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 14:22:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:22:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:22:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:22:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:22:57 INFO - no previously-included directories found matching 'docs/_build' 14:22:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:22:57 INFO - Running setup.py install for pep8 14:22:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:22:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:22:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:22:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:22:57 INFO - no previously-included directories found matching 'docs/_build' 14:22:57 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 14:22:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:22:57 INFO - Cleaning up... 14:22:57 INFO - Return code: 0 14:22:57 INFO - Running post-action listener: _resource_record_post_action 14:22:57 INFO - Running post-action listener: _start_resource_monitoring 14:22:57 INFO - Starting resource monitoring. 14:22:57 INFO - ##### 14:22:57 INFO - ##### Running install step. 14:22:57 INFO - ##### 14:22:57 INFO - Running pre-action listener: _resource_record_pre_action 14:22:57 INFO - Running main action method: install 14:22:57 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:22:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:22:57 INFO - Reading from file tmpfile_stdout 14:22:57 INFO - Detecting whether we're running mozinstall >=1.0... 14:22:57 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 14:22:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 14:22:57 INFO - Reading from file tmpfile_stdout 14:22:57 INFO - Output received: 14:22:57 INFO - Usage: mozinstall [options] installer 14:22:57 INFO - Options: 14:22:57 INFO - -h, --help show this help message and exit 14:22:57 INFO - -d DEST, --destination=DEST 14:22:57 INFO - Directory to install application into. [default: 14:22:57 INFO - "/builds/slave/test-pgo"] 14:22:57 INFO - --app=APP Application being installed. [default: firefox] 14:22:57 INFO - mkdir: /builds/slave/test-pgo/build/application 14:22:57 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 14:22:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 14:23:11 INFO - Reading from file tmpfile_stdout 14:23:11 INFO - Output received: 14:23:11 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 14:23:11 INFO - Running post-action listener: _resource_record_post_action 14:23:11 INFO - ##### 14:23:11 INFO - ##### Running run-tests step. 14:23:11 INFO - ##### 14:23:11 INFO - Running pre-action listener: _resource_record_pre_action 14:23:11 INFO - Running main action method: run_tests 14:23:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 14:23:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 14:23:12 INFO - Python 2.7.3 14:23:12 INFO - Return code: 0 14:23:12 INFO - grabbing minidump binary from tooltool 14:23:12 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')]} 14:23:12 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 0x169d350>, '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 0x169e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x169e6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 14:23:12 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 14:23:12 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 14:23:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:23:12 INFO - Return code: 0 14:23:12 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 14:23:12 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 14:23:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 14:23:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 14:23:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 14:23:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 14:23:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 14:23:13 INFO - Return code: 0 14:23:13 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 14:23:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --webServer localhost 14:23:13 INFO - Using env: {'DISPLAY': ':0', 14:23:13 INFO - 'HOME': '/home/cltbld', 14:23:13 INFO - 'LANG': 'en_US.UTF-8', 14:23:13 INFO - 'LANGUAGE': 'en_US:en', 14:23:13 INFO - 'LOGNAME': 'cltbld', 14:23:13 INFO - 'MAIL': '/var/mail/cltbld', 14:23:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:23:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 14:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:13 INFO - 'MOZ_NO_REMOTE': '1', 14:23:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:23:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:13 INFO - 'NO_EM_RESTART': '1', 14:23:13 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:23:13 INFO - 'PWD': '/builds/slave/test-pgo', 14:23:13 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 14:23:13 INFO - 'SHELL': '/bin/bash', 14:23:13 INFO - 'SHLVL': '1', 14:23:13 INFO - 'TERM': 'linux', 14:23:13 INFO - 'TMOUT': '86400', 14:23:13 INFO - 'USER': 'cltbld', 14:23:13 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360', 14:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:13 INFO - '_': '/tools/buildbot/bin/python'} 14:23:13 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] with output_timeout 3600 14:23:13 INFO - mozversion INFO | application_buildid: 20160104120315 14:23:13 INFO - mozversion INFO | application_changeset: de0d8df586dc4eef07d8ad989ea1572a420dc417 14:23:13 INFO - mozversion INFO | application_display_name: Nightly 14:23:13 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:23:13 INFO - mozversion INFO | application_name: Firefox 14:23:13 INFO - mozversion INFO | application_remotingname: firefox 14:23:13 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:23:13 INFO - mozversion INFO | application_vendor: Mozilla 14:23:13 INFO - mozversion INFO | application_version: 44.0 14:23:13 INFO - mozversion INFO | platform_buildid: 20160104120315 14:23:13 INFO - mozversion INFO | platform_changeset: de0d8df586dc4eef07d8ad989ea1572a420dc417 14:23:13 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:23:13 INFO - mozversion INFO | platform_version: 44.0 14:23:13 INFO - 2016-01-04 14:23:13,220 DEBUG : using testdate: 1451946193 14:23:13 INFO - 2016-01-04 14:23:13,220 DEBUG : actual date: 1451946193 14:23:13 INFO - 2016-01-04 14:23:13,269 INFO : starting webserver on 'localhost:40677' 14:23:13 INFO - 2016-01-04 14:23:13,270 INFO : Starting test suite talos-linux64-ix-046 14:23:13 INFO - 2016-01-04 14:23:13,270 INFO : Starting test dromaeo_css 14:23:13 INFO - 2016-01-04 14:23:13,271 DEBUG : operating with platform_type : linux_ 14:23:13 INFO - 2016-01-04 14:23:13,271 INFO : Initialising browser for dromaeo_css test... 14:23:13 INFO - 2016-01-04 14:23:13,286 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpW6Dq8o/profile http://localhost:40677/getInfo.html 14:23:16 INFO - 2016-01-04 14:23:16,748 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:23:16 INFO - 2016-01-04 14:23:16,748 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:23:16 INFO - 2016-01-04 14:23:16,748 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:23:16 INFO - 2016-01-04 14:23:16,748 DEBUG : BROWSER_OUTPUT: __metrics 14:23:16 INFO - 2016-01-04 14:23:16,897 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] 14:23:17 INFO - 2016-01-04 14:23:17,962 INFO : Browser initialized. 14:23:17 INFO - 2016-01-04 14:23:17,962 INFO : Running cycle 1/1 for dromaeo_css test... 14:23:17 INFO - 2016-01-04 14:23:17,963 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpW6Dq8o/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:23:29 INFO - 2016-01-04 14:23:29,468 DEBUG : BROWSER_OUTPUT: RSS: Main: 128282624 14:23:29 INFO - 2016-01-04 14:23:29,468 DEBUG : BROWSER_OUTPUT: 14:24:20 INFO - 2016-01-04 14:24:20,350 DEBUG : BROWSER_OUTPUT: 1451946260349 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] 14:26:28 INFO - 2016-01-04 14:26:28,292 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40677/tests/dromaeo/cssquery-ext.html) 14:26:29 INFO - 2016-01-04 14:26:29,425 DEBUG : BROWSER_OUTPUT: RSS: Main: 237527040 14:26:29 INFO - 2016-01-04 14:26:29,425 DEBUG : BROWSER_OUTPUT: 14:29:31 INFO - 2016-01-04 14:29:31,474 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/dromaeo/cssquery-ext.html (next: http://localhost:40677/tests/dromaeo/cssquery-jquery.html) 14:29:32 INFO - 2016-01-04 14:29:32,035 DEBUG : BROWSER_OUTPUT: RSS: Main: 206856192 14:29:32 INFO - 2016-01-04 14:29:32,036 DEBUG : BROWSER_OUTPUT: 14:32:33 INFO - 2016-01-04 14:32:33,667 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40677/tests/dromaeo/cssquery-mootools.html) 14:32:34 INFO - 2016-01-04 14:32:34,249 DEBUG : BROWSER_OUTPUT: RSS: Main: 209338368 14:32:34 INFO - 2016-01-04 14:32:34,249 DEBUG : BROWSER_OUTPUT: 14:35:35 INFO - 2016-01-04 14:35:35,959 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40677/tests/dromaeo/cssquery-prototype.html) 14:35:36 INFO - 2016-01-04 14:35:36,525 DEBUG : BROWSER_OUTPUT: RSS: Main: 191057920 14:35:36 INFO - 2016-01-04 14:35:36,526 DEBUG : BROWSER_OUTPUT: 14:38:38 INFO - 2016-01-04 14:38:38,774 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40677/tests/dromaeo/cssquery-yui.html) 14:38:39 INFO - 2016-01-04 14:38:39,336 DEBUG : BROWSER_OUTPUT: RSS: Main: 191684608 14:38:39 INFO - 2016-01-04 14:38:39,336 DEBUG : BROWSER_OUTPUT: 14:41:41 INFO - 2016-01-04 14:41:41,549 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/dromaeo/cssquery-yui.html (next: http://localhost:40677/tests/dromaeo/cssquery-dojo.html) 14:41:41 INFO - 2016-01-04 14:41:41,808 DEBUG : BROWSER_OUTPUT: RSS: Main: 205848576 14:41:41 INFO - 2016-01-04 14:41:41,808 DEBUG : BROWSER_OUTPUT: 14:41:41 INFO - 2016-01-04 14:41:41,808 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:41:41 INFO - 2016-01-04 14:41:41,808 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:41:41 INFO - 2016-01-04 14:41:41,808 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:41:41 INFO - 2016-01-04 14:41:41,808 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:41:41 INFO - 2016-01-04 14:41:41,808 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3286.145602143443;3349.715274201693;3370.106921665759;3371.4774209997445;3405.1827561385276;5426.050441172799;5478.096114141167;5504.504594586487;5509.944900550993;5525.419830917756;5498.697571633568;5529.917051244234;5538.141588053848;5544.500994910457;5562.610617256793;4981.601471882241;5049.545540901309;5094.923576146361;5163.948360516403;5164.561012313939;4970.403551573816;5009.9749501252445;5020.347354843865;5038.84883453497;5098.980203959204;5115.9744201278945;5165.276861239411;5165.896682066356;5173.663711858736;5191.584673226152;4921.704697718148;5096.108181068335;5097.5667068299235;5155.02054609623;5156.510131537517;4862.82980095695;4947.158982972867;4975.825846095369;4989.176785830334;5008.824691135793;5294.735263236823;5350.117230656937;5385.919211211835;5412.052890744143;5422.15956526739;5907.527397808165;5966.105084237356;5972.6715030673295;5978.641281523122;6038.57729958903;5876.29484461863;5957.791477298274;5962.3739507351975;5963;5973.8207853764025;5595.916061259084;5672.8014519492035;5675.489205971482;5686.232358631614;5689.231953686241;5826.300843898759;5931.555133365015;5964;6121.663308518075;6136.417040381246;5970.970145149333;5973.581849270597;5980.910286345708;5982.670953097534;5993.0710739835395;6062.939370606326;6065.787697430657;6069.423404776539;6074.149619053336;6075.058365953284;5776.797812076641;5820.214271073351;5947;5953.404659534099;6024.939750602525;5931.940680593225;5952.702364881825;5954.46409823118;5967.850803729854;5981.910271345933;4550.453945526492;4561.635069194456;4562.201614717411;4562.771861406916;4591.540845915381;3982.4623675803396;4016.879493615196;4072.450219220427;4097.713160078766;4102.2000709861295;5557.055300598908;5566.2207290979295;5575.303087114111;5592.748326325325;5641.548676105902;5776.653400795965;5954.791582294599;5969.552283578662;5978.701064946736;5987.64074155552;5511.834644960658;5547.5007249347755;5559.471850174227;5595.832125036255;5622.072358060956;5193.37679478465;5224.895502089937;5227.111391063529;5230.97384513075;5253.894922101613;5976.551758618121;6063.575549711478;6157.8902654159365;6217.844553886099;6222.222222222223;6005.189299444519;6079.756809727562;6104.145419641165;6109.961306577981;6115.908261376172;6207.193064901534;6212.937870621417;6215.81352559424;6218.595791273553;6238.064290356483;5745.253117094751;5762.337331206965;5862.736176871966;5978.730957106854;5980.611260268069;5940.138679891478;5967.76128954837;5974.850628734316;5977.462028417463;5991.131285963424;5839.474447299764;5841.766329346779;5845.561582881216;5848.239728835224;5848.970755146197;5654.89729502743;5734.971325143348;5771.884562308815;5844.6785426801935;5860.94139058621;5712.514436272963;5794.507466865363;5809.477147056785;5814.244148260868;5815.244018277597;5894.351621321565;5899.882002360015;5912.438318359749;5913.142594323713;5917.644941303535;5565.5825813063375;5638.605297629127;5690.430956904276;5694.117411801178;5758.596898217084 14:41:41 INFO - 2016-01-04 14:41:41,809 DEBUG : BROWSER_OUTPUT: |1;ext.html;594.3877805859966;638.5306799502366;645.7191121861989;671.6708812681787;676.404763807849;44643.33035004477;45510.77244613781;45589.77205113974;45660.31509527356;45665;48302;48828;48852.75573622127;48911.755441222835;48952.75523622377;8424.452410593323;8572.271356934636;9256.907430925672;10836.295640783363;11014.889851101467;11087.944560277187;11172;11174.1060715143;11182.105431565455;11208.887911120906;11188.440577971109;11277.887221127807;11289.830652540219;11304.547818087267;11308;11140.777184456307;11210.495527701192;11217.831732523979;11233.831492527619;11240.662780116569;11008.174386920933;11106.500207490602;11122.61070862524;11138.94430527842;11142.331460112418;46041.53958460406;46562.30156547655;46568.53431465676;46584;46591.534084659055;3687.8524859005606;3758.0980564664283;4057.2494088593703;4243.787810609457;4552.817887284505;34463.31073378544;34918.65081349205;34950;34969;35040;3420.9828950855335;3467.081223475781;3493.161641206092;3514.5782506099176;3781.111438811877;312579.99999999773;316959;317539;318102;318197;54224;78781;80316.19683803087;80332.59833700793;80516.19483805087;72627.63686181535;72651;72714.63642681752;73064.63467682627;73154.63422682852;18554.165062572218;18722.063896805106;18737.90631046836;18813.247470101316;18819.247230110643;14093.097431846774;14424.567262982127;14431.061980971203;14439.78340324875;14482.927585362006;23163.88418057899;23354.64968025481;23412.64881026786;23451.76548234496;23500.942457589448;8252.669893204325;8399.496030238206;8404.453710508797;8450.154984501502;8474.237318641352;1007.7302598725659;1039.6776999130293;1131.2307630810963;1158.3108050709814;1173.2491205628405;30827.537586936214;30911.845440772653;32577.348453030805;33425.49861752076;33747;1077.7129754249509;1124.5670416889557;1135.2677522997785;1165.9708507287385;1221.5480272299128;14596.854031459761;16092.67814643724;16131.193440327937;16290.51128466148;16314.673706525802;367545;371817;372189;373767;374934;61226.0816087759;61392.69303653453;61404;61409.38590614126;61494.38505614976;1437.6908964572667;1482.5996980815119;1515.0758037597057;1520.9847901521287;1527.938882444712;29003.709962900946;29723.40553188881;29817.850910745306;30268.84865575658;30522.08433746991;52466.73766631143;52719.20921186109;52913.47086529085;52940;52961;245998;246559;247414;248006;248761;34874.651253488155;35342.64657353394;35350.82324588463;35766.821165894005;35829.64170358367;50095;51421.48578514167;51551;52045;52441.47558524366;42394.36408453814;43412.565874342115;43538;43716;44590;41499.7925010373;41799.58200418078;42116.78941605272;43072.35391469193;43342.34986475142;41198;41647.1670566593;42905.57094429016;42976;43207;33320.50019249665;34070.48894266637;34266.10295946282;36824;37160.44259336166;27571.86214068917;28077;28112;28154.85922570374;28200.85899570489 14:41:41 INFO - 2016-01-04 14:41:41,809 DEBUG : BROWSER_OUTPUT: |2;jquery.html;382.8009435093753;385.4757529759527;385.8553042609022;386.2564563215809;386.73508646577096;14452.783208251885;18403.815961840377;20883.582328353463;21150.788492115054;21169;20018.899905500453;20023.799762002374;20472.28347007855;20482.18071277147;20496.89751551244;5820.2433683621175;5939.762409503614;5943.375945525722;5944.762209511614;5948.345681974979;5827.737751801179;5971.164037034819;5977.073553599199;5983.252093488314;6020.548458865603;4412.404325416076;4534.455865296169;5936.287645482526;5940.108983652465;5942.019566771478;6310.684465776716;6458.644774537399;6485.351464853524;6505.284418713938;6505.6421896795655;5783.479486846204;5888.205092312569;5926.081457374114;5935.2877654681715;5942.286925568917;23717.88141059301;24491.632625510632;24560.508789824282;24984.50030999388;25430.745692543023;779.2246714519033;779.5790273252437;2317.316391664463;2437.2566367257978;2528.1431981860565;14491.275436228147;14749.926250368679;14870.553883383516;15124.168170750605;15268.465603703817;1910.1786231920269;1939.9030048497518;2010.5476267839765;2062.257587268582;2074.9066292016746;69654.65172674155;70582.29417705808;71013.64493177553;71518.99999999948;71741.64129179373;11724.179307448398;12032.458539365749;12139.757204855854;12150.21023633461;12156.149069565223;16115.274812633459;16159.67680646404;16173.838261617233;16174.27215775293;16183.676326473405;9997.350172238781;10467.633632822966;10713.142948564097;10750.569977200825;10921.453927303603;9196.080391960884;9252.259819214447;9331.113544213289;9379.343445958717;9537.284703647116;8665.610047547876;8811.823763524693;8838;8841.115888411236;8872.778180545538;3204.6154461464776;3293.3084052349277;3295.6045274567205;3307.6196237432523;3406.710429613461;461.8614415675351;510.8518529626441;539.7058603061341;541.9295491586147;542.888707814897;12823.871761282455;12971.740565188644;13551.59345219645;13834.584962451143;13967.161970281815;528.0916823064323;537.9031774280588;559.5495626021011;571.3914680864866;574.6121368076615;6281.560290779602;6974.197967233732;7023.122109736283;7150.249223831423;7305.232950540223;592491;596533;602849;609753;610382;83945;86631.56684216665;87209;88802;88816.55591722;583.7081459270365;599.7421108923205;607.1621162795315;613.3406587918006;614.4623454477422;11739.941300293443;11907.226030308002;11983.22109062943;12031.27812331263;12215;39861.60138398579;40487.797561012005;41472.792636036625;42067.7896610515;42094.57905421029;53671.73164134155;55218.72390638021;56673;56707.43292567186;57880;7564.886526702214;7575.318221360104;7604.581748003691;7626.046744156981;7669.846603068019;13746.243956582484;14086.788698169741;14249.857501425267;14296.428142874169;14330.928345358207;11989.82015269789;12114.818277725664;12230.14388992762;12412.503499859904;12427.192232504858;11697.473613687574;11857.229280096766;12037.337946412681;12068.517259309529;12250.938745306217;11636.360000199731;11945;12529.310887901252;12542.059345548938;12652.936735316265;6430.196225471816;7395.852082958419;7472.14070381913;7475.514091584029;7575.469717119749;8629.611667474854;12156.088293377856;12231.69420764488;12425.37873106359;12511.687207819878 14:41:41 INFO - 2016-01-04 14:41:41,809 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1259.4458438287154;1284.6916739982407;1290.406413049997;1798.1279079646374;1819.6451691920072;7078.469114816384;7240.5293655912465;7946.24510671486;8150;8157.714479993201;7794.805129871742;7975.4815936964205;8046.396520260989;8049.275565199131;8055.959720201406;2251.279590531031;2281.247188427823;2283.56612243674;2284.326123793485;2284.817214622826;2051.0872661665567;2097.590969760897;2298.195631528968;2315.1896836107394;2357.5167090746354;2238.5858616155983;2247.8089362404216;2254.256095488493;2254.797068263856;2263.2644390573046;2290.6564015397657;2310.8960096795518;2311.514581937795;2312.236961802601;2316.247219653611;2241.2939923923914;2245.1019592163266;2246.84272100953;2250.7524172341027;2252.1666983216314;7846.215378462165;7923.088844782808;7931.167227441093;7935.841283174362;7963.203679632048;4970.826021089245;5036.496350364971;5039.672421292641;5039.949600504021;5055.191169412877;3263.1515805890403;3270.9509357359657;3345.7657963942534;3350.8827191048194;3356.110630682871;1056.7675111475462;1071.4589132488122;1074.1406874500447;1104.3263609198382;1107.5735841700923;11051.502682379312;11051.889481105167;11066.723331916766;11078.169137314653;11081.058110060654;4453.287474004143;4468.776561171929;4517.322401639714;4523.434570678665;4531.56950089741;9469.289803264783;9507.144357007903;9508.904910950801;9509.714708558755;9511.191548718298;4919.655624106278;4935.3090567320605;4936.950630493721;4938.9012219755405;4939;4529.297958816389;4534.274516077412;4536.546345365503;4537.863864084083;4539.114872599811;3951.5258169019326;3982.960170398259;3995.220931918306;4004.0790618157985;4037.9905023744063;1152.343164396286;1198.6823491774687;1259.6661884600587;1291.7481091187317;1299.857015728269;1164.6913567904512;1164.8369228308043;1166.8424762657103;1217.1175897474436;1231.8275441438207;7530.209328020588;7568.281013303727;7585.658645360972;7586.582737949356;7775.611219439005;1213.1932265043824;1217.5190799634095;1217.5799349224503;1231.9938400307942;1238.2570457725292;6270.184875966095;6287.151234583364;6350.682465876687;6359.682015899187;6364.045393191059;9558.330916835755;9727.9027209727;9733.707988760207;9742.171915387129;9746.025397460196;9473.336866419284;9637.662681806103;9638.662646807468;9644;9653;7512.436567257478;7539.208383119803;7586.848263034708;7596.734114305973;7599.886001709979;7338.816529586589;7348.154962179417;7377.3360397564475;7404.259574042552;7414.073240844895;8534.146585341416;8643.394962352575;8651.6971905983;8680.78298042554;8686.695965641173;8661.566921654017;8844.601992910206;8845.469271843453;8851.601677924638;8999.460032398078;6938.5836849789175;6989.965050174716;6993.650317484004;6994.685239164148;7012.964935175291;7091.11361079865;7624.656890440055;7912.0901096374655;7934.603269836601;7973.122956474667;7005.299470053158;7297.708091676486;7392.408607311295;7512.098548174254;7628;6981.127359080115;7029.05107810439;7055.894161587477;7125.857482850418;7230.457715671451;7273.454490913097;7319.817004574928;7323;7329.890051649336;7330.230325815713;6713.63075030878;6728.865422691617;6732.932670673231;6953.23514413404;6966.3730264276455;6683.665816709048;6713.765018224534;6718.093057437183;6718.6976586054725;6726.192856857208 14:41:41 INFO - 2016-01-04 14:41:41,809 DEBUG : BROWSER_OUTPUT: |4;prototype.html;350.245221547565;356.9214772749994;357.13573152969826;358.449779588332;359.2276605298608;5749.252597162363;5896.204012458317;5920.615160014601;5943.465088142067;5961.672108034057;5735;5791.160281759142;5798.65208087515;5833.154192642064;5845.181674565553;4623.306504024398;4628.629709623222;4628.953710462886;4635.235186194274;4635.443746750395;4608.354830323758;4619.445666520022;4631.814727410916;4639.65202609804;4642.234031384834;4532.864014079566;4601.171789077965;4609.331646911196;4638.512956139591;4647.419072615923;4618.745968971705;4620.145273124466;4629.629629629621;4638.165130276549;4645.187092258858;3965.7620542767527;4113.732607380511;4123.690723195772;4128.215639028605;4128.649064829493;5507.834764957058;5510.421405752379;5600;5601.663900166003;5619.803306884281;5273.683578985273;5275;5275.973620131913;5276.868078298035;5303.920441193385;6131.14164017038;6176.413240742122;6209.099680546341;6212.347703491158;6214.65819379934;4356.760378179199;4451.577100175478;4455.086707225011;4467.195904737146;4491.056878055612;6318.842028949267;6381.074744162071;6387.010013447876;6392.232932048138;6393.073004414334;4530.70550414222;4535.4103966484145;4536.342230376643;4536.637069034469;4539.909201815945;4588.655850811136;4594.402727645384;4597.149527337453;4597.241455159928;4598.724076555417;5150.072986862401;5242.606804489678;5245.318108645852;5249.868753281198;5253.238280449314;6295.937040629535;6299.370062993756;6304.684765761694;6312.495000399957;6315;1651.265186991801;1666.8666506679438;1667.374734474572;1667.4914151184005;1667.65813008333;1114.5931734916696;1125.870524889648;1132.8923752243525;1133.8525991621202;1136.2216881436243;6092.421219984094;6116.327204007555;6138.324784273726;6139.109829074849;6143.6928153593035;6494.415502604788;6501.414872661483;6505.056766768792;6506.089147519351;6512.023196520465;6026.276846778327;6131.356207598227;6133.570650054543;6134.018557030854;6136.938630613726;3559.839807208682;3580.5703315602295;3583.7849729016343;3585.8565657373415;3586.4261718124976;6454.741810327534;6485.967570162214;6525.4779617630475;6583.473322134218;6651.800445986628;6571.901421478682;6599.538032337787;6622.072909792633;6627.469802415795;6632.369924857131;6611.23970743361;6614.7023383947835;6620.668966551653;6624.436922861514;6632.104665870046;6329.746810127727;6436.967815160894;6453.128827608401;6462.0306953955405;6463.192100987377;6455.870882582416;6477.222733272038;6483.967580162069;6484.643344616092;6485.675716214076;6692.83267918306;6694.531382803352;6706.798796036127;6714.160729908762;6719.697613607302;4423.77881105937;4496.595306422307;4497.168023915586;4500.729956202638;4514.819407223675;6330.905036424365;6388.073729309317;6402.647854368054;6402.839929001626;6411.711472983634;6211.937880621136;6253.1245625613365;6257.530685198537;6266.248050234001;6266.624002559861;6164.4143806338325;6206.255249370104;6214.098955651315;6246.375362463899;6252.468540173954;6188.195534580475;6231.065340199007;6233.657148856856;6235.282942461675;6240.7503699851495;6352.745890164527;6356.682165891594;6363.7772677956045;6364.904526432015;6367.267764207176;6045.637261764308;6132.969335153296;6146.569740118328;6150.354212807591;6150.446459818638 14:41:41 INFO - 2016-01-04 14:41:41,810 DEBUG : BROWSER_OUTPUT: |5;yui.html;776.4758787818225;804.7304153108709;897.5063714956774;928.1414691410446;965.7392504023909;15330.080195188297;15600.843991560081;15602.063876167438;15604.063756174623;15611.375544978215;14900.105993640362;14995.47515836946;15011.249437528135;15012.474563390284;15015.249237538106;788.5071830106184;799.940004499662;908.5593487158707;916.5234078279291;931.8509038553833;927.9072092790734;928.5496534180917;932.067932067932;934.313279739391;934.8457504511747;968.4237878462303;977.1938151025397;977.359829311802;977.9168770654503;979.1872745621117;810.7446154461326;819.2831272636444;959.7168835193635;964.4068897627978;971.6259240192577;718.7987363538218;720.7549433192739;720.8306048078697;721.0734206544618;721.0914248047445;14573.781393279003;14864.256787160599;14942.402303907831;14992.250387480692;15046.774298385533;13783.655408614764;14080;14104.294785260696;14167.57497275073;14311.499097531536;10588.470576471145;10772.030517253406;10841.620543281026;10844;11035.613753518588;3559.056849934769;3882.437046628223;3902.3756199008308;3966.4446977423186;3980.5621381647998;52800.735996319774;53676.46323536753;53693;53911.19133213044;54287;7635.350995165473;7637.4653774236385;7691.730789422343;7727.111382191008;7761.767146985487;13088.476460941456;13110.803337949937;13111.344432778324;13111.934440327928;13122.737545249041;7666.923330766621;7752.806179845436;7784.416168787364;7868.606569671608;7976.481528700616;4681.344611754357;4727.7163370197895;4765.69023013503;4766.594839438687;4791.6645834791925;2897.4060317634835;2912.51943429336;2915.0526079024403;2950.5131653276967;2952.70472952703;472.2561964905288;473.6826326361346;478.44739326078434;489.47381564817965;490.43109992408637;573.7704918032737;628.7924984755019;680.7821497120973;702.8629417263585;721.4949535325293;9025;9773.315867889321;9784.902150978542;9799.314048016571;9911.603535858629;535.3201434178649;540.9729513524308;654.8657525207416;691.3708525242006;745.1058729524592;4164.9583504165175;4515.661325400542;4614.215583350839;4835.2505361669;4858.684185527929;65609.67195163993;65846.34153658402;70731;70784;71197.64401178065;58138.418615814146;58159;58782.70608646929;58842.7057864708;60155.69922150449;53321.20018199808;53711.46288537143;53988;54015.45984540183;54291.45708542943;9210;9210.677626283048;9576;9655.420674759256;10094.596216151273;40801.38797918093;41205.793971029954;41386.58613413948;41471.58528414677;42021.78989105035;52601.473985259654;53426.46573534371;53550;53989.460105398444;54259;12042.157049006486;12589.055820813659;12608.621741347773;12743.553975610808;12803.61589152327;11961.102917281412;12370.44333005035;12871.549495767604;13274.93362533181;13767;12176.939115304367;12294.446749896097;12302.261864288172;12574.30841303737;12775.85017348443;12818.294993775431;12833.294168820805;12890.742185156058;12917.935410322889;13079.476820927437;11928.76142477163;12043.698907527381;12087.39563021863;12121.69695757613;12621;10726.785464290826;10771.51528181218;10793.190510711886;10992.803751796791;11781.116416268642;10757.354558726502;11152.832707509231;11163.386013769321;11164.385958772345;11221.71945701364 14:41:41 INFO - 2016-01-04 14:41:41,810 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:41:41 INFO - 2016-01-04 14:41:41,810 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:41:41 INFO - 2016-01-04 14:41:41,810 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,877 14:41:41 INFO - 2016-01-04 14:41:41,810 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:41:41 INFO - 2016-01-04 14:41:41,810 DEBUG : BROWSER_OUTPUT: __startTimestamp1451947301801__endTimestamp 14:41:41 INFO - 2016-01-04 14:41:41,810 DEBUG : BROWSER_OUTPUT: 14:41:41 INFO - 2016-01-04 14:41:41,811 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:41:41 INFO - 2016-01-04 14:41:41,811 DEBUG : BROWSER_OUTPUT: Number of tests: 6 14:41:41 INFO - 2016-01-04 14:41:41,811 DEBUG : BROWSER_OUTPUT: 14:41:41 INFO - 2016-01-04 14:41:41,811 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5537.90 Median:5745.25 stddev:619.02 (10.8%) stddev-sans-first:595.18 14:41:41 INFO - 2016-01-04 14:41:41,811 DEBUG : BROWSER_OUTPUT: Values: 3286.1 3349.7 3370.1 3371.5 3405.2 5426.1 5478.1 5504.5 5509.9 5525.4 5498.7 5529.9 5538.1 5544.5 5562.6 4981.6 5049.5 5094.9 5163.9 5164.6 4970.4 5010.0 5020.3 5038.8 5099.0 5116.0 5165.3 5165.9 5173.7 5191.6 4921.7 5096.1 5097.6 5155.0 5156.5 4862.8 4947.2 4975.8 4989.2 5008.8 5294.7 5350.1 5385.9 5412.1 5422.2 5907.5 5966.1 5972.7 5978.6 6038.6 5876.3 5957.8 5962.4 5963.0 5973.8 5595.9 5672.8 5675.5 5686.2 5689.2 5826.3 5931.6 5964.0 6121.7 6136.4 5971.0 5973.6 5980.9 5982.7 5993.1 6062.9 6065.8 6069.4 6074.1 6075.1 5776.8 5820.2 5947.0 5953.4 6024.9 5931.9 5952.7 5954.5 5967.9 5981.9 4550.5 4561.6 4562.2 4562.8 4591.5 3982.5 4016.9 4072.5 4097.7 4102.2 5557.1 5566.2 5575.3 5592.7 5641.5 5776.7 5954.8 5969.6 5978.7 5987.6 5511.8 5547.5 5559.5 5595.8 5622.1 5193.4 5224.9 5227.1 5231.0 5253.9 5976.6 6063.6 6157.9 6217.8 6222.2 6005.2 6079.8 6104.1 6110.0 6115.9 6207.2 6212.9 6215.8 6218.6 6238.1 5745.3 5762.3 5862.7 5978.7 5980.6 5940.1 5967.8 5974.9 5977.5 5991.1 5839.5 5841.8 5845.6 5848.2 5849.0 5654.9 5735.0 5771.9 5844.7 5860.9 5712.5 5794.5 5809.5 5814.2 5815.2 5894.4 5899.9 5912.4 5913.1 5917.6 5565.6 5638.6 5690.4 5694.1 5758.6 14:41:41 INFO - 2016-01-04 14:41:41,811 DEBUG : BROWSER_OUTPUT: 14:41:41 INFO - 2016-01-04 14:41:41,811 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:51697.73 Median:19221.58 stddev:82637.96 (429.9%) stddev-sans-first:82780.70 14:41:41 INFO - 2016-01-04 14:41:41,811 DEBUG : BROWSER_OUTPUT: Values: 594.4 638.5 645.7 671.7 676.4 44643.3 45510.8 45589.8 45660.3 45665.0 48302.0 48828.0 48852.8 48911.8 48952.8 8424.5 8572.3 9256.9 10836.3 11014.9 11087.9 11172.0 11174.1 11182.1 11208.9 11188.4 11277.9 11289.8 11304.5 11308.0 11140.8 11210.5 11217.8 11233.8 11240.7 11008.2 11106.5 11122.6 11138.9 11142.3 46041.5 46562.3 46568.5 46584.0 46591.5 3687.9 3758.1 4057.2 4243.8 4552.8 34463.3 34918.7 34950.0 34969.0 35040.0 3421.0 3467.1 3493.2 3514.6 3781.1 312580.0 316959.0 317539.0 318102.0 318197.0 54224.0 78781.0 80316.2 80332.6 80516.2 72627.6 72651.0 72714.6 73064.6 73154.6 18554.2 18722.1 18737.9 18813.2 18819.2 14093.1 14424.6 14431.1 14439.8 14482.9 23163.9 23354.6 23412.6 23451.8 23500.9 8252.7 8399.5 8404.5 8450.2 8474.2 1007.7 1039.7 1131.2 1158.3 1173.2 30827.5 30911.8 32577.3 33425.5 33747.0 1077.7 1124.6 1135.3 1166.0 1221.5 14596.9 16092.7 16131.2 16290.5 16314.7 367545.0 371817.0 372189.0 373767.0 374934.0 61226.1 61392.7 61404.0 61409.4 61494.4 1437.7 1482.6 1515.1 1521.0 1527.9 29003.7 29723.4 29817.9 30268.8 30522.1 52466.7 52719.2 52913.5 52940.0 52961.0 245998.0 246559.0 247414.0 248006.0 248761.0 34874.7 35342.6 35350.8 35766.8 35829.6 50095.0 51421.5 51551.0 52045.0 52441.5 42394.4 43412.6 43538.0 43716.0 44590.0 41499.8 41799.6 42116.8 43072.4 43342.3 41198.0 41647.2 42905.6 42976.0 43207.0 33320.5 34070.5 34266.1 36824.0 37160.4 27571.9 28077.0 28112.0 28154.9 28200.9 14:41:41 INFO - 2016-01-04 14:41:41,811 DEBUG : BROWSER_OUTPUT: 14:41:41 INFO - 2016-01-04 14:41:41,811 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31714.65 Median:23253.49 stddev:98585.17 (424.0%) stddev-sans-first:98833.65 14:41:41 INFO - 2016-01-04 14:41:41,811 DEBUG : BROWSER_OUTPUT: Values: 382.8 385.5 385.9 386.3 386.7 14452.8 18403.8 20883.6 21150.8 21169.0 20018.9 20023.8 20472.3 20482.2 20496.9 5820.2 5939.8 5943.4 5944.8 5948.3 5827.7 5971.2 5977.1 5983.3 6020.5 4412.4 4534.5 5936.3 5940.1 5942.0 6310.7 6458.6 6485.4 6505.3 6505.6 5783.5 5888.2 5926.1 5935.3 5942.3 23717.9 24491.6 24560.5 24984.5 25430.7 779.2 779.6 2317.3 2437.3 2528.1 14491.3 14749.9 14870.6 15124.2 15268.5 1910.2 1939.9 2010.5 2062.3 2074.9 69654.7 70582.3 71013.6 71519.0 71741.6 11724.2 12032.5 12139.8 12150.2 12156.1 16115.3 16159.7 16173.8 16174.3 16183.7 9997.4 10467.6 10713.1 10750.6 10921.5 9196.1 9252.3 9331.1 9379.3 9537.3 8665.6 8811.8 8838.0 8841.1 8872.8 3204.6 3293.3 3295.6 3307.6 3406.7 461.9 510.9 539.7 541.9 542.9 12823.9 12971.7 13551.6 13834.6 13967.2 528.1 537.9 559.5 571.4 574.6 6281.6 6974.2 7023.1 7150.2 7305.2 592491.0 596533.0 602849.0 609753.0 610382.0 83945.0 86631.6 87209.0 88802.0 88816.6 583.7 599.7 607.2 613.3 614.5 11739.9 11907.2 11983.2 12031.3 12215.0 39861.6 40487.8 41472.8 42067.8 42094.6 53671.7 55218.7 56673.0 56707.4 57880.0 7564.9 7575.3 7604.6 7626.0 7669.8 13746.2 14086.8 14249.9 14296.4 14330.9 11989.8 12114.8 12230.1 12412.5 12427.2 11697.5 11857.2 12037.3 12068.5 12250.9 11636.4 11945.0 12529.3 12542.1 12652.9 6430.2 7395.9 7472.1 7475.5 7575.5 8629.6 12156.1 12231.7 12425.4 12511.7 14:41:41 INFO - 2016-01-04 14:41:41,813 DEBUG : BROWSER_OUTPUT: 14:41:41 INFO - 2016-01-04 14:41:41,813 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5658.06 Median:6698.65 stddev:2914.81 (43.5%) stddev-sans-first:2904.23 14:41:41 INFO - 2016-01-04 14:41:41,813 DEBUG : BROWSER_OUTPUT: Values: 1259.4 1284.7 1290.4 1798.1 1819.6 7078.5 7240.5 7946.2 8150.0 8157.7 7794.8 7975.5 8046.4 8049.3 8056.0 2251.3 2281.2 2283.6 2284.3 2284.8 2051.1 2097.6 2298.2 2315.2 2357.5 2238.6 2247.8 2254.3 2254.8 2263.3 2290.7 2310.9 2311.5 2312.2 2316.2 2241.3 2245.1 2246.8 2250.8 2252.2 7846.2 7923.1 7931.2 7935.8 7963.2 4970.8 5036.5 5039.7 5039.9 5055.2 3263.2 3271.0 3345.8 3350.9 3356.1 1056.8 1071.5 1074.1 1104.3 1107.6 11051.5 11051.9 11066.7 11078.2 11081.1 4453.3 4468.8 4517.3 4523.4 4531.6 9469.3 9507.1 9508.9 9509.7 9511.2 4919.7 4935.3 4937.0 4938.9 4939.0 4529.3 4534.3 4536.5 4537.9 4539.1 3951.5 3983.0 3995.2 4004.1 4038.0 1152.3 1198.7 1259.7 1291.7 1299.9 1164.7 1164.8 1166.8 1217.1 1231.8 7530.2 7568.3 7585.7 7586.6 7775.6 1213.2 1217.5 1217.6 1232.0 1238.3 6270.2 6287.2 6350.7 6359.7 6364.0 9558.3 9727.9 9733.7 9742.2 9746.0 9473.3 9637.7 9638.7 9644.0 9653.0 7512.4 7539.2 7586.8 7596.7 7599.9 7338.8 7348.2 7377.3 7404.3 7414.1 8534.1 8643.4 8651.7 8680.8 8686.7 8661.6 8844.6 8845.5 8851.6 8999.5 6938.6 6990.0 6993.7 6994.7 7013.0 7091.1 7624.7 7912.1 7934.6 7973.1 7005.3 7297.7 7392.4 7512.1 7628.0 6981.1 7029.1 7055.9 7125.9 7230.5 7273.5 7319.8 7323.0 7329.9 7330.2 6713.6 6728.9 6732.9 6953.2 6966.4 6683.7 6713.8 6718.1 6718.7 6726.2 14:41:41 INFO - 2016-01-04 14:41:41,813 DEBUG : BROWSER_OUTPUT: 14:41:41 INFO - 2016-01-04 14:41:41,813 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5269.75 Median:6035.96 stddev:1544.89 (25.6%) stddev-sans-first:1504.45 14:41:41 INFO - 2016-01-04 14:41:41,813 DEBUG : BROWSER_OUTPUT: Values: 350.2 356.9 357.1 358.4 359.2 5749.3 5896.2 5920.6 5943.5 5961.7 5735.0 5791.2 5798.7 5833.2 5845.2 4623.3 4628.6 4629.0 4635.2 4635.4 4608.4 4619.4 4631.8 4639.7 4642.2 4532.9 4601.2 4609.3 4638.5 4647.4 4618.7 4620.1 4629.6 4638.2 4645.2 3965.8 4113.7 4123.7 4128.2 4128.6 5507.8 5510.4 5600.0 5601.7 5619.8 5273.7 5275.0 5276.0 5276.9 5303.9 6131.1 6176.4 6209.1 6212.3 6214.7 4356.8 4451.6 4455.1 4467.2 4491.1 6318.8 6381.1 6387.0 6392.2 6393.1 4530.7 4535.4 4536.3 4536.6 4539.9 4588.7 4594.4 4597.1 4597.2 4598.7 5150.1 5242.6 5245.3 5249.9 5253.2 6295.9 6299.4 6304.7 6312.5 6315.0 1651.3 1666.9 1667.4 1667.5 1667.7 1114.6 1125.9 1132.9 1133.9 1136.2 6092.4 6116.3 6138.3 6139.1 6143.7 6494.4 6501.4 6505.1 6506.1 6512.0 6026.3 6131.4 6133.6 6134.0 6136.9 3559.8 3580.6 3583.8 3585.9 3586.4 6454.7 6486.0 6525.5 6583.5 6651.8 6571.9 6599.5 6622.1 6627.5 6632.4 6611.2 6614.7 6620.7 6624.4 6632.1 6329.7 6437.0 6453.1 6462.0 6463.2 6455.9 6477.2 6484.0 6484.6 6485.7 6692.8 6694.5 6706.8 6714.2 6719.7 4423.8 4496.6 4497.2 4500.7 4514.8 6330.9 6388.1 6402.6 6402.8 6411.7 6211.9 6253.1 6257.5 6266.2 6266.6 6164.4 6206.3 6214.1 6246.4 6252.5 6188.2 6231.1 6233.7 6235.3 6240.8 6352.7 6356.7 6363.8 6364.9 6367.3 6045.6 6133.0 6146.6 6150.4 6150.4 14:41:41 INFO - 2016-01-04 14:41:41,813 DEBUG : BROWSER_OUTPUT: 14:41:41 INFO - 2016-01-04 14:41:41,813 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15457.25 Median:2635.06 stddev:18728.79 (710.8%) stddev-sans-first:18748.88 14:41:41 INFO - 2016-01-04 14:41:41,813 DEBUG : BROWSER_OUTPUT: Values: 776.5 804.7 897.5 928.1 965.7 15330.1 15600.8 15602.1 15604.1 15611.4 14900.1 14995.5 15011.2 15012.5 15015.2 788.5 799.9 908.6 916.5 931.9 927.9 928.5 932.1 934.3 934.8 968.4 977.2 977.4 977.9 979.2 810.7 819.3 959.7 964.4 971.6 718.8 720.8 720.8 721.1 721.1 14573.8 14864.3 14942.4 14992.3 15046.8 13783.7 14080.0 14104.3 14167.6 14311.5 10588.5 10772.0 10841.6 10844.0 11035.6 3559.1 3882.4 3902.4 3966.4 3980.6 52800.7 53676.5 53693.0 53911.2 54287.0 7635.4 7637.5 7691.7 7727.1 7761.8 13088.5 13110.8 13111.3 13111.9 13122.7 7666.9 7752.8 7784.4 7868.6 7976.5 4681.3 4727.7 4765.7 4766.6 4791.7 2897.4 2912.5 2915.1 2950.5 2952.7 472.3 473.7 478.4 489.5 490.4 573.8 628.8 680.8 702.9 721.5 9025.0 9773.3 9784.9 9799.3 9911.6 535.3 541.0 654.9 691.4 745.1 4165.0 4515.7 4614.2 4835.3 4858.7 65609.7 65846.3 70731.0 70784.0 71197.6 58138.4 58159.0 58782.7 58842.7 60155.7 53321.2 53711.5 53988.0 54015.5 54291.5 9210.0 9210.7 9576.0 9655.4 10094.6 40801.4 41205.8 41386.6 41471.6 42021.8 52601.5 53426.5 53550.0 53989.5 54259.0 12042.2 12589.1 12608.6 12743.6 12803.6 11961.1 12370.4 12871.5 13274.9 13767.0 12176.9 12294.4 12302.3 12574.3 12775.9 12818.3 12833.3 12890.7 12917.9 13079.5 11928.8 12043.7 12087.4 12121.7 12621.0 10726.8 10771.5 10793.2 10992.8 11781.1 10757.4 11152.8 11163.4 11164.4 11221.7 14:41:41 INFO - 2016-01-04 14:41:41,813 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:41:41 INFO - 2016-01-04 14:41:41,813 DEBUG : BROWSER_OUTPUT: 14:41:42 INFO - 2016-01-04 14:41:42,366 INFO : Browser exited with error code: 0 14:41:42 INFO - 2016-01-04 14:41:42,386 INFO : Completed test dromaeo_css (00:18:29) 14:41:42 INFO - 2016-01-04 14:41:42,387 INFO : Starting test kraken 14:41:42 INFO - 2016-01-04 14:41:42,387 DEBUG : operating with platform_type : linux_ 14:41:42 INFO - 2016-01-04 14:41:42,387 INFO : Initialising browser for kraken test... 14:41:42 INFO - 2016-01-04 14:41:42,393 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0owfAg/profile http://localhost:40677/getInfo.html 14:41:45 INFO - 2016-01-04 14:41:45,761 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:41:45 INFO - 2016-01-04 14:41:45,761 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:41:45 INFO - 2016-01-04 14:41:45,762 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:41:45 INFO - 2016-01-04 14:41:45,762 DEBUG : BROWSER_OUTPUT: __metrics 14:41:45 INFO - 2016-01-04 14:41:45,843 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] 14:41:47 INFO - 2016-01-04 14:41:47,037 INFO : Browser initialized. 14:41:47 INFO - 2016-01-04 14:41:47,038 INFO : Running cycle 1/1 for kraken test... 14:41:47 INFO - 2016-01-04 14:41:47,038 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0owfAg/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:41:58 INFO - 2016-01-04 14:41:58,556 DEBUG : BROWSER_OUTPUT: RSS: Main: 128364544 14:41:58 INFO - 2016-01-04 14:41:58,557 DEBUG : BROWSER_OUTPUT: 14:42:00 INFO - 2016-01-04 14:42:00,984 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40677/tests/kraken/driver.html?testName=audio-beat-detection) 14:42:01 INFO - 2016-01-04 14:42:01,551 DEBUG : BROWSER_OUTPUT: RSS: Main: 163745792 14:42:01 INFO - 2016-01-04 14:42:01,551 DEBUG : BROWSER_OUTPUT: 14:42:04 INFO - 2016-01-04 14:42:04,088 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40677/tests/kraken/driver.html?testName=audio-dft) 14:42:04 INFO - 2016-01-04 14:42:04,650 DEBUG : BROWSER_OUTPUT: RSS: Main: 161538048 14:42:04 INFO - 2016-01-04 14:42:04,650 DEBUG : BROWSER_OUTPUT: 14:42:07 INFO - 2016-01-04 14:42:07,434 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40677/tests/kraken/driver.html?testName=audio-fft) 14:42:07 INFO - 2016-01-04 14:42:07,995 DEBUG : BROWSER_OUTPUT: RSS: Main: 158359552 14:42:07 INFO - 2016-01-04 14:42:07,995 DEBUG : BROWSER_OUTPUT: 14:42:09 INFO - 2016-01-04 14:42:09,973 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40677/tests/kraken/driver.html?testName=audio-oscillator) 14:42:10 INFO - 2016-01-04 14:42:10,534 DEBUG : BROWSER_OUTPUT: RSS: Main: 159354880 14:42:10 INFO - 2016-01-04 14:42:10,535 DEBUG : BROWSER_OUTPUT: 14:42:12 INFO - 2016-01-04 14:42:12,653 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40677/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:42:13 INFO - 2016-01-04 14:42:13,206 DEBUG : BROWSER_OUTPUT: RSS: Main: 156807168 14:42:13 INFO - 2016-01-04 14:42:13,206 DEBUG : BROWSER_OUTPUT: 14:42:16 INFO - 2016-01-04 14:42:16,073 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40677/tests/kraken/driver.html?testName=imaging-darkroom) 14:42:16 INFO - 2016-01-04 14:42:16,634 DEBUG : BROWSER_OUTPUT: RSS: Main: 156569600 14:42:16 INFO - 2016-01-04 14:42:16,635 DEBUG : BROWSER_OUTPUT: 14:42:19 INFO - 2016-01-04 14:42:19,960 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40677/tests/kraken/driver.html?testName=imaging-desaturate) 14:42:20 INFO - 2016-01-04 14:42:20,541 DEBUG : BROWSER_OUTPUT: RSS: Main: 156127232 14:42:20 INFO - 2016-01-04 14:42:20,541 DEBUG : BROWSER_OUTPUT: 14:42:23 INFO - 2016-01-04 14:42:23,259 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40677/tests/kraken/driver.html?testName=json-parse-financial) 14:42:23 INFO - 2016-01-04 14:42:23,826 DEBUG : BROWSER_OUTPUT: RSS: Main: 157319168 14:42:23 INFO - 2016-01-04 14:42:23,826 DEBUG : BROWSER_OUTPUT: 14:42:25 INFO - 2016-01-04 14:42:25,374 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40677/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:42:25 INFO - 2016-01-04 14:42:25,928 DEBUG : BROWSER_OUTPUT: RSS: Main: 162562048 14:42:25 INFO - 2016-01-04 14:42:25,928 DEBUG : BROWSER_OUTPUT: 14:42:27 INFO - 2016-01-04 14:42:27,657 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40677/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:42:28 INFO - 2016-01-04 14:42:28,251 DEBUG : BROWSER_OUTPUT: RSS: Main: 160153600 14:42:28 INFO - 2016-01-04 14:42:28,251 DEBUG : BROWSER_OUTPUT: 14:42:30 INFO - 2016-01-04 14:42:30,224 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40677/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:42:30 INFO - 2016-01-04 14:42:30,785 DEBUG : BROWSER_OUTPUT: RSS: Main: 166653952 14:42:30 INFO - 2016-01-04 14:42:30,785 DEBUG : BROWSER_OUTPUT: 14:42:33 INFO - 2016-01-04 14:42:33,106 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40677/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:42:33 INFO - 2016-01-04 14:42:33,687 DEBUG : BROWSER_OUTPUT: RSS: Main: 170577920 14:42:33 INFO - 2016-01-04 14:42:33,688 DEBUG : BROWSER_OUTPUT: 14:42:36 INFO - 2016-01-04 14:42:36,103 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40677/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:42:36 INFO - 2016-01-04 14:42:36,664 DEBUG : BROWSER_OUTPUT: RSS: Main: 167952384 14:42:36 INFO - 2016-01-04 14:42:36,664 DEBUG : BROWSER_OUTPUT: 14:42:38 INFO - 2016-01-04 14:42:38,245 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40677/tests/kraken/driver.html?testName=ai-astar) 14:42:38 INFO - 2016-01-04 14:42:38,495 DEBUG : BROWSER_OUTPUT: RSS: Main: 227401728 14:42:38 INFO - 2016-01-04 14:42:38,495 DEBUG : BROWSER_OUTPUT: 14:42:38 INFO - 2016-01-04 14:42:38,496 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:42:38 INFO - 2016-01-04 14:42:38,496 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:42:38 INFO - 2016-01-04 14:42:38,497 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:42:38 INFO - 2016-01-04 14:42:38,497 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:42:38 INFO - 2016-01-04 14:42:38,497 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;110;106;109;110;110;101;110;110;109 14:42:38 INFO - 2016-01-04 14:42:38,497 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;126;144;164;122;157;145;123;161;122 14:42:38 INFO - 2016-01-04 14:42:38,497 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;159;160;160;162;162;160;160;160;161 14:42:38 INFO - 2016-01-04 14:42:38,498 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;70;157;71;72;81;72;160;71 14:42:38 INFO - 2016-01-04 14:42:38,498 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;90;138;88;90;90;140;89;89;139 14:42:38 INFO - 2016-01-04 14:42:38,498 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;108;109;109;109;109;110;109;109;109;109 14:42:38 INFO - 2016-01-04 14:42:38,498 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;145;146;145;146;145;146;145;146 14:42:38 INFO - 2016-01-04 14:42:38,498 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;96;95;97;97;96;96;96;96 14:42:38 INFO - 2016-01-04 14:42:38,498 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;57;57;56;55;57;60;58;59;57 14:42:38 INFO - 2016-01-04 14:42:38,499 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;63;63;68;63;63;63;63;63;63 14:42:38 INFO - 2016-01-04 14:42:38,499 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;79;76;77;78;77;78;79;98;78;86 14:42:38 INFO - 2016-01-04 14:42:38,499 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;112;108;112;121;115;107;114;118;123;109 14:42:38 INFO - 2016-01-04 14:42:38,499 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;150;141;134;131;127;148;134;142;135 14:42:38 INFO - 2016-01-04 14:42:38,499 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;61;63;62;64;62;60;60;61;61;62 14:42:38 INFO - 2016-01-04 14:42:38,499 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:42:38 INFO - 2016-01-04 14:42:38,499 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:42:38 INFO - 2016-01-04 14:42:38,500 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,856 14:42:38 INFO - 2016-01-04 14:42:38,500 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:42:38 INFO - 2016-01-04 14:42:38,500 DEBUG : BROWSER_OUTPUT: __startTimestamp1451947358495__endTimestamp 14:42:38 INFO - 2016-01-04 14:42:38,500 DEBUG : BROWSER_OUTPUT: 14:42:38 INFO - 2016-01-04 14:42:38,503 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:42:38 INFO - 2016-01-04 14:42:38,504 DEBUG : BROWSER_OUTPUT: Number of tests: 14 14:42:38 INFO - 2016-01-04 14:42:38,504 DEBUG : BROWSER_OUTPUT: 14:42:38 INFO - 2016-01-04 14:42:38,504 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.50 Median:110.00 stddev:2.92 (2.7%) stddev-sans-first:3.04 14:42:38 INFO - 2016-01-04 14:42:38,504 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 106.0 109.0 110.0 110.0 101.0 110.0 110.0 109.0 14:42:38 INFO - 2016-01-04 14:42:38,505 DEBUG : BROWSER_OUTPUT: 14:42:38 INFO - 2016-01-04 14:42:38,505 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.00 Median:145.50 stddev:16.69 (11.5%) stddev-sans-first:17.60 14:42:38 INFO - 2016-01-04 14:42:38,505 DEBUG : BROWSER_OUTPUT: Values: 146.0 126.0 144.0 164.0 122.0 157.0 145.0 123.0 161.0 122.0 14:42:38 INFO - 2016-01-04 14:42:38,505 DEBUG : BROWSER_OUTPUT: 14:42:38 INFO - 2016-01-04 14:42:38,505 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.40 Median:160.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 14:42:38 INFO - 2016-01-04 14:42:38,505 DEBUG : BROWSER_OUTPUT: Values: 160.0 159.0 160.0 160.0 162.0 162.0 160.0 160.0 160.0 161.0 14:42:38 INFO - 2016-01-04 14:42:38,506 DEBUG : BROWSER_OUTPUT: 14:42:38 INFO - 2016-01-04 14:42:38,506 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:89.80 Median:72.00 stddev:36.34 (50.5%) stddev-sans-first:37.97 14:42:38 INFO - 2016-01-04 14:42:38,506 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 70.0 157.0 71.0 72.0 81.0 72.0 160.0 71.0 14:42:38 INFO - 2016-01-04 14:42:38,506 DEBUG : BROWSER_OUTPUT: 14:42:38 INFO - 2016-01-04 14:42:38,506 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:104.30 Median:89.50 stddev:23.96 (26.8%) stddev-sans-first:24.85 14:42:38 INFO - 2016-01-04 14:42:38,506 DEBUG : BROWSER_OUTPUT: Values: 90.0 90.0 138.0 88.0 90.0 90.0 140.0 89.0 89.0 139.0 14:42:38 INFO - 2016-01-04 14:42:38,506 DEBUG : BROWSER_OUTPUT: 14:42:38 INFO - 2016-01-04 14:42:38,506 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 14:42:38 INFO - 2016-01-04 14:42:38,506 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 14:42:38 INFO - 2016-01-04 14:42:38,507 DEBUG : BROWSER_OUTPUT: 14:42:38 INFO - 2016-01-04 14:42:38,507 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 14:42:38 INFO - 2016-01-04 14:42:38,507 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 145.0 146.0 145.0 146.0 145.0 146.0 145.0 146.0 14:42:38 INFO - 2016-01-04 14:42:38,507 DEBUG : BROWSER_OUTPUT: 14:42:38 INFO - 2016-01-04 14:42:38,507 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 14:42:38 INFO - 2016-01-04 14:42:38,507 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 96.0 95.0 97.0 97.0 96.0 96.0 96.0 96.0 14:42:38 INFO - 2016-01-04 14:42:38,507 DEBUG : BROWSER_OUTPUT: 14:42:38 INFO - 2016-01-04 14:42:38,507 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.20 Median:57.00 stddev:1.48 (2.6%) stddev-sans-first:1.50 14:42:38 INFO - 2016-01-04 14:42:38,508 DEBUG : BROWSER_OUTPUT: Values: 56.0 57.0 57.0 56.0 55.0 57.0 60.0 58.0 59.0 57.0 14:42:38 INFO - 2016-01-04 14:42:38,508 DEBUG : BROWSER_OUTPUT: 14:42:38 INFO - 2016-01-04 14:42:38,508 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.50 Median:63.00 stddev:1.58 (2.5%) stddev-sans-first:1.67 14:42:38 INFO - 2016-01-04 14:42:38,508 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 63.0 68.0 63.0 63.0 63.0 63.0 63.0 63.0 14:42:38 INFO - 2016-01-04 14:42:38,508 DEBUG : BROWSER_OUTPUT: 14:42:38 INFO - 2016-01-04 14:42:38,508 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:78.50 stddev:6.70 (8.5%) stddev-sans-first:7.08 14:42:38 INFO - 2016-01-04 14:42:38,508 DEBUG : BROWSER_OUTPUT: Values: 79.0 76.0 77.0 78.0 77.0 78.0 79.0 98.0 78.0 86.0 14:42:38 INFO - 2016-01-04 14:42:38,508 DEBUG : BROWSER_OUTPUT: 14:42:38 INFO - 2016-01-04 14:42:38,508 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:113.90 Median:114.50 stddev:5.43 (4.7%) stddev-sans-first:5.71 14:42:38 INFO - 2016-01-04 14:42:38,508 DEBUG : BROWSER_OUTPUT: Values: 112.0 108.0 112.0 121.0 115.0 107.0 114.0 118.0 123.0 109.0 14:42:38 INFO - 2016-01-04 14:42:38,509 DEBUG : BROWSER_OUTPUT: 14:42:38 INFO - 2016-01-04 14:42:38,509 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.30 Median:141.00 stddev:7.36 (5.2%) stddev-sans-first:7.75 14:42:38 INFO - 2016-01-04 14:42:38,509 DEBUG : BROWSER_OUTPUT: Values: 141.0 150.0 141.0 134.0 131.0 127.0 148.0 134.0 142.0 135.0 14:42:38 INFO - 2016-01-04 14:42:38,509 DEBUG : BROWSER_OUTPUT: 14:42:38 INFO - 2016-01-04 14:42:38,509 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:62.00 stddev:1.26 (2.0%) stddev-sans-first:1.32 14:42:38 INFO - 2016-01-04 14:42:38,509 DEBUG : BROWSER_OUTPUT: Values: 61.0 63.0 62.0 64.0 62.0 60.0 60.0 61.0 61.0 62.0 14:42:38 INFO - 2016-01-04 14:42:38,509 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:42:38 INFO - 2016-01-04 14:42:38,509 DEBUG : BROWSER_OUTPUT: 14:42:39 INFO - 2016-01-04 14:42:39,142 INFO : Browser exited with error code: 0 14:42:39 INFO - 2016-01-04 14:42:39,192 INFO : Completed test kraken (00:00:56) 14:42:39 INFO - 2016-01-04 14:42:39,192 INFO : Starting test v8_7 14:42:39 INFO - 2016-01-04 14:42:39,192 DEBUG : operating with platform_type : linux_ 14:42:39 INFO - 2016-01-04 14:42:39,193 INFO : Initialising browser for v8_7 test... 14:42:39 INFO - 2016-01-04 14:42:39,199 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmptbcmWD/profile http://localhost:40677/getInfo.html 14:42:42 INFO - 2016-01-04 14:42:42,576 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:42:42 INFO - 2016-01-04 14:42:42,576 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:42:42 INFO - 2016-01-04 14:42:42,576 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:42:42 INFO - 2016-01-04 14:42:42,577 DEBUG : BROWSER_OUTPUT: __metrics 14:42:42 INFO - 2016-01-04 14:42:42,738 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] 14:42:43 INFO - 2016-01-04 14:42:43,782 INFO : Browser initialized. 14:42:43 INFO - 2016-01-04 14:42:43,782 INFO : Running cycle 1/1 for v8_7 test... 14:42:43 INFO - 2016-01-04 14:42:43,783 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmptbcmWD/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:42:55 INFO - 2016-01-04 14:42:55,280 DEBUG : BROWSER_OUTPUT: RSS: Main: 128356352 14:42:55 INFO - 2016-01-04 14:42:55,280 DEBUG : BROWSER_OUTPUT: 14:43:16 INFO - 2016-01-04 14:43:16,672 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40677/tests/v8_7/run.html (next: http://localhost:40677/tests/v8_7/run.html) 14:43:16 INFO - 2016-01-04 14:43:16,922 DEBUG : BROWSER_OUTPUT: RSS: Main: 416759808 14:43:16 INFO - 2016-01-04 14:43:16,922 DEBUG : BROWSER_OUTPUT: 14:43:16 INFO - 2016-01-04 14:43:16,922 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:43:16 INFO - 2016-01-04 14:43:16,922 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:43:16 INFO - 2016-01-04 14:43:16,923 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:43:16 INFO - 2016-01-04 14:43:16,923 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:43:16 INFO - 2016-01-04 14:43:16,923 DEBUG : BROWSER_OUTPUT: |0;Richards;163.18537859007833 14:43:16 INFO - 2016-01-04 14:43:16,923 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.44486108743823 14:43:16 INFO - 2016-01-04 14:43:16,923 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.09572983533354 14:43:16 INFO - 2016-01-04 14:43:16,923 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5020 14:43:16 INFO - 2016-01-04 14:43:16,923 DEBUG : BROWSER_OUTPUT: |4;RayTrace;954.1984732824427 14:43:16 INFO - 2016-01-04 14:43:16,924 DEBUG : BROWSER_OUTPUT: |5;Earley;555.247084952804 14:43:16 INFO - 2016-01-04 14:43:16,924 DEBUG : BROWSER_OUTPUT: |6;Boyer;10479.166666666666 14:43:16 INFO - 2016-01-04 14:43:16,924 DEBUG : BROWSER_OUTPUT: |7;RegExp;23857.14285714286 14:43:16 INFO - 2016-01-04 14:43:16,924 DEBUG : BROWSER_OUTPUT: |8;Splay;537.9273504273505 14:43:16 INFO - 2016-01-04 14:43:16,924 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4812.5 14:43:16 INFO - 2016-01-04 14:43:16,925 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:43:16 INFO - 2016-01-04 14:43:16,925 DEBUG : BROWSER_OUTPUT: __startTimestamp1451947396921__endTimestamp 14:43:16 INFO - 2016-01-04 14:43:16,925 DEBUG : BROWSER_OUTPUT: 14:43:16 INFO - 2016-01-04 14:43:16,925 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:43:16 INFO - 2016-01-04 14:43:16,926 DEBUG : BROWSER_OUTPUT: Number of tests: 10 14:43:16 INFO - 2016-01-04 14:43:16,926 DEBUG : BROWSER_OUTPUT: 14:43:16 INFO - 2016-01-04 14:43:16,927 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.19 Median:163.19 stddev:0.00 (0.0%) 14:43:16 INFO - 2016-01-04 14:43:16,927 DEBUG : BROWSER_OUTPUT: Values: 163.2 14:43:16 INFO - 2016-01-04 14:43:16,927 DEBUG : BROWSER_OUTPUT: 14:43:16 INFO - 2016-01-04 14:43:16,927 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.44 Median:170.44 stddev:0.00 (0.0%) 14:43:16 INFO - 2016-01-04 14:43:16,928 DEBUG : BROWSER_OUTPUT: Values: 170.4 14:43:16 INFO - 2016-01-04 14:43:16,928 DEBUG : BROWSER_OUTPUT: 14:43:16 INFO - 2016-01-04 14:43:16,928 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.10 Median:279.10 stddev:0.00 (0.0%) 14:43:16 INFO - 2016-01-04 14:43:16,928 DEBUG : BROWSER_OUTPUT: Values: 279.1 14:43:16 INFO - 2016-01-04 14:43:16,928 DEBUG : BROWSER_OUTPUT: 14:43:16 INFO - 2016-01-04 14:43:16,928 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5020.00 Median:5020.00 stddev:0.00 (0.0%) 14:43:16 INFO - 2016-01-04 14:43:16,928 DEBUG : BROWSER_OUTPUT: Values: 5020.0 14:43:16 INFO - 2016-01-04 14:43:16,929 DEBUG : BROWSER_OUTPUT: 14:43:16 INFO - 2016-01-04 14:43:16,929 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:954.20 Median:954.20 stddev:0.00 (0.0%) 14:43:16 INFO - 2016-01-04 14:43:16,929 DEBUG : BROWSER_OUTPUT: Values: 954.2 14:43:16 INFO - 2016-01-04 14:43:16,929 DEBUG : BROWSER_OUTPUT: 14:43:16 INFO - 2016-01-04 14:43:16,929 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:555.25 Median:555.25 stddev:0.00 (0.0%) 14:43:16 INFO - 2016-01-04 14:43:16,929 DEBUG : BROWSER_OUTPUT: Values: 555.2 14:43:16 INFO - 2016-01-04 14:43:16,929 DEBUG : BROWSER_OUTPUT: 14:43:16 INFO - 2016-01-04 14:43:16,929 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10479.17 Median:10479.17 stddev:0.00 (0.0%) 14:43:16 INFO - 2016-01-04 14:43:16,929 DEBUG : BROWSER_OUTPUT: Values: 10479.2 14:43:16 INFO - 2016-01-04 14:43:16,929 DEBUG : BROWSER_OUTPUT: 14:43:16 INFO - 2016-01-04 14:43:16,929 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23857.14 Median:23857.14 stddev:0.00 (0.0%) 14:43:16 INFO - 2016-01-04 14:43:16,930 DEBUG : BROWSER_OUTPUT: Values: 23857.1 14:43:16 INFO - 2016-01-04 14:43:16,930 DEBUG : BROWSER_OUTPUT: 14:43:16 INFO - 2016-01-04 14:43:16,930 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:537.93 Median:537.93 stddev:0.00 (0.0%) 14:43:16 INFO - 2016-01-04 14:43:16,930 DEBUG : BROWSER_OUTPUT: Values: 537.9 14:43:16 INFO - 2016-01-04 14:43:16,930 DEBUG : BROWSER_OUTPUT: 14:43:16 INFO - 2016-01-04 14:43:16,930 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4812.50 Median:4812.50 stddev:0.00 (0.0%) 14:43:16 INFO - 2016-01-04 14:43:16,930 DEBUG : BROWSER_OUTPUT: Values: 4812.5 14:43:16 INFO - 2016-01-04 14:43:16,930 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:43:16 INFO - 2016-01-04 14:43:16,930 DEBUG : BROWSER_OUTPUT: 14:43:17 INFO - 2016-01-04 14:43:17,610 INFO : Browser exited with error code: 0 14:43:17 INFO - 2016-01-04 14:43:17,626 INFO : Completed test v8_7 (00:00:38) 14:43:17 INFO - 2016-01-04 14:43:17,936 INFO : Completed test suite (00:20:04) 14:43:17 INFO - 2016-01-04 14:43:17,936 DEBUG : Working with test: dromaeo_css 14:43:17 INFO - 2016-01-04 14:43:17,936 DEBUG : Generating results file: dromaeo_css 14:43:17 INFO - 2016-01-04 14:43:17,938 DEBUG : Working with test: kraken 14:43:17 INFO - 2016-01-04 14:43:17,938 DEBUG : Generating results file: kraken 14:43:17 INFO - 2016-01-04 14:43:17,939 INFO : javascript benchmark 14:43:17 INFO - 2016-01-04 14:43:17,939 DEBUG : Working with test: v8_7 14:43:17 INFO - 2016-01-04 14:43:17,939 DEBUG : Generating results file: v8_7 14:43:17 INFO - 2016-01-04 14:43:17,940 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:43:17 INFO - 2016-01-04 14:43:17,992 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 14:43:17 INFO - 2016-01-04 14:43:17,992 DEBUG : process_Request line: dromaeo_css 7352.25 graph.html#tests=[[72,53,35]] 14:43:17 INFO - 2016-01-04 14:43:17,992 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:43:18 INFO - 2016-01-04 14:43:18,021 DEBUG : process_Request line: kraken 1469.50 graph.html#tests=[[232,53,35]] 14:43:18 INFO - 2016-01-04 14:43:18,021 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:43:18 INFO - 2016-01-04 14:43:18,050 DEBUG : process_Request line: v8_7 23268.44 graph.html#tests=[[230,53,35]] 14:43:18 INFO - 2016-01-04 14:43:18,051 INFO : javascript benchmark 14:43:18 INFO - 2016-01-04 14:43:18,055 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3286.145602143443, 3349.715274201693, 3370.106921665759, 3371.4774209997445, 3405.1827561385276, 5426.050441172799, 5478.096114141167, 5504.504594586487, 5509.944900550993, 5525.419830917756, 5498.697571633568, 5529.917051244234, 5538.141588053848, 5544.500994910457, 5562.610617256793, 4981.601471882241, 5049.545540901309, 5094.923576146361, 5163.948360516403, 5164.561012313939, 4970.403551573816, 5009.9749501252445, 5020.347354843865, 5038.84883453497, 5098.980203959204, 5115.9744201278945, 5165.276861239411, 5165.896682066356, 5173.663711858736, 5191.584673226152, 4921.704697718148, 5096.108181068335, 5097.5667068299235, 5155.02054609623, 5156.510131537517, 4862.82980095695, 4947.158982972867, 4975.825846095369, 4989.176785830334, 5008.824691135793, 5294.735263236823, 5350.117230656937, 5385.919211211835, 5412.052890744143, 5422.15956526739, 5907.527397808165, 5966.105084237356, 5972.6715030673295, 5978.641281523122, 6038.57729958903, 5876.29484461863, 5957.791477298274, 5962.3739507351975, 5963.0, 5973.8207853764025, 5595.916061259084, 5672.8014519492035, 5675.489205971482, 5686.232358631614, 5689.231953686241, 5826.300843898759, 5931.555133365015, 5964.0, 6121.663308518075, 6136.417040381246, 5970.970145149333, 5973.581849270597, 5980.910286345708, 5982.670953097534, 5993.0710739835395, 6062.939370606326, 6065.787697430657, 6069.423404776539, 6074.149619053336, 6075.058365953284, 5776.797812076641, 5820.214271073351, 5947.0, 5953.404659534099, 6024.939750602525, 5931.940680593225, 5952.702364881825, 5954.46409823118, 5967.850803729854, 5981.910271345933, 4550.453945526492, 4561.635069194456, 4562.201614717411, 4562.771861406916, 4591.540845915381, 3982.4623675803396, 4016.879493615196, 4072.450219220427, 4097.713160078766, 4102.2000709861295, 5557.055300598908, 5566.2207290979295, 5575.303087114111, 5592.748326325325, 5641.548676105902, 5776.653400795965, 5954.791582294599, 5969.552283578662, 5978.701064946736, 5987.64074155552, 5511.834644960658, 5547.5007249347755, 5559.471850174227, 5595.832125036255, 5622.072358060956, 5193.37679478465, 5224.895502089937, 5227.111391063529, 5230.97384513075, 5253.894922101613, 5976.551758618121, 6063.575549711478, 6157.8902654159365, 6217.844553886099, 6222.222222222223, 6005.189299444519, 6079.756809727562, 6104.145419641165, 6109.961306577981, 6115.908261376172, 6207.193064901534, 6212.937870621417, 6215.81352559424, 6218.595791273553, 6238.064290356483, 5745.253117094751, 5762.337331206965, 5862.736176871966, 5978.730957106854, 5980.611260268069, 5940.138679891478, 5967.76128954837, 5974.850628734316, 5977.462028417463, 5991.131285963424, 5839.474447299764, 5841.766329346779, 5845.561582881216, 5848.239728835224, 5848.970755146197, 5654.89729502743, 5734.971325143348, 5771.884562308815, 5844.6785426801935, 5860.94139058621, 5712.514436272963, 5794.507466865363, 5809.477147056785, 5814.244148260868, 5815.244018277597, 5894.351621321565, 5899.882002360015, 5912.438318359749, 5913.142594323713, 5917.644941303535, 5565.5825813063375, 5638.605297629127, 5690.430956904276, 5694.117411801178, 5758.596898217084], "unit": "ms", "name": "dojo.html", "value": 5497.6001552031385}, {"lowerIsBetter": false, "replicates": [594.3877805859966, 638.5306799502366, 645.7191121861989, 671.6708812681787, 676.404763807849, 44643.33035004477, 45510.77244613781, 45589.77205113974, 45660.31509527356, 45665.0, 48302.0, 48828.0, 48852.75573622127, 48911.755441222835, 48952.75523622377, 8424.452410593323, 8572.271356934636, 9256.907430925672, 10836.295640783363, 11014.889851101467, 11087.944560277187, 11172.0, 11174.1060715143, 11182.105431565455, 11208.887911120906, 11188.440577971109, 11277.887221127807, 11289.830652540219, 11304.547818087267, 11308.0, 11140.777184456307, 11210.495527701192, 11217.831732523979, 11233.831492527619, 11240.662780116569, 11008.174386920933, 11106.500207490602, 11122.61070862524, 11138.94430527842, 11142.331460112418, 46041.53958460406, 46562.30156547655, 46568.53431465676, 46584.0, 46591.534084659055, 3687.8524859005606, 3758.0980564664283, 4057.2494088593703, 4243.787810609457, 4552.817887284505, 34463.31073378544, 34918.65081349205, 34950.0, 34969.0, 35040.0, 3420.9828950855335, 3467.081223475781, 3493.161641206092, 3514.5782506099176, 3781.111438811877, 312579.99999999773, 316959.0, 317539.0, 318102.0, 318197.0, 54224.0, 78781.0, 80316.19683803087, 80332.59833700793, 80516.19483805087, 72627.63686181535, 72651.0, 72714.63642681752, 73064.63467682627, 73154.63422682852, 18554.165062572218, 18722.063896805106, 18737.90631046836, 18813.247470101316, 18819.247230110643, 14093.097431846774, 14424.567262982127, 14431.061980971203, 14439.78340324875, 14482.927585362006, 23163.88418057899, 23354.64968025481, 23412.64881026786, 23451.76548234496, 23500.942457589448, 8252.669893204325, 8399.496030238206, 8404.453710508797, 8450.154984501502, 8474.237318641352, 1007.7302598725659, 1039.6776999130293, 1131.2307630810963, 1158.3108050709814, 1173.2491205628405, 30827.537586936214, 30911.845440772653, 32577.348453030805, 33425.49861752076, 33747.0, 1077.7129754249509, 1124.5670416889557, 1135.2677522997785, 1165.9708507287385, 1221.5480272299128, 14596.854031459761, 16092.67814643724, 16131.193440327937, 16290.51128466148, 16314.673706525802, 367545.0, 371817.0, 372189.0, 373767.0, 374934.0, 61226.0816087759, 61392.69303653453, 61404.0, 61409.38590614126, 61494.38505614976, 1437.6908964572667, 1482.5996980815119, 1515.0758037597057, 1520.9847901521287, 1527.938882444712, 29003.709962900946, 29723.40553188881, 29817.850910745306, 30268.84865575658, 30522.08433746991, 52466.73766631143, 52719.20921186109, 52913.47086529085, 52940.0, 52961.0, 245998.0, 246559.0, 247414.0, 248006.0, 248761.0, 34874.651253488155, 35342.64657353394, 35350.82324588463, 35766.821165894005, 35829.64170358367, 50095.0, 51421.48578514167, 51551.0, 52045.0, 52441.47558524366, 42394.36408453814, 43412.565874342115, 43538.0, 43716.0, 44590.0, 41499.7925010373, 41799.58200418078, 42116.78941605272, 43072.35391469193, 43342.34986475142, 41198.0, 41647.1670566593, 42905.57094429016, 42976.0, 43207.0, 33320.50019249665, 34070.48894266637, 34266.10295946282, 36824.0, 37160.44259336166, 27571.86214068917, 28077.0, 28112.0, 28154.85922570374, 28200.85899570489], "unit": "ms", "name": "ext.html", "value": 21196.301995750793}, {"lowerIsBetter": false, "replicates": [382.8009435093753, 385.4757529759527, 385.8553042609022, 386.2564563215809, 386.73508646577096, 14452.783208251885, 18403.815961840377, 20883.582328353463, 21150.788492115054, 21169.0, 20018.899905500453, 20023.799762002374, 20472.28347007855, 20482.18071277147, 20496.89751551244, 5820.2433683621175, 5939.762409503614, 5943.375945525722, 5944.762209511614, 5948.345681974979, 5827.737751801179, 5971.164037034819, 5977.073553599199, 5983.252093488314, 6020.548458865603, 4412.404325416076, 4534.455865296169, 5936.287645482526, 5940.108983652465, 5942.019566771478, 6310.684465776716, 6458.644774537399, 6485.351464853524, 6505.284418713938, 6505.6421896795655, 5783.479486846204, 5888.205092312569, 5926.081457374114, 5935.2877654681715, 5942.286925568917, 23717.88141059301, 24491.632625510632, 24560.508789824282, 24984.50030999388, 25430.745692543023, 779.2246714519033, 779.5790273252437, 2317.316391664463, 2437.2566367257978, 2528.1431981860565, 14491.275436228147, 14749.926250368679, 14870.553883383516, 15124.168170750605, 15268.465603703817, 1910.1786231920269, 1939.9030048497518, 2010.5476267839765, 2062.257587268582, 2074.9066292016746, 69654.65172674155, 70582.29417705808, 71013.64493177553, 71518.99999999948, 71741.64129179373, 11724.179307448398, 12032.458539365749, 12139.757204855854, 12150.21023633461, 12156.149069565223, 16115.274812633459, 16159.67680646404, 16173.838261617233, 16174.27215775293, 16183.676326473405, 9997.350172238781, 10467.633632822966, 10713.142948564097, 10750.569977200825, 10921.453927303603, 9196.080391960884, 9252.259819214447, 9331.113544213289, 9379.343445958717, 9537.284703647116, 8665.610047547876, 8811.823763524693, 8838.0, 8841.115888411236, 8872.778180545538, 3204.6154461464776, 3293.3084052349277, 3295.6045274567205, 3307.6196237432523, 3406.710429613461, 461.8614415675351, 510.8518529626441, 539.7058603061341, 541.9295491586147, 542.888707814897, 12823.871761282455, 12971.740565188644, 13551.59345219645, 13834.584962451143, 13967.161970281815, 528.0916823064323, 537.9031774280588, 559.5495626021011, 571.3914680864866, 574.6121368076615, 6281.560290779602, 6974.197967233732, 7023.122109736283, 7150.249223831423, 7305.232950540223, 592491.0, 596533.0, 602849.0, 609753.0, 610382.0, 83945.0, 86631.56684216665, 87209.0, 88802.0, 88816.55591722, 583.7081459270365, 599.7421108923205, 607.1621162795315, 613.3406587918006, 614.4623454477422, 11739.941300293443, 11907.226030308002, 11983.22109062943, 12031.27812331263, 12215.0, 39861.60138398579, 40487.797561012005, 41472.792636036625, 42067.7896610515, 42094.57905421029, 53671.73164134155, 55218.72390638021, 56673.0, 56707.43292567186, 57880.0, 7564.886526702214, 7575.318221360104, 7604.581748003691, 7626.046744156981, 7669.846603068019, 13746.243956582484, 14086.788698169741, 14249.857501425267, 14296.428142874169, 14330.928345358207, 11989.82015269789, 12114.818277725664, 12230.14388992762, 12412.503499859904, 12427.192232504858, 11697.473613687574, 11857.229280096766, 12037.337946412681, 12068.517259309529, 12250.938745306217, 11636.360000199731, 11945.0, 12529.310887901252, 12542.059345548938, 12652.936735316265, 6430.196225471816, 7395.852082958419, 7472.14070381913, 7475.514091584029, 7575.469717119749, 8629.611667474854, 12156.088293377856, 12231.69420764488, 12425.37873106359, 12511.687207819878], "unit": "ms", "name": "jquery.html", "value": 8987.440231459315}, {"lowerIsBetter": false, "replicates": [1259.4458438287154, 1284.6916739982407, 1290.406413049997, 1798.1279079646374, 1819.6451691920072, 7078.469114816384, 7240.5293655912465, 7946.24510671486, 8150.0, 8157.714479993201, 7794.805129871742, 7975.4815936964205, 8046.396520260989, 8049.275565199131, 8055.959720201406, 2251.279590531031, 2281.247188427823, 2283.56612243674, 2284.326123793485, 2284.817214622826, 2051.0872661665567, 2097.590969760897, 2298.195631528968, 2315.1896836107394, 2357.5167090746354, 2238.5858616155983, 2247.8089362404216, 2254.256095488493, 2254.797068263856, 2263.2644390573046, 2290.6564015397657, 2310.8960096795518, 2311.514581937795, 2312.236961802601, 2316.247219653611, 2241.2939923923914, 2245.1019592163266, 2246.84272100953, 2250.7524172341027, 2252.1666983216314, 7846.215378462165, 7923.088844782808, 7931.167227441093, 7935.841283174362, 7963.203679632048, 4970.826021089245, 5036.496350364971, 5039.672421292641, 5039.949600504021, 5055.191169412877, 3263.1515805890403, 3270.9509357359657, 3345.7657963942534, 3350.8827191048194, 3356.110630682871, 1056.7675111475462, 1071.4589132488122, 1074.1406874500447, 1104.3263609198382, 1107.5735841700923, 11051.502682379312, 11051.889481105167, 11066.723331916766, 11078.169137314653, 11081.058110060654, 4453.287474004143, 4468.776561171929, 4517.322401639714, 4523.434570678665, 4531.56950089741, 9469.289803264783, 9507.144357007903, 9508.904910950801, 9509.714708558755, 9511.191548718298, 4919.655624106278, 4935.3090567320605, 4936.950630493721, 4938.9012219755405, 4939.0, 4529.297958816389, 4534.274516077412, 4536.546345365503, 4537.863864084083, 4539.114872599811, 3951.5258169019326, 3982.960170398259, 3995.220931918306, 4004.0790618157985, 4037.9905023744063, 1152.343164396286, 1198.6823491774687, 1259.6661884600587, 1291.7481091187317, 1299.857015728269, 1164.6913567904512, 1164.8369228308043, 1166.8424762657103, 1217.1175897474436, 1231.8275441438207, 7530.209328020588, 7568.281013303727, 7585.658645360972, 7586.582737949356, 7775.611219439005, 1213.1932265043824, 1217.5190799634095, 1217.5799349224503, 1231.9938400307942, 1238.2570457725292, 6270.184875966095, 6287.151234583364, 6350.682465876687, 6359.682015899187, 6364.045393191059, 9558.330916835755, 9727.9027209727, 9733.707988760207, 9742.171915387129, 9746.025397460196, 9473.336866419284, 9637.662681806103, 9638.662646807468, 9644.0, 9653.0, 7512.436567257478, 7539.208383119803, 7586.848263034708, 7596.734114305973, 7599.886001709979, 7338.816529586589, 7348.154962179417, 7377.3360397564475, 7404.259574042552, 7414.073240844895, 8534.146585341416, 8643.394962352575, 8651.6971905983, 8680.78298042554, 8686.695965641173, 8661.566921654017, 8844.601992910206, 8845.469271843453, 8851.601677924638, 8999.460032398078, 6938.5836849789175, 6989.965050174716, 6993.650317484004, 6994.685239164148, 7012.964935175291, 7091.11361079865, 7624.656890440055, 7912.0901096374655, 7934.603269836601, 7973.122956474667, 7005.299470053158, 7297.708091676486, 7392.408607311295, 7512.098548174254, 7628.0, 6981.127359080115, 7029.05107810439, 7055.894161587477, 7125.857482850418, 7230.457715671451, 7273.454490913097, 7319.817004574928, 7323.0, 7329.890051649336, 7330.230325815713, 6713.63075030878, 6728.865422691617, 6732.932670673231, 6953.23514413404, 6966.3730264276455, 6683.665816709048, 6713.765018224534, 6718.093057437183, 6718.6976586054725, 6726.192856857208], "unit": "ms", "name": "mootools.html", "value": 4650.300507512177}, {"lowerIsBetter": false, "replicates": [350.245221547565, 356.9214772749994, 357.13573152969826, 358.449779588332, 359.2276605298608, 5749.252597162363, 5896.204012458317, 5920.615160014601, 5943.465088142067, 5961.672108034057, 5735.0, 5791.160281759142, 5798.65208087515, 5833.154192642064, 5845.181674565553, 4623.306504024398, 4628.629709623222, 4628.953710462886, 4635.235186194274, 4635.443746750395, 4608.354830323758, 4619.445666520022, 4631.814727410916, 4639.65202609804, 4642.234031384834, 4532.864014079566, 4601.171789077965, 4609.331646911196, 4638.512956139591, 4647.419072615923, 4618.745968971705, 4620.145273124466, 4629.629629629621, 4638.165130276549, 4645.187092258858, 3965.7620542767527, 4113.732607380511, 4123.690723195772, 4128.215639028605, 4128.649064829493, 5507.834764957058, 5510.421405752379, 5600.0, 5601.663900166003, 5619.803306884281, 5273.683578985273, 5275.0, 5275.973620131913, 5276.868078298035, 5303.920441193385, 6131.14164017038, 6176.413240742122, 6209.099680546341, 6212.347703491158, 6214.65819379934, 4356.760378179199, 4451.577100175478, 4455.086707225011, 4467.195904737146, 4491.056878055612, 6318.842028949267, 6381.074744162071, 6387.010013447876, 6392.232932048138, 6393.073004414334, 4530.70550414222, 4535.4103966484145, 4536.342230376643, 4536.637069034469, 4539.909201815945, 4588.655850811136, 4594.402727645384, 4597.149527337453, 4597.241455159928, 4598.724076555417, 5150.072986862401, 5242.606804489678, 5245.318108645852, 5249.868753281198, 5253.238280449314, 6295.937040629535, 6299.370062993756, 6304.684765761694, 6312.495000399957, 6315.0, 1651.265186991801, 1666.8666506679438, 1667.374734474572, 1667.4914151184005, 1667.65813008333, 1114.5931734916696, 1125.870524889648, 1132.8923752243525, 1133.8525991621202, 1136.2216881436243, 6092.421219984094, 6116.327204007555, 6138.324784273726, 6139.109829074849, 6143.6928153593035, 6494.415502604788, 6501.414872661483, 6505.056766768792, 6506.089147519351, 6512.023196520465, 6026.276846778327, 6131.356207598227, 6133.570650054543, 6134.018557030854, 6136.938630613726, 3559.839807208682, 3580.5703315602295, 3583.7849729016343, 3585.8565657373415, 3586.4261718124976, 6454.741810327534, 6485.967570162214, 6525.4779617630475, 6583.473322134218, 6651.800445986628, 6571.901421478682, 6599.538032337787, 6622.072909792633, 6627.469802415795, 6632.369924857131, 6611.23970743361, 6614.7023383947835, 6620.668966551653, 6624.436922861514, 6632.104665870046, 6329.746810127727, 6436.967815160894, 6453.128827608401, 6462.0306953955405, 6463.192100987377, 6455.870882582416, 6477.222733272038, 6483.967580162069, 6484.643344616092, 6485.675716214076, 6692.83267918306, 6694.531382803352, 6706.798796036127, 6714.160729908762, 6719.697613607302, 4423.77881105937, 4496.595306422307, 4497.168023915586, 4500.729956202638, 4514.819407223675, 6330.905036424365, 6388.073729309317, 6402.647854368054, 6402.839929001626, 6411.711472983634, 6211.937880621136, 6253.1245625613365, 6257.530685198537, 6266.248050234001, 6266.624002559861, 6164.4143806338325, 6206.255249370104, 6214.098955651315, 6246.375362463899, 6252.468540173954, 6188.195534580475, 6231.065340199007, 6233.657148856856, 6235.282942461675, 6240.7503699851495, 6352.745890164527, 6356.682165891594, 6363.7772677956045, 6364.904526432015, 6367.267764207176, 6045.637261764308, 6132.969335153296, 6146.569740118328, 6150.354212807591, 6150.446459818638], "unit": "ms", "name": "prototype.html", "value": 4779.165685255617}, {"lowerIsBetter": false, "replicates": [776.4758787818225, 804.7304153108709, 897.5063714956774, 928.1414691410446, 965.7392504023909, 15330.080195188297, 15600.843991560081, 15602.063876167438, 15604.063756174623, 15611.375544978215, 14900.105993640362, 14995.47515836946, 15011.249437528135, 15012.474563390284, 15015.249237538106, 788.5071830106184, 799.940004499662, 908.5593487158707, 916.5234078279291, 931.8509038553833, 927.9072092790734, 928.5496534180917, 932.067932067932, 934.313279739391, 934.8457504511747, 968.4237878462303, 977.1938151025397, 977.359829311802, 977.9168770654503, 979.1872745621117, 810.7446154461326, 819.2831272636444, 959.7168835193635, 964.4068897627978, 971.6259240192577, 718.7987363538218, 720.7549433192739, 720.8306048078697, 721.0734206544618, 721.0914248047445, 14573.781393279003, 14864.256787160599, 14942.402303907831, 14992.250387480692, 15046.774298385533, 13783.655408614764, 14080.0, 14104.294785260696, 14167.57497275073, 14311.499097531536, 10588.470576471145, 10772.030517253406, 10841.620543281026, 10844.0, 11035.613753518588, 3559.056849934769, 3882.437046628223, 3902.3756199008308, 3966.4446977423186, 3980.5621381647998, 52800.735996319774, 53676.46323536753, 53693.0, 53911.19133213044, 54287.0, 7635.350995165473, 7637.4653774236385, 7691.730789422343, 7727.111382191008, 7761.767146985487, 13088.476460941456, 13110.803337949937, 13111.344432778324, 13111.934440327928, 13122.737545249041, 7666.923330766621, 7752.806179845436, 7784.416168787364, 7868.606569671608, 7976.481528700616, 4681.344611754357, 4727.7163370197895, 4765.69023013503, 4766.594839438687, 4791.6645834791925, 2897.4060317634835, 2912.51943429336, 2915.0526079024403, 2950.5131653276967, 2952.70472952703, 472.2561964905288, 473.6826326361346, 478.44739326078434, 489.47381564817965, 490.43109992408637, 573.7704918032737, 628.7924984755019, 680.7821497120973, 702.8629417263585, 721.4949535325293, 9025.0, 9773.315867889321, 9784.902150978542, 9799.314048016571, 9911.603535858629, 535.3201434178649, 540.9729513524308, 654.8657525207416, 691.3708525242006, 745.1058729524592, 4164.9583504165175, 4515.661325400542, 4614.215583350839, 4835.2505361669, 4858.684185527929, 65609.67195163993, 65846.34153658402, 70731.0, 70784.0, 71197.64401178065, 58138.418615814146, 58159.0, 58782.70608646929, 58842.7057864708, 60155.69922150449, 53321.20018199808, 53711.46288537143, 53988.0, 54015.45984540183, 54291.45708542943, 9210.0, 9210.677626283048, 9576.0, 9655.420674759256, 10094.596216151273, 40801.38797918093, 41205.793971029954, 41386.58613413948, 41471.58528414677, 42021.78989105035, 52601.473985259654, 53426.46573534371, 53550.0, 53989.460105398444, 54259.0, 12042.157049006486, 12589.055820813659, 12608.621741347773, 12743.553975610808, 12803.61589152327, 11961.102917281412, 12370.44333005035, 12871.549495767604, 13274.93362533181, 13767.0, 12176.939115304367, 12294.446749896097, 12302.261864288172, 12574.30841303737, 12775.85017348443, 12818.294993775431, 12833.294168820805, 12890.742185156058, 12917.935410322889, 13079.476820927437, 11928.76142477163, 12043.698907527381, 12087.39563021863, 12121.69695757613, 12621.0, 10726.785464290826, 10771.51528181218, 10793.190510711886, 10992.803751796791, 11781.116416268642, 10757.354558726502, 11152.832707509231, 11163.386013769321, 11164.385958772345, 11221.71945701364], "unit": "ms", "name": "yui.html", "value": 6785.427896975832}], "name": "dromaeo_css", "value": 7352.249042275533}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 110.0, 106.0, 109.0, 110.0, 110.0, 101.0, 110.0, 110.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 108.5}, {"lowerIsBetter": true, "replicates": [146.0, 126.0, 144.0, 164.0, 122.0, 157.0, 145.0, 123.0, 161.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 141.0}, {"lowerIsBetter": true, "replicates": [160.0, 159.0, 160.0, 160.0, 162.0, 162.0, 160.0, 160.0, 160.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.4}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 70.0, 157.0, 71.0, 72.0, 81.0, 72.0, 160.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 89.8}, {"lowerIsBetter": true, "replicates": [90.0, 90.0, 138.0, 88.0, 90.0, 90.0, 140.0, 89.0, 89.0, 139.0], "unit": "ms", "name": "audio-oscillator", "value": 104.3}, {"lowerIsBetter": true, "replicates": [108.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 96.0, 95.0, 97.0, 97.0, 96.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.0}, {"lowerIsBetter": true, "replicates": [56.0, 57.0, 57.0, 56.0, 55.0, 57.0, 60.0, 58.0, 59.0, 57.0], "unit": "ms", "name": "json-parse-financial", "value": 57.2}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 63.0, 68.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.5}, {"lowerIsBetter": true, "replicates": [79.0, 76.0, 77.0, 78.0, 77.0, 78.0, 79.0, 98.0, 78.0, 86.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 80.6}, {"lowerIsBetter": true, "replicates": [112.0, 108.0, 112.0, 121.0, 115.0, 107.0, 114.0, 118.0, 123.0, 109.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 113.9}, {"lowerIsBetter": true, "replicates": [141.0, 150.0, 141.0, 134.0, 131.0, 127.0, 148.0, 134.0, 142.0, 135.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 138.3}, {"lowerIsBetter": true, "replicates": [61.0, 63.0, 62.0, 64.0, 62.0, 60.0, 60.0, 61.0, 61.0, 62.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.6}], "name": "kraken", "value": 1469.4999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.18537859007833], "unit": "score", "name": "Richards", "value": 216.33065600000006}, {"lowerIsBetter": false, "replicates": [170.44486108743823], "unit": "score", "name": "DeltaBlue", "value": 387.91430599999984}, {"lowerIsBetter": false, "replicates": [279.09572983533354], "unit": "score", "name": "Encrypt", "value": 953.7265229999995}, {"lowerIsBetter": false, "replicates": [5020.0], "unit": "score", "name": "Decrypt", "value": 53.0241035856574}, {"lowerIsBetter": false, "replicates": [954.1984732824427], "unit": "score", "name": "RayTrace", "value": 775.5084720000002}, {"lowerIsBetter": false, "replicates": [555.247084952804], "unit": "score", "name": "Earley", "value": 1200.2998630000004}, {"lowerIsBetter": false, "replicates": [10479.166666666666], "unit": "score", "name": "Boyer", "value": 63.59885487077532}, {"lowerIsBetter": false, "replicates": [23857.14285714286], "unit": "score", "name": "RegExp", "value": 38.185}, {"lowerIsBetter": false, "replicates": [537.9273504273505], "unit": "score", "name": "Splay", "value": 151.490716981132}, {"lowerIsBetter": false, "replicates": [4812.5], "unit": "score", "name": "NavierStokes", "value": 308.3636363636364}], "name": "v8_7", "value": 23268.435806704892}]} 14:43:18 INFO - RETURN: dromaeo_css: 7352.25 14:43:18 INFO - RETURN: kraken: 1469.50 14:43:18 INFO - RETURN: v8_7: 23268.44 14:43:18 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23268.44"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1469.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7352.25"}}} 14:43:18 INFO - Return code: 0 14:43:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 14:43:18 INFO - # TBPL SUCCESS # 14:43:18 INFO - Running post-action listener: _resource_record_post_action 14:43:18 INFO - Running post-run listener: _resource_record_post_run 14:43:19 INFO - Total resource usage - Wall time: 1220s; CPU: 13.0%; Read bytes: 315392; Write bytes: 510877696; Read time: 68; Write time: 541652 14:43:19 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 249085952; Read time: 0; Write time: 325792 14:43:19 INFO - run-tests - Wall time: 1206s; CPU: 12.0%; Read bytes: 315392; Write bytes: 261791744; Read time: 68; Write time: 215860 14:43:19 INFO - Running post-run listener: _upload_blobber_files 14:43:19 INFO - Blob upload gear active. 14:43:19 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 14:43:19 INFO - Copying logs to upload dir... 14:43:19 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1249.797566 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-01-04 14:43:19.007203) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-04 14:43:19.010829) ========= 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=652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010019 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451937795/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-04 14:43:19.061351) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:43:19.061691) ========= 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=652eb03db935cfc1c15ebda0000001ad-1451946147.804629-1087502360 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004528 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:43:19.111522) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-04 14:43:19.111852) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-04 14:43:19.112180) ========= ========= Total master_lag: 0.23 =========