builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-030 starttime: 1452653424.6 results: success (0) buildid: 20160112162131 builduid: f9e47599db174eecbb1ba31cea2c97a7 revision: 94987e23aa72 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:24.604500) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:24.604923) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:24.605253) ========= 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=4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.005862 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:24.629430) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:24.629719) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:24.654261) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:24.654531) ========= 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=4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004540 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:24.703409) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:24.703677) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:24.704044) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:24.704332) ========= 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=4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-12 18:50:24-- 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% 84.3M=0s 2016-01-12 18:50:25 (84.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.570852 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:25.287643) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:25.287970) ========= 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=4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016818 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:25.337985) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:25.338434) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 94987e23aa72 --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 94987e23aa72 --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=4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-12 18:50:25,443 Setting DEBUG logging. 2016-01-12 18:50:25,443 attempt 1/10 2016-01-12 18:50:25,443 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/94987e23aa72?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-12 18:50:25,704 unpacking tar archive at: mozilla-beta-94987e23aa72/testing/mozharness/ program finished with exit code 0 elapsedTime=0.560896 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:25.911617) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:25.911885) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:25.922041) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:25.922324) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-12 18:50:25.922688) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-01-12 18:50:25.922976) ========= /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=4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:50:26 INFO - MultiFileLogger online at 20160112 18:50:26 in /builds/slave/test-pgo 18:50:26 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 18:50:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 18:50:26 INFO - {'append_to_log': False, 18:50:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 18:50:26 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:50:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 18:50:26 INFO - 'branch': 'Mozilla-Beta', 18:50:26 INFO - 'buildbot_json_path': 'buildprops.json', 18:50:26 INFO - 'config_files': ('talos/linux_config.py',), 18:50:26 INFO - 'default_actions': ('clobber', 18:50:26 INFO - 'read-buildbot-config', 18:50:26 INFO - 'download-and-extract', 18:50:26 INFO - 'populate-webroot', 18:50:26 INFO - 'create-virtualenv', 18:50:26 INFO - 'install', 18:50:26 INFO - 'run-tests'), 18:50:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:50:26 INFO - 'download_minidump_stackwalk': True, 18:50:26 INFO - 'download_symbols': 'ondemand', 18:50:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:50:26 INFO - 'tooltool.py': '/tools/tooltool.py', 18:50:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:50:26 INFO - '/tools/misc-python/virtualenv.py')}, 18:50:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:50:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:50:26 INFO - 'installer_path': 'installer.exe', 18:50:26 INFO - 'log_level': 'info', 18:50:26 INFO - 'log_name': 'talos', 18:50:26 INFO - 'log_to_console': True, 18:50:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:50:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:50:26 INFO - 'opt_config_files': (), 18:50:26 INFO - 'pip_index': False, 18:50:26 INFO - 'sps_profile': False, 18:50:26 INFO - 'sps_profile_interval': 0, 18:50:26 INFO - 'suite': 'dromaeojs', 18:50:26 INFO - 'system_bits': '32', 18:50:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:50:26 INFO - 'title': 'talos-linux64-ix-030', 18:50:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:50:26 INFO - 'use_talos_json': True, 18:50:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 18:50:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:50:26 INFO - 'work_dir': 'build'} 18:50:26 INFO - ##### 18:50:26 INFO - ##### Running clobber step. 18:50:26 INFO - ##### 18:50:26 INFO - Running pre-action listener: _resource_record_pre_action 18:50:26 INFO - Running main action method: clobber 18:50:26 INFO - rmtree: /builds/slave/test-pgo/build 18:50:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 18:50:26 INFO - Running post-action listener: _resource_record_post_action 18:50:26 INFO - ##### 18:50:26 INFO - ##### Running read-buildbot-config step. 18:50:26 INFO - ##### 18:50:26 INFO - Running pre-action listener: _resource_record_pre_action 18:50:26 INFO - Running main action method: read_buildbot_config 18:50:26 INFO - Using buildbot properties: 18:50:26 INFO - { 18:50:26 INFO - "properties": { 18:50:26 INFO - "buildnumber": 85, 18:50:26 INFO - "product": "firefox", 18:50:26 INFO - "script_repo_revision": "production", 18:50:26 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 18:50:26 INFO - "repository": "", 18:50:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 18:50:26 INFO - "buildid": "20160112162131", 18:50:26 INFO - "slavename": "talos-linux64-ix-030", 18:50:26 INFO - "pgo_build": "True", 18:50:26 INFO - "basedir": "/builds/slave/test-pgo", 18:50:26 INFO - "project": "", 18:50:26 INFO - "platform": "ubuntu64_hw", 18:50:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:50:26 INFO - "slavebuilddir": "test", 18:50:26 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 18:50:26 INFO - "branch": "mozilla-beta", 18:50:26 INFO - "repo_path": "releases/mozilla-beta", 18:50:26 INFO - "stage_platform": "linux64-pgo", 18:50:26 INFO - "builduid": "f9e47599db174eecbb1ba31cea2c97a7", 18:50:26 INFO - "revision": "94987e23aa72" 18:50:26 INFO - }, 18:50:26 INFO - "sourcestamp": { 18:50:26 INFO - "repository": "", 18:50:26 INFO - "hasPatch": false, 18:50:26 INFO - "project": "", 18:50:26 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 18:50:26 INFO - "changes": [ 18:50:26 INFO - { 18:50:26 INFO - "category": null, 18:50:26 INFO - "files": [ 18:50:26 INFO - { 18:50:26 INFO - "url": null, 18:50:26 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/firefox-44.0.en-US.linux-x86_64.tar.bz2" 18:50:26 INFO - } 18:50:26 INFO - ], 18:50:26 INFO - "repository": "", 18:50:26 INFO - "rev": "94987e23aa72", 18:50:26 INFO - "who": "sendchange", 18:50:26 INFO - "when": 1452653385, 18:50:26 INFO - "number": 7452535, 18:50:26 INFO - "comments": "Bug 1224736: When image size lookup fails in nsTreeBodyFrame::PaintImage, only fall back to use the full destRect if we_ve got a VectorImage. r=tn a=ritu", 18:50:26 INFO - "project": "", 18:50:26 INFO - "at": "Tue 12 Jan 2016 18:49:45", 18:50:26 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 18:50:26 INFO - "revlink": "", 18:50:26 INFO - "properties": [ 18:50:26 INFO - [ 18:50:26 INFO - "buildid", 18:50:26 INFO - "20160112162131", 18:50:26 INFO - "Change" 18:50:26 INFO - ], 18:50:26 INFO - [ 18:50:26 INFO - "builduid", 18:50:26 INFO - "f9e47599db174eecbb1ba31cea2c97a7", 18:50:26 INFO - "Change" 18:50:26 INFO - ], 18:50:26 INFO - [ 18:50:26 INFO - "pgo_build", 18:50:26 INFO - "True", 18:50:26 INFO - "Change" 18:50:26 INFO - ] 18:50:26 INFO - ], 18:50:26 INFO - "revision": "94987e23aa72" 18:50:26 INFO - } 18:50:26 INFO - ], 18:50:26 INFO - "revision": "94987e23aa72" 18:50:26 INFO - } 18:50:26 INFO - } 18:50:26 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/firefox-44.0.en-US.linux-x86_64.tar.bz2. 18:50:26 INFO - Running post-action listener: _resource_record_post_action 18:50:26 INFO - ##### 18:50:26 INFO - ##### Running download-and-extract step. 18:50:26 INFO - ##### 18:50:26 INFO - Running pre-action listener: _resource_record_pre_action 18:50:26 INFO - Running main action method: download_and_extract 18:50:26 INFO - mkdir: /builds/slave/test-pgo/build/tests 18:50:26 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')]} 18:50:26 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/test_packages.json 18:50:26 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 18:50:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 18:50:26 INFO - Downloaded 1270 bytes. 18:50:26 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 18:50:26 INFO - Using the following test package requirements: 18:50:26 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 18:50:26 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:50:26 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 18:50:26 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:50:26 INFO - u'jsshell-linux-x86_64.zip'], 18:50:26 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:50:26 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 18:50:26 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 18:50:26 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:50:26 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 18:50:26 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:50:26 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 18:50:26 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:50:26 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 18:50:26 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 18:50:26 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:50:26 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 18:50:26 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:50:26 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')]} 18:50:26 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:50:26 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/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 18:50:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/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 18:50:30 INFO - Downloaded 22573077 bytes. 18:50: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 18:50:30 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:50: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 18:50:31 INFO - Return code: 0 18:50: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 18:50: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')]} 18:50:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:50:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/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 18:50:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/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 18:50:33 INFO - Downloaded 22573077 bytes. 18:50:33 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 18:50:33 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:50:33 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 18:50:35 INFO - Return code: 0 18:50:35 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')]} 18:50:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 18:50:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/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 18:50:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/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 18:50:37 INFO - Downloaded 11131970 bytes. 18:50:37 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 18:50:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 18:50:37 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 18:50:38 INFO - Return code: 0 18:50:38 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')]} 18:50:38 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/firefox-44.0.en-US.linux-x86_64.tar.bz2 18:50:38 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/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 18:50:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/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 18:50:51 INFO - Downloaded 56619167 bytes. 18:50:51 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/firefox-44.0.en-US.linux-x86_64.tar.bz2 18:50:51 INFO - mkdir: /builds/slave/test-pgo/properties 18:50:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 18:50:51 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 18:50:51 INFO - Contents: 18:50:51 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/firefox-44.0.en-US.linux-x86_64.tar.bz2 18:50:51 INFO - Running post-action listener: _resource_record_post_action 18:50:51 INFO - Running post-action listener: set_extra_try_arguments 18:50:51 INFO - ##### 18:50:51 INFO - ##### Running populate-webroot step. 18:50:51 INFO - ##### 18:50:51 INFO - Running pre-action listener: _resource_record_pre_action 18:50:51 INFO - Running main action method: populate_webroot 18:50:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:50:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:50:51 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:50:51 INFO - '../fennec_ids.txt'], 18:50:51 INFO - 'tests': ['tcheck2']}, 18:50:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:50:51 INFO - '--tppagecycles', 18:50:51 INFO - '7'], 18:50:51 INFO - 'tests': ['tsvgm']}}, 18:50:51 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:50:51 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:50:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:50:51 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:50:51 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:50:51 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:50:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:50:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:51 INFO - 'talos_options': ['--e10s'], 18:50:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:50:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:51 INFO - 'tests': ['damp', 'tps']}, 18:50:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:51 INFO - 'talos_options': ['--e10s'], 18:50:51 INFO - 'tests': ['damp', 'tps']}, 18:50:51 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:50:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:50:51 INFO - 'other': {'tests': ['a11yr', 18:50:51 INFO - 'ts_paint', 18:50:51 INFO - 'tpaint', 18:50:51 INFO - 'sessionrestore', 18:50:51 INFO - 'sessionrestore_no_auto_restore']}, 18:50:51 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:50:51 INFO - 'tests': ['a11yr', 18:50:51 INFO - 'ts_paint', 18:50:51 INFO - 'tpaint', 18:50:51 INFO - 'sessionrestore', 18:50:51 INFO - 'sessionrestore_no_auto_restore']}, 18:50:51 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:50:51 INFO - 'tests': ['a11yr', 18:50:51 INFO - 'ts_paint', 18:50:51 INFO - 'tpaint', 18:50:51 INFO - 'sessionrestore', 18:50:51 INFO - 'sessionrestore_no_auto_restore']}, 18:50:51 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:50:51 INFO - 'tests': ['a11yr', 18:50:51 INFO - 'ts_paint', 18:50:51 INFO - 'tpaint', 18:50:51 INFO - 'sessionrestore', 18:50:51 INFO - 'sessionrestore_no_auto_restore']}, 18:50:51 INFO - 'other_l64': {'tests': ['a11yr', 18:50:51 INFO - 'ts_paint', 18:50:51 INFO - 'tpaint', 18:50:51 INFO - 'sessionrestore', 18:50:51 INFO - 'sessionrestore_no_auto_restore']}, 18:50:51 INFO - 'other_nol64': {'tests': ['a11yr', 18:50:51 INFO - 'ts_paint', 18:50:51 INFO - 'tpaint', 18:50:51 INFO - 'sessionrestore', 18:50:51 INFO - 'sessionrestore_no_auto_restore']}, 18:50:51 INFO - 'svgr': {'tests': ['tsvgx', 18:50:51 INFO - 'tsvgr_opacity', 18:50:51 INFO - 'tart', 18:50:51 INFO - 'tscrollx', 18:50:51 INFO - 'cart']}, 18:50:51 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:50:51 INFO - 'tests': ['tsvgx', 18:50:51 INFO - 'tsvgr_opacity', 18:50:51 INFO - 'tart', 18:50:51 INFO - 'tscrollx', 18:50:51 INFO - 'cart']}, 18:50:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:51 INFO - 'tests': ['tp5o']}, 18:50:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:51 INFO - 'talos_options': ['--e10s'], 18:50:51 INFO - 'tests': ['tp5o']}, 18:50:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:51 INFO - 'talos_options': ['--xperf_path', 18:50:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:50:51 INFO - 'tests': ['tp5n']}, 18:50:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:51 INFO - 'talos_options': ['--e10s', 18:50:51 INFO - '--xperf_path', 18:50:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:50:51 INFO - 'tests': ['tp5n']}}, 18:50:51 INFO - 'talos.zip': {'path': '', 18:50:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:50:51 INFO - Running post-action listener: _resource_record_post_action 18:50:51 INFO - ##### 18:50:51 INFO - ##### Running create-virtualenv step. 18:50:51 INFO - ##### 18:50:51 INFO - Running pre-action listener: _resource_record_pre_action 18:50:51 INFO - Running main action method: create_virtualenv 18:50:51 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 18:50:51 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 18:50:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 18:50:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:50:51 INFO - Using real prefix '/usr' 18:50:51 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 18:50:52 INFO - Installing distribute.............................................................................................................................................................................................done. 18:50:54 INFO - Installing pip.................done. 18:50:54 INFO - Return code: 0 18:50:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 18:50: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')]} 18:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50: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')]} 18:50:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f07ea8dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2162600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f1350>, '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 0x21f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f26e0>, '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': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', '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 18:50: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', 'pip>=1.5'] in /builds/slave/test-pgo/build 18:50: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 pip>=1.5 18:50:54 INFO - Using env: {'DISPLAY': ':0', 18:50:54 INFO - 'HOME': '/home/cltbld', 18:50:54 INFO - 'LANG': 'en_US.UTF-8', 18:50:54 INFO - 'LANGUAGE': 'en_US:en', 18:50:54 INFO - 'LOGNAME': 'cltbld', 18:50:54 INFO - 'MAIL': '/var/mail/cltbld', 18:50:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:54 INFO - 'MOZ_NO_REMOTE': '1', 18:50:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:54 INFO - 'NO_EM_RESTART': '1', 18:50:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:50:54 INFO - 'PWD': '/builds/slave/test-pgo', 18:50:54 INFO - 'SHELL': '/bin/bash', 18:50:54 INFO - 'SHLVL': '1', 18:50:54 INFO - 'TERM': 'linux', 18:50:54 INFO - 'TMOUT': '86400', 18:50:54 INFO - 'USER': 'cltbld', 18:50:54 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', 18:50:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:54 INFO - '_': '/tools/buildbot/bin/python'} 18:50:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:54 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 18:50:54 INFO - Cleaning up... 18:50:54 INFO - Return code: 0 18:50:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 18:50: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')]} 18:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50: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')]} 18:50:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50: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', '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 0x7f07ea8dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2162600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f1350>, '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 0x21f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f26e0>, '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': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', '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 18:50: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', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 18:50: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 psutil>=0.7.1 18:50:54 INFO - Using env: {'DISPLAY': ':0', 18:50:54 INFO - 'HOME': '/home/cltbld', 18:50:54 INFO - 'LANG': 'en_US.UTF-8', 18:50:54 INFO - 'LANGUAGE': 'en_US:en', 18:50:54 INFO - 'LOGNAME': 'cltbld', 18:50:54 INFO - 'MAIL': '/var/mail/cltbld', 18:50:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:54 INFO - 'MOZ_NO_REMOTE': '1', 18:50:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:54 INFO - 'NO_EM_RESTART': '1', 18:50:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:50:54 INFO - 'PWD': '/builds/slave/test-pgo', 18:50:54 INFO - 'SHELL': '/bin/bash', 18:50:54 INFO - 'SHLVL': '1', 18:50:54 INFO - 'TERM': 'linux', 18:50:54 INFO - 'TMOUT': '86400', 18:50:54 INFO - 'USER': 'cltbld', 18:50:54 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', 18:50:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:54 INFO - '_': '/tools/buildbot/bin/python'} 18:50:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:54 INFO - Downloading/unpacking psutil>=0.7.1 18:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:56 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 18:50:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:50:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 18:50:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:50:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:50:56 INFO - Installing collected packages: psutil 18:50:56 INFO - Running setup.py install for psutil 18:50:56 INFO - building 'psutil._psutil_linux' extension 18:50:56 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 18:50:56 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 18:50:56 INFO - building 'psutil._psutil_posix' extension 18:50:56 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 18:50:57 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 18:50:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:50:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:50:57 INFO - Successfully installed psutil 18:50:57 INFO - Cleaning up... 18:50:57 INFO - Return code: 0 18:50:57 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 18:50:57 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')]} 18:50:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:57 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')]} 18:50:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:57 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 0x7f07ea8dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2162600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f1350>, '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 0x21f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f26e0>, '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': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', '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 18:50:57 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 18:50:57 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 18:50:57 INFO - Using env: {'DISPLAY': ':0', 18:50:57 INFO - 'HOME': '/home/cltbld', 18:50:57 INFO - 'LANG': 'en_US.UTF-8', 18:50:57 INFO - 'LANGUAGE': 'en_US:en', 18:50:57 INFO - 'LOGNAME': 'cltbld', 18:50:57 INFO - 'MAIL': '/var/mail/cltbld', 18:50:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:57 INFO - 'MOZ_NO_REMOTE': '1', 18:50:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:57 INFO - 'NO_EM_RESTART': '1', 18:50:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:50:57 INFO - 'PWD': '/builds/slave/test-pgo', 18:50:57 INFO - 'SHELL': '/bin/bash', 18:50:57 INFO - 'SHLVL': '1', 18:50:57 INFO - 'TERM': 'linux', 18:50:57 INFO - 'TMOUT': '86400', 18:50:57 INFO - 'USER': 'cltbld', 18:50:57 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', 18:50:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:57 INFO - '_': '/tools/buildbot/bin/python'} 18:50:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:57 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:50:57 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 18:50:57 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 18:50:57 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 18:50:57 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 18:50:57 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 18:50:57 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 18:50:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:50:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:50:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:50:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:50:59 INFO - Installing collected packages: mozsystemmonitor 18:50:59 INFO - Running setup.py install for mozsystemmonitor 18:50:59 INFO - Successfully installed mozsystemmonitor 18:50:59 INFO - Cleaning up... 18:50:59 INFO - Return code: 0 18:50:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 18:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:59 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 0x7f07ea8dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2162600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f1350>, '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 0x21f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f26e0>, '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': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', '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 18:50:59 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 18:50:59 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 18:50:59 INFO - Using env: {'DISPLAY': ':0', 18:50:59 INFO - 'HOME': '/home/cltbld', 18:50:59 INFO - 'LANG': 'en_US.UTF-8', 18:50:59 INFO - 'LANGUAGE': 'en_US:en', 18:50:59 INFO - 'LOGNAME': 'cltbld', 18:50:59 INFO - 'MAIL': '/var/mail/cltbld', 18:50:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:59 INFO - 'MOZ_NO_REMOTE': '1', 18:50:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:59 INFO - 'NO_EM_RESTART': '1', 18:50:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:50:59 INFO - 'PWD': '/builds/slave/test-pgo', 18:50:59 INFO - 'SHELL': '/bin/bash', 18:50:59 INFO - 'SHLVL': '1', 18:50:59 INFO - 'TERM': 'linux', 18:50:59 INFO - 'TMOUT': '86400', 18:50:59 INFO - 'USER': 'cltbld', 18:50:59 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', 18:50:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:59 INFO - '_': '/tools/buildbot/bin/python'} 18:50:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:59 INFO - Downloading/unpacking blobuploader==1.2.4 18:50:59 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 18:50:59 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 18:50:59 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 18:50:59 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 18:50:59 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 18:50:59 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 18:51:01 INFO - Downloading blobuploader-1.2.4.tar.gz 18:51:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:51:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:51:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:51:01 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 18:51:01 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 18:51:01 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 18:51:01 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 18:51:01 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 18:51:01 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 18:51:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:51:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 18:51:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:51:01 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 18:51:01 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 18:51:01 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 18:51:01 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 18:51:01 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 18:51:01 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 18:51:01 INFO - Downloading docopt-0.6.1.tar.gz 18:51:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:51:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 18:51:02 INFO - Installing collected packages: blobuploader, requests, docopt 18:51:02 INFO - Running setup.py install for blobuploader 18:51:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:51:02 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 18:51:02 INFO - Running setup.py install for requests 18:51:02 INFO - Running setup.py install for docopt 18:51:02 INFO - Successfully installed blobuploader requests docopt 18:51:02 INFO - Cleaning up... 18:51:02 INFO - Return code: 0 18:51:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:51:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:02 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 0x7f07ea8dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2162600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f1350>, '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 0x21f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f26e0>, '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': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', '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 18:51:02 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 18:51:02 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 18:51:02 INFO - Using env: {'DISPLAY': ':0', 18:51:02 INFO - 'HOME': '/home/cltbld', 18:51:02 INFO - 'LANG': 'en_US.UTF-8', 18:51:02 INFO - 'LANGUAGE': 'en_US:en', 18:51:02 INFO - 'LOGNAME': 'cltbld', 18:51:02 INFO - 'MAIL': '/var/mail/cltbld', 18:51:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:02 INFO - 'MOZ_NO_REMOTE': '1', 18:51:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:51:02 INFO - 'NO_EM_RESTART': '1', 18:51:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:51:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:51:02 INFO - 'PWD': '/builds/slave/test-pgo', 18:51:02 INFO - 'SHELL': '/bin/bash', 18:51:02 INFO - 'SHLVL': '1', 18:51:02 INFO - 'TERM': 'linux', 18:51:02 INFO - 'TMOUT': '86400', 18:51:02 INFO - 'USER': 'cltbld', 18:51:02 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', 18:51:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:02 INFO - '_': '/tools/buildbot/bin/python'} 18:51:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:51:02 INFO - Running setup.py (path:/tmp/pip-7GtbTU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:51:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:51:02 INFO - Running setup.py (path:/tmp/pip-uOXOAh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:51:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:51:02 INFO - Running setup.py (path:/tmp/pip-kJk1U5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:51:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:51:03 INFO - Running setup.py (path:/tmp/pip-t_wEnS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:51:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:51:03 INFO - Running setup.py (path:/tmp/pip-wlAZkr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:51:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:51:03 INFO - Running setup.py (path:/tmp/pip-wlftia-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:51:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:51:03 INFO - Running setup.py (path:/tmp/pip-K5k0Ra-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:51:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:51:03 INFO - Running setup.py (path:/tmp/pip-bAAMYe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:51:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:51:03 INFO - Running setup.py (path:/tmp/pip-1urvOO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:51:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:51:03 INFO - Running setup.py (path:/tmp/pip-ocssj_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:51:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:51:03 INFO - Running setup.py (path:/tmp/pip-qJTEtM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:51:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:51:03 INFO - Running setup.py (path:/tmp/pip-ibHlW5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:51:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:51:03 INFO - Running setup.py (path:/tmp/pip-W4ju3j-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:51:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:51:03 INFO - Running setup.py (path:/tmp/pip-O0MRHs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:51:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:51:03 INFO - Running setup.py (path:/tmp/pip-R9mFLC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:51:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:51:03 INFO - Running setup.py (path:/tmp/pip-d1SsFP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:51:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:51:03 INFO - Running setup.py (path:/tmp/pip-q6NiY8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:51:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:51:04 INFO - Running setup.py install for manifestparser 18:51:04 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:51:04 INFO - Running setup.py install for mozcrash 18:51:04 INFO - Running setup.py install for mozdebug 18:51:04 INFO - Running setup.py install for mozdevice 18:51:04 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:51:04 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:51:04 INFO - Running setup.py install for mozfile 18:51:04 INFO - Running setup.py install for mozhttpd 18:51:04 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:51:04 INFO - Running setup.py install for mozinfo 18:51:04 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:51:04 INFO - Running setup.py install for mozInstall 18:51:04 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:51:04 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:51:04 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:51:04 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:51:04 INFO - Running setup.py install for mozleak 18:51:04 INFO - Running setup.py install for mozlog 18:51:05 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:51:05 INFO - Running setup.py install for moznetwork 18:51:05 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:51:05 INFO - Running setup.py install for mozprocess 18:51:05 INFO - Running setup.py install for mozprofile 18:51:05 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:51:05 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:51:05 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:51:05 INFO - Running setup.py install for mozrunner 18:51:05 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:51:05 INFO - Running setup.py install for mozscreenshot 18:51:05 INFO - Running setup.py install for moztest 18:51:05 INFO - Running setup.py install for mozversion 18:51:05 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:51:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:51:05 INFO - Cleaning up... 18:51:05 INFO - Return code: 0 18:51:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:51:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7f07ea8dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2162600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f1350>, '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 0x21f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f26e0>, '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': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', '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 18:51:05 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 18:51:05 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 18:51:05 INFO - Using env: {'DISPLAY': ':0', 18:51:05 INFO - 'HOME': '/home/cltbld', 18:51:05 INFO - 'LANG': 'en_US.UTF-8', 18:51:05 INFO - 'LANGUAGE': 'en_US:en', 18:51:05 INFO - 'LOGNAME': 'cltbld', 18:51:05 INFO - 'MAIL': '/var/mail/cltbld', 18:51:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:05 INFO - 'MOZ_NO_REMOTE': '1', 18:51:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:51:05 INFO - 'NO_EM_RESTART': '1', 18:51:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:51:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:51:05 INFO - 'PWD': '/builds/slave/test-pgo', 18:51:05 INFO - 'SHELL': '/bin/bash', 18:51:05 INFO - 'SHLVL': '1', 18:51:05 INFO - 'TERM': 'linux', 18:51:05 INFO - 'TMOUT': '86400', 18:51:05 INFO - 'USER': 'cltbld', 18:51:05 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', 18:51:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:05 INFO - '_': '/tools/buildbot/bin/python'} 18:51:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:51:06 INFO - Running setup.py (path:/tmp/pip-h6AJtv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:51:06 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)) 18:51:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:51:06 INFO - Running setup.py (path:/tmp/pip-8HrpKG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:51:06 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)) 18:51:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:51:06 INFO - Running setup.py (path:/tmp/pip-6zH73Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:51:06 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)) 18:51:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:51:06 INFO - Running setup.py (path:/tmp/pip-Ak_Rsl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:51:06 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)) 18:51:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:51:06 INFO - Running setup.py (path:/tmp/pip-4alQCN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:51:06 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)) 18:51:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:51:06 INFO - Running setup.py (path:/tmp/pip-QtaMjc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:51:06 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)) 18:51:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:51:06 INFO - Running setup.py (path:/tmp/pip-rrUo4v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:51:06 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)) 18:51:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:51:06 INFO - Running setup.py (path:/tmp/pip-rMgiIs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:51:06 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)) 18:51:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:51:06 INFO - Running setup.py (path:/tmp/pip-xuGHPD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:51:06 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)) 18:51:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:51:06 INFO - Running setup.py (path:/tmp/pip-ijiUgu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:51:06 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)) 18:51:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:51:06 INFO - Running setup.py (path:/tmp/pip-lxZN5i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:51:06 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)) 18:51:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:51:06 INFO - Running setup.py (path:/tmp/pip-3sc7b5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:51:06 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)) 18:51:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:51:06 INFO - Running setup.py (path:/tmp/pip-Nn7RHn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:51:06 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)) 18:51:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:51:06 INFO - Running setup.py (path:/tmp/pip-hQjBYQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:51:07 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)) 18:51:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:51:07 INFO - Running setup.py (path:/tmp/pip-ta1hdm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:51:07 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)) 18:51:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:51:07 INFO - Running setup.py (path:/tmp/pip-SgDioK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:51:07 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)) 18:51:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:51:07 INFO - Running setup.py (path:/tmp/pip-83pq3q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:51:07 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)) 18:51:07 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)) 18:51:07 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)) 18:51:07 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)) 18:51:07 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)) 18:51:07 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)) 18:51:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:51:07 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 18:51:07 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 18:51:07 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 18:51:07 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 18:51:07 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 18:51:07 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 18:51:08 INFO - Downloading blessings-1.5.1.tar.gz 18:51:08 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 18:51:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:51:09 INFO - Installing collected packages: blessings 18:51:09 INFO - Running setup.py install for blessings 18:51:09 INFO - Successfully installed blessings 18:51:09 INFO - Cleaning up... 18:51:10 INFO - Return code: 0 18:51:10 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:51:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:51:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:51:11 INFO - Reading from file tmpfile_stdout 18:51:11 INFO - Current package versions: 18:51:11 INFO - argparse == 1.2.1 18:51:11 INFO - blessings == 1.5.1 18:51:11 INFO - blobuploader == 1.2.4 18:51:11 INFO - docopt == 0.6.1 18:51:11 INFO - manifestparser == 1.1 18:51:11 INFO - mozInstall == 1.12 18:51:11 INFO - mozcrash == 0.16 18:51:11 INFO - mozdebug == 0.1 18:51:11 INFO - mozdevice == 0.46 18:51:11 INFO - mozfile == 1.2 18:51:11 INFO - mozhttpd == 0.7 18:51:11 INFO - mozinfo == 0.8 18:51:11 INFO - mozleak == 0.1 18:51:11 INFO - mozlog == 3.0 18:51:11 INFO - moznetwork == 0.27 18:51:11 INFO - mozprocess == 0.22 18:51:11 INFO - mozprofile == 0.27 18:51:11 INFO - mozrunner == 6.11 18:51:11 INFO - mozscreenshot == 0.1 18:51:11 INFO - mozsystemmonitor == 0.0 18:51:11 INFO - moztest == 0.7 18:51:11 INFO - mozversion == 1.4 18:51:11 INFO - psutil == 3.1.1 18:51:11 INFO - requests == 1.2.3 18:51:11 INFO - wsgiref == 0.1.2 18:51:11 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:51:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:11 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7f07ea8dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2162600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f1350>, '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 0x21f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f26e0>, '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': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', '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 18:51:11 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 18:51:11 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 18:51:11 INFO - Using env: {'DISPLAY': ':0', 18:51:11 INFO - 'HOME': '/home/cltbld', 18:51:11 INFO - 'LANG': 'en_US.UTF-8', 18:51:11 INFO - 'LANGUAGE': 'en_US:en', 18:51:11 INFO - 'LOGNAME': 'cltbld', 18:51:11 INFO - 'MAIL': '/var/mail/cltbld', 18:51:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:11 INFO - 'MOZ_NO_REMOTE': '1', 18:51:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:51:11 INFO - 'NO_EM_RESTART': '1', 18:51:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:51:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:51:11 INFO - 'PWD': '/builds/slave/test-pgo', 18:51:11 INFO - 'SHELL': '/bin/bash', 18:51:11 INFO - 'SHLVL': '1', 18:51:11 INFO - 'TERM': 'linux', 18:51:11 INFO - 'TMOUT': '86400', 18:51:11 INFO - 'USER': 'cltbld', 18:51:11 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', 18:51:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:11 INFO - '_': '/tools/buildbot/bin/python'} 18:51:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:11 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)) 18:51:11 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)) 18:51:11 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)) 18:51:11 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)) 18:51:11 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)) 18:51:11 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)) 18:51:11 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)) 18:51:11 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)) 18:51:11 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)) 18:51:11 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)) 18:51:11 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)) 18:51:11 INFO - Cleaning up... 18:51:11 INFO - Return code: 0 18:51:11 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 18:51:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:11 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f07ea8dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2162600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f1350>, '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 0x21f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f26e0>, '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': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', '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 18:51:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 18:51:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:51:11 INFO - Using env: {'DISPLAY': ':0', 18:51:11 INFO - 'HOME': '/home/cltbld', 18:51:11 INFO - 'LANG': 'en_US.UTF-8', 18:51:11 INFO - 'LANGUAGE': 'en_US:en', 18:51:11 INFO - 'LOGNAME': 'cltbld', 18:51:11 INFO - 'MAIL': '/var/mail/cltbld', 18:51:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:11 INFO - 'MOZ_NO_REMOTE': '1', 18:51:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:51:11 INFO - 'NO_EM_RESTART': '1', 18:51:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:51:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:51:11 INFO - 'PWD': '/builds/slave/test-pgo', 18:51:11 INFO - 'SHELL': '/bin/bash', 18:51:11 INFO - 'SHLVL': '1', 18:51:11 INFO - 'TERM': 'linux', 18:51:11 INFO - 'TMOUT': '86400', 18:51:11 INFO - 'USER': 'cltbld', 18:51:11 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', 18:51:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:11 INFO - '_': '/tools/buildbot/bin/python'} 18:51:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:11 INFO - Downloading/unpacking jsonschema 18:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:51:13 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 18:51:13 INFO - Downloading/unpacking functools32 (from jsonschema) 18:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:13 INFO - Downloading functools32-3.2.3-2.tar.gz 18:51:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 18:51:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 18:51:13 INFO - warning: no files found matching '*.txt' 18:51:13 INFO - no previously-included directories found matching 'build' 18:51:13 INFO - no previously-included directories found matching 'dist' 18:51:13 INFO - no previously-included directories found matching '.git*' 18:51:13 INFO - Installing collected packages: jsonschema, functools32 18:51:13 INFO - Running setup.py install for functools32 18:51:13 INFO - warning: no files found matching '*.txt' 18:51:13 INFO - no previously-included directories found matching 'build' 18:51:13 INFO - no previously-included directories found matching 'dist' 18:51:13 INFO - no previously-included directories found matching '.git*' 18:51:14 INFO - Successfully installed jsonschema functools32 18:51:14 INFO - Cleaning up... 18:51:14 INFO - Return code: 0 18:51:14 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 18:51:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:14 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 0x7f07ea8dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2162600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f1350>, '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 0x21f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f26e0>, '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': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', '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 18:51:14 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 18:51:14 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 18:51:14 INFO - Using env: {'DISPLAY': ':0', 18:51:14 INFO - 'HOME': '/home/cltbld', 18:51:14 INFO - 'LANG': 'en_US.UTF-8', 18:51:14 INFO - 'LANGUAGE': 'en_US:en', 18:51:14 INFO - 'LOGNAME': 'cltbld', 18:51:14 INFO - 'MAIL': '/var/mail/cltbld', 18:51:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:14 INFO - 'MOZ_NO_REMOTE': '1', 18:51:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:51:14 INFO - 'NO_EM_RESTART': '1', 18:51:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:51:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:51:14 INFO - 'PWD': '/builds/slave/test-pgo', 18:51:14 INFO - 'SHELL': '/bin/bash', 18:51:14 INFO - 'SHLVL': '1', 18:51:14 INFO - 'TERM': 'linux', 18:51:14 INFO - 'TMOUT': '86400', 18:51:14 INFO - 'USER': 'cltbld', 18:51:14 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', 18:51:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:14 INFO - '_': '/tools/buildbot/bin/python'} 18:51:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:14 INFO - Downloading/unpacking flake8 18:51:14 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 18:51:14 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 18:51:14 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 18:51:14 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 18:51:14 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 18:51:14 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 18:51:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:51:15 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 18:51:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:51:16 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 18:51:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:51:16 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 18:51:16 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 18:51:16 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 18:51:16 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 18:51:16 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 18:51:16 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 18:51:16 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 18:51:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:51:16 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 18:51:16 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 18:51:16 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 18:51:16 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 18:51:16 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 18:51:16 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 18:51:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 18:51:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 18:51:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:51:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:51:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:51:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:51:16 INFO - no previously-included directories found matching 'docs/_build' 18:51:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:51:16 INFO - Running setup.py install for pep8 18:51:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:51:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:51:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:51:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:51:16 INFO - no previously-included directories found matching 'docs/_build' 18:51:16 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 18:51:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:51:16 INFO - Cleaning up... 18:51:16 INFO - Return code: 0 18:51:16 INFO - Running post-action listener: _resource_record_post_action 18:51:16 INFO - Running post-action listener: _start_resource_monitoring 18:51:16 INFO - Starting resource monitoring. 18:51:16 INFO - ##### 18:51:16 INFO - ##### Running install step. 18:51:16 INFO - ##### 18:51:16 INFO - Running pre-action listener: _resource_record_pre_action 18:51:16 INFO - Running main action method: install 18:51:16 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:51:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:51:17 INFO - Reading from file tmpfile_stdout 18:51:17 INFO - Detecting whether we're running mozinstall >=1.0... 18:51:17 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:51:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:51:17 INFO - Reading from file tmpfile_stdout 18:51:17 INFO - Output received: 18:51:17 INFO - Usage: mozinstall [options] installer 18:51:17 INFO - Options: 18:51:17 INFO - -h, --help show this help message and exit 18:51:17 INFO - -d DEST, --destination=DEST 18:51:17 INFO - Directory to install application into. [default: 18:51:17 INFO - "/builds/slave/test-pgo"] 18:51:17 INFO - --app=APP Application being installed. [default: firefox] 18:51:17 INFO - mkdir: /builds/slave/test-pgo/build/application 18:51:17 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'] 18:51:17 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 18:51:31 INFO - Reading from file tmpfile_stdout 18:51:31 INFO - Output received: 18:51:31 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:51:31 INFO - Running post-action listener: _resource_record_post_action 18:51:31 INFO - ##### 18:51:31 INFO - ##### Running run-tests step. 18:51:31 INFO - ##### 18:51:31 INFO - Running pre-action listener: _resource_record_pre_action 18:51:31 INFO - Running main action method: run_tests 18:51:31 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:51:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:51:31 INFO - Python 2.7.3 18:51:31 INFO - Return code: 0 18:51:31 INFO - grabbing minidump binary from tooltool 18:51: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')]} 18:51:31 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 0x21f1350>, '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 0x21f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f26e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:51:31 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 18:51:31 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 18:51:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:51:31 INFO - Return code: 0 18:51:31 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:51:31 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:51:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:51:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:51:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:51:31 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 18:51:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 18:51:32 INFO - Return code: 0 18:51:32 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/1452644491/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 18:51:32 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/1452644491/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-030 --webServer localhost 18:51:32 INFO - Using env: {'DISPLAY': ':0', 18:51:32 INFO - 'HOME': '/home/cltbld', 18:51:32 INFO - 'LANG': 'en_US.UTF-8', 18:51:32 INFO - 'LANGUAGE': 'en_US:en', 18:51:32 INFO - 'LOGNAME': 'cltbld', 18:51:32 INFO - 'MAIL': '/var/mail/cltbld', 18:51:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:51:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:51:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:32 INFO - 'MOZ_NO_REMOTE': '1', 18:51:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:51:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:51:32 INFO - 'NO_EM_RESTART': '1', 18:51:32 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:51:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:51:32 INFO - 'PWD': '/builds/slave/test-pgo', 18:51:32 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:51:32 INFO - 'SHELL': '/bin/bash', 18:51:32 INFO - 'SHLVL': '1', 18:51:32 INFO - 'TERM': 'linux', 18:51:32 INFO - 'TMOUT': '86400', 18:51:32 INFO - 'USER': 'cltbld', 18:51:32 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052', 18:51:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:32 INFO - '_': '/tools/buildbot/bin/python'} 18:51:32 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/1452644491/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost'] with output_timeout 3600 18:51:32 INFO - mozversion INFO | application_buildid: 20160112162131 18:51:32 INFO - mozversion INFO | application_changeset: 94987e23aa720af290174d37d94e7bd2dc5c3dbb 18:51:32 INFO - mozversion INFO | application_display_name: Nightly 18:51:32 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:51:32 INFO - mozversion INFO | application_name: Firefox 18:51:32 INFO - mozversion INFO | application_remotingname: firefox 18:51:32 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:51:32 INFO - mozversion INFO | application_vendor: Mozilla 18:51:32 INFO - mozversion INFO | application_version: 44.0 18:51:32 INFO - mozversion INFO | platform_buildid: 20160112162131 18:51:32 INFO - mozversion INFO | platform_changeset: 94987e23aa720af290174d37d94e7bd2dc5c3dbb 18:51:32 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:51:32 INFO - mozversion INFO | platform_version: 44.0 18:51:32 INFO - 2016-01-12 18:51:32,567 DEBUG : using testdate: 1452653492 18:51:32 INFO - 2016-01-12 18:51:32,567 DEBUG : actual date: 1452653492 18:51:32 INFO - 2016-01-12 18:51:32,576 INFO : starting webserver on 'localhost:50687' 18:51:32 INFO - 2016-01-12 18:51:32,577 INFO : Starting test suite talos-linux64-ix-030 18:51:32 INFO - 2016-01-12 18:51:32,577 INFO : Starting test dromaeo_css 18:51:32 INFO - 2016-01-12 18:51:32,577 DEBUG : operating with platform_type : linux_ 18:51:32 INFO - 2016-01-12 18:51:32,578 INFO : Initialising browser for dromaeo_css test... 18:51:32 INFO - 2016-01-12 18:51:32,594 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp_PDBo_/profile http://localhost:50687/getInfo.html 18:51:36 INFO - 2016-01-12 18:51:36,452 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:51:36 INFO - 2016-01-12 18:51:36,453 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:51:36 INFO - 2016-01-12 18:51:36,453 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:51:36 INFO - 2016-01-12 18:51:36,453 DEBUG : BROWSER_OUTPUT: __metrics 18:51:36 INFO - 2016-01-12 18:51:36,614 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] 18:51:37 INFO - 2016-01-12 18:51:37,829 INFO : Browser initialized. 18:51:37 INFO - 2016-01-12 18:51:37,830 INFO : Running cycle 1/1 for dromaeo_css test... 18:51:37 INFO - 2016-01-12 18:51:37,830 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp_PDBo_/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:51:49 INFO - 2016-01-12 18:51:49,358 DEBUG : BROWSER_OUTPUT: RSS: Main: 129122304 18:51:49 INFO - 2016-01-12 18:51:49,358 DEBUG : BROWSER_OUTPUT: 18:52:40 INFO - 2016-01-12 18:52:40,395 DEBUG : BROWSER_OUTPUT: 1452653560394 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] 18:54:48 INFO - 2016-01-12 18:54:48,217 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50687/tests/dromaeo/cssquery-ext.html) 18:54:49 INFO - 2016-01-12 18:54:49,293 DEBUG : BROWSER_OUTPUT: RSS: Main: 239108096 18:54:49 INFO - 2016-01-12 18:54:49,293 DEBUG : BROWSER_OUTPUT: 18:57:51 INFO - 2016-01-12 18:57:51,358 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/dromaeo/cssquery-ext.html (next: http://localhost:50687/tests/dromaeo/cssquery-jquery.html) 18:57:51 INFO - 2016-01-12 18:57:51,921 DEBUG : BROWSER_OUTPUT: RSS: Main: 205099008 18:57:51 INFO - 2016-01-12 18:57:51,921 DEBUG : BROWSER_OUTPUT: 19:00:53 INFO - 2016-01-12 19:00:53,671 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50687/tests/dromaeo/cssquery-mootools.html) 19:00:54 INFO - 2016-01-12 19:00:54,234 DEBUG : BROWSER_OUTPUT: RSS: Main: 207728640 19:00:54 INFO - 2016-01-12 19:00:54,235 DEBUG : BROWSER_OUTPUT: 19:03:55 INFO - 2016-01-12 19:03:55,894 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50687/tests/dromaeo/cssquery-prototype.html) 19:03:56 INFO - 2016-01-12 19:03:56,468 DEBUG : BROWSER_OUTPUT: RSS: Main: 192167936 19:03:56 INFO - 2016-01-12 19:03:56,469 DEBUG : BROWSER_OUTPUT: 19:06:58 INFO - 2016-01-12 19:06:58,851 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50687/tests/dromaeo/cssquery-yui.html) 19:06:59 INFO - 2016-01-12 19:06:59,404 DEBUG : BROWSER_OUTPUT: RSS: Main: 195747840 19:06:59 INFO - 2016-01-12 19:06:59,404 DEBUG : BROWSER_OUTPUT: 19:10:01 INFO - 2016-01-12 19:10:01,483 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/dromaeo/cssquery-yui.html (next: http://localhost:50687/tests/dromaeo/cssquery-dojo.html) 19:10:01 INFO - 2016-01-12 19:10:01,741 DEBUG : BROWSER_OUTPUT: RSS: Main: 235425792 19:10:01 INFO - 2016-01-12 19:10:01,741 DEBUG : BROWSER_OUTPUT: 19:10:01 INFO - 2016-01-12 19:10:01,741 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:10:01 INFO - 2016-01-12 19:10:01,741 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:10:01 INFO - 2016-01-12 19:10:01,741 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:10:01 INFO - 2016-01-12 19:10:01,741 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:10:01 INFO - 2016-01-12 19:10:01,741 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3216.0994921422002;3276.197331653745;3307.338532293542;3312.039508542523;3334.0664613908107;5528.917066244009;5578.470045345685;5580.414056524068;5599.1041433370665;5606.495415412612;5558.055130627793;5599.888002239963;5610.663360198391;5612.915806262909;5622.803201887935;4981.178105612569;5070.569001634856;5104.285400043978;5188.584913206953;5195;4937.827176048839;5020.272060551218;5047.722375269359;5124.205748109049;5125.282460455521;5118.564921981636;5137.3064636274;5141.688869338313;5146.536811686948;5161.896762064756;4900.754962251909;5062.443131255559;5085.872853178663;5172.353455818023;5194.272801807731;4874.341963834872;4961.305417241589;4983.90032199354;4993.875153121165;5012.423571289312;5297.894042119136;5393.649412788156;5408.486193811621;5410.864728381783;5469.808556700496;5830.3586605473365;5935.465808077293;6052.031674932034;6069.423404776539;6072.45347918685;5971.611845230046;6059.36376680451;6066;6067.514598832083;6099.878002439971;5686.43135686428;5702.372738998707;5719.370869204384;5726.141078838166;5728.169415434739;5977.850553736192;5989.460948514655;6100.72546735398;6180.598261113013;6195.19462469885;5957;5998.430149135826;6009.549283803644;6011.338752737195;6012.038073908241;6068.605540639845;6079.0273556230795;6082.087686847009;6087.908681369783;6091.969540152271;5813.82558523245;5856.853578660568;5874.412558744091;5982.312034116046;6048.425399587032;6008;6017.909731354033;6024.819255422344;6025.8192254232445;6054.273487181566;4634.397528321297;4637.04940487206;4637.605803506673;4640.32715256286;4666.486686464486;4038.2327357801923;4052.250333688277;4125.752454852718;4158.875233742992;4170.791460426966;5723.141528770634;5727.885442291131;5742.827715168552;5746.454086861741;5746.741396637161;5913.113033044991;6071.544634152457;6086.239220097488;6089.725962331706;6091.451769340781;5579.46995035471;5638.830835074954;5651.2935883014625;5667.773289068391;5682.687452190086;5189.273501709764;5218.869528261824;5220.3735551734035;5220.556252718561;5237.004969055867;6018.518518518508;6120.326764055951;6203.720832562545;6235.033569796688;6239.844003899939;5961.374055724086;6047.758089676364;6056.727447264796;6065.211522502046;6081.422264884829;6148.077788331786;6165.537584681257;6221.844453888689;6229.0967809668255;6229.5327850410495;5827.970860145672;5853.94146058534;5916.26046744157;6002.219711437485;6039.275286965417;5985.850353741017;6068.6965651716355;6074.757009719562;6083.7870674526175;6085.878282434238;5897.970510147594;5914.378990206139;5917.526597872074;5919.585629005928;5919.733611987384;5689.459501347365;5745.8563535911935;5803.216565763568;5865.560082993878;5892.823215303548;5783.797567085132;5841.269841269841;5842.123681447817;5848.093545500455;5848.561357898089;5908.881822363614;5940.97029514867;5943.702814859326;5949.851253718692;5965.224520812267;5622.32532096151;5725.370209277059;5773.538116950718;5868.970655146697;5892.94107058924 19:10:01 INFO - 2016-01-12 19:10:01,741 DEBUG : BROWSER_OUTPUT: |1;ext.html;576.8759716660917;608.3764141754701;614.2138063279003;625.9436650701439;627.7457629659983;42251.36622950658;42717.7864110679;42736.572634273645;42765;42820.785896070476;48369;48997.51002489974;49076.26385604219;49122.75438622802;49189.754051229786;8180.836383272331;8298.585070746438;9093.681721139761;9266.721998340061;10457.21570882183;10575.153987681006;10603.310784798963;10661.520231589559;10678.946605266963;10687.412192329419;10647.467626618676;10649.84025239622;10749.62376316829;10750.569977200941;10750.78498430031;10434.113100386476;10493.632722854663;10518.105960993325;10527.052565269125;10531.104856087204;10532.894671053267;10997.780044399067;11004.559817607285;11022.834657480144;11032.44837758109;46122.53877461216;46236.5376346239;46316.76841615804;46415.535844641796;46544.767276163744;3707.6663100321102;3826.827792749333;4157.771322577257;4187.43469631602;4535.546445355471;33744.49383259253;34702.13244668878;34865.82567087174;34920;34931.30137397263;3421.264428147935;3442;3455.2916652086515;3523.735719820998;3524.7004004659634;329187;329275;329363;330312;331070;61210.69394652998;62200.37799621946;78501;79315;79420;73315;73428.63285683538;73616.26383736094;73801.6309918447;73915.26084739083;18884.811151888305;18994;19021.714674279898;19141.712874306897;19196;14439;14551;14556.781648275284;14758.55724328272;14777;22661.660075098887;22721.886390567943;22722.091116355492;22746.545069098527;22835.31494055181;7912.28789408956;8065.193480652005;8186.713465028695;8239.835203296021;8255.917440825635;1050.726811029138;1125.8700811743367;1129.615930583589;1134.319408354997;1156.5258244120039;31882.20294492656;32079.839600801846;32483.18792030218;32768.836155819066;32917.01248962535;1160.5937921727498;1196.5632544121333;1205.7287110400266;1231.1382032577233;1272.1349482352089;14622.707545849024;16442.671146577002;16635.584110397336;16700.49898503047;16921.492355229362;406470;410625;412196;412657;414087;62078.379216208166;62079.379206208265;62296;62348;62527;1295.7602843473987;1364.6793003644136;1432.7349440353569;1477.8078849749463;1480.104536755269;29717.702822971492;29935.850320749127;30992.380152396374;31467.84266078655;32297.51553726743;51926.221106684185;52113.218301724744;52142.47857521376;52282;52332;310752;311573;312275;312930;313817;35462.46806297856;36726.816365918;36905.44641830429;36907;36978;43182;43436;44121.11757764891;44308.77845610751;44366.11267774691;41353.586464134976;41884.58115418807;42644.57355426406;43357.78321108374;43458.56541434545;42127.57872421236;42310;42664.36003459888;43216.35175472307;43284.13431731285;41586;41697.1660566793;42164.78917605392;42224;42234.36648450214;32538.186545336557;33077.834610826794;33198;33770.8311458451;35577.64422355847;26132.60801087947;27916.720832791412;28055.117679527368;28092;28092.71907280901 19:10:01 INFO - 2016-01-12 19:10:01,743 DEBUG : BROWSER_OUTPUT: |2;jquery.html;389.4178203585639;390.63280516314643;391.5555844116926;392.17055926715005;393.38828122269865;14372.63459971301;18057.277708891645;19936;19940.401787946368;20834.583308333826;19149.42551723447;19185.52036199096;19341.323053693122;19459.124339404727;19462.1242044108;5701.657900525971;5820.650760954346;5825.912611310834;5828.562857785661;5830.708464576775;5717.942820571782;5797.971010144965;5845.210896528956;5852.707364631773;5880.235569375996;4373.956260437387;4490.708103973231;5914.9408505915035;5924.526037916957;5926.436988486087;6059.515238780909;6280.497560195174;6334.556581039329;6334.778282760081;6335.968320158393;5591.189277554772;5680.460356266148;5810.3899090595305;5813.883722325572;5832.708364581754;17773.289068437247;17786.110694465355;20988.475288117766;21532.354029379145;23431.765682343128;2340.1107579119994;2361.6929799126005;2373.2287006722886;2430.562498750242;2529.190658989124;15077.849221507864;15215.08709477435;15386.384544618202;15440.922795385952;15666.295016724274;1815.682255605277;1820.5266630675987;1840.411068458094;1850.2783914273443;1852.7220916862443;72625;73673.6316318415;74996.25003750002;75009.62495187543;75165.62417187933;11708.590199343153;12039.63881083569;12177.878221217852;12181.634550963485;12198.512059517698;16205.675886482204;16299.837001630069;16322.591935201715;16360.67278654444;16365.181740913145;6183.319834818257;6379.904301435482;6461.8061458156335;6555.016747487915;6602.537822352485;7047.43620510358;7050.189228238818;7053.612051337226;7104.92895071053;7107.218205997337;3684.981575092161;3734.57052438973;3743.6256374362347;3746.662800347982;3759.9624003759614;1272.8790764877322;1286.7619490394306;1293.0948336164533;1297.5264028629483;1344.5562964221979;474.59896387552465;506.12693104394623;511.60095125801934;525.3931708876254;539.7625044980197;12284.324362159989;12541.749165016648;13243.337833108306;13359.998000150028;13621;536.9301990741257;540.5891522442893;541.8780774325824;544.8256557901512;557.1698169727077;5917;7077.752278670222;7085.716571337191;7277.708891644381;7503.699852005859;623183;627000;632520;647706;648544;81954.5914073281;82921;84132;84170.15829841624;87060.1293987052;624.09506215987;625.1185827982523;625.9749610015548;627.5732501898661;628.4940622798599;12531.87468125307;12788.424520896768;12833;12944.41750121228;13026.218426894417;37853;38227.80886095552;38577.228455431294;38957.415638764876;40161.1967760649;48745.756271218415;49342;50597.24104138515;50634.24048639347;50664.240036400224;7647.579383133981;7711.961440192763;7730.729424470116;7762.146163921851;7798.181190975093;13136.343182840628;13309.201447913156;13649;13730.244836533688;14020;11810.350430726392;11868.643940681794;11973.880261197277;12079;12101.394930253276;11535.596254131066;11621.535138594361;11819.82270265964;11933.4033298333;12066.577669781516;12048.879511205127;12122.27266364021;12151.453184606891;12244.938775306422;12439.440225190068;6847.07564478789;7555.886661700189;7616.20029896875;7729.961350193213;7733.729319473791;9709.465979371202;11765.882341176479;11926.403679816149;12033.75932481328;12147.149699520949 19:10:01 INFO - 2016-01-12 19:10:01,744 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1634.419780977753;1643.3919449803568;1908.2939312454384;1911.636789010088;1933.8839669619815;8321.542315172663;8665.436746611465;8683.088275731052;8690.695825646104;8692.478451292927;7508.66211020504;7668.693252269916;8246.299064579518;8400.285975692073;8517.829643407144;2347.0963678983544;2358.8584684918874;2383.9642405363934;2387.200287903546;2388.10446082719;2384.2608791274674;2423.7091549014053;2428.0530593068715;2442.535918175541;2443.4868677577642;2402.1352313167245;2414.7705967933107;2434.464417828075;2438.9512209755794;2464.2114523352534;2307.3654744945106;2356.4815740537056;2358.5282943411303;2377.6076947303673;2384.5350156719446;2365.27859003004;2376.4771750214927;2396.5446565152565;2400.135951057618;2400.1599440195964;8335.416520843544;8395.328373730086;8403.201695838885;8406.705765298186;8422.663093476254;5082;5114.488551144893;5141.280220769096;5142.357205349332;5149.6910185389;3280.7867488613165;3281.278118813857;3314;3319.9834000830083;3340.0981734931433;1114.3369695031524;1152.8328392383141;1161.372858656333;1164.604034628222;1174.3364998775664;11586.768264634744;11671.649850504498;11698.649040528799;11717.355545444998;11721.706957326134;4464.598186163196;4507.2788353863225;4517.2094883395275;4552.9772351138035;4553.886152846206;10091.848622270672;10107.241956853266;10110.039546243095;10115.747106322253;10121.24090693201;5100.69395836251;5101;5104.03023425548;5108.2848401223855;5109.20807274873;4232.619064284229;4315.28797748368;4617.676762626651;4628.652851036186;4685.906281874343;3991.26161660088;4037.0916543778003;4076.673866090765;4079.204555111784;4133.111381053088;1309.319154039913;1322.7023919618202;1327.5287273017939;1335.5058628307588;1338.9263590502421;1124.6569796212234;1178.4166837415362;1276.8850803427736;1496.7081419123167;1510.5317351621034;8142.837143257102;8181.222783835526;8199.877001844978;8200.220979006985;8224.38317126218;1130.9547618095348;1138.299945533491;1191.8569771627288;1243.0911666699965;1251.443107817031;6508.349165083454;6528.640924749185;6544.509161812883;6584.440322572539;6586.209654841449;10290.485475726184;10446.112080473245;10447.425391603534;10449.68650940473;10459.84310235347;9623.951880240695;9811;10311.484425778832;10344.32761870476;10354.378737275787;7908.367330613537;7959.482633628795;7969.481983671043;7971.800704982538;7987.520748755093;7905.4070944680525;7997.560134192442;8007.599620018859;8027.759167224993;8047.83904321922;8968.10318968098;9092;9120.452772833385;9126.954365228132;9141.085891410805;9121.179093881583;9179.082091791035;9219.585118669542;9392.95303523478;9418.576164072496;6579;6677.09859169006;7332.670029848777;7606.353459955966;7665.846683066419;8019.478733882279;8037.276645101969;8049.39629527776;8057.476264042818;8066.959665201871;7228.31331023552;7535.660895259617;7555.773326800205;7604.733834315773;7609.39124869998;7412.332890039922;7425.071866016748;7513.4740568159705;7569;7622.123455802654;7618.238176182337;7643.847123057397;7655.349295309738;7657.004589403342;7658.425618078778;6980.685869136003;7071.964640176766;7101.360877521048;7126.714931402686;7227.205007449282;7172.318629730167;7179.174394944648;7219.350258476762;7225.891611625935;7232.0959880015 19:10:01 INFO - 2016-01-12 19:10:01,744 DEBUG : BROWSER_OUTPUT: |4;prototype.html;362.5214716574122;363.2372018760602;366.6058986589417;367.7793324005597;375.530586766542;5796.275465566804;5881.823545293648;5887.470127688507;5935.169076329312;5946.702664866755;5701.372848986619;5799;5819.563532735041;5820.097884827858;5823.825285241439;4586.151561961031;4588.357629931812;4591.494935557086;4591.540845915415;4592.72443653381;4443.800028998703;4498.167838949788;4519.5932366087045;4588.564086411802;4595.678302518825;4506.932396014063;4513.029698614797;4520.796564154604;4523.841665541707;4528.20756367636;4531.705439146462;4531.796069176862;4538.069695712371;4538.909221815562;4546.931796023029;4023.8591649292134;4025.8993525161814;4030.375291829771;4031.4355990161403;4037.192561487685;5528.75120619573;5576;5582.246396736429;5617.297837770278;5645.463680950304;5256.553192978601;5259.316288882459;5259.815906443295;5260.316158899357;5262.500062494057;5963.970180149072;6204.100405441231;6211.0372892201785;6212.844678883064;6219.129321894939;4317.266064768997;4365.083332500179;4414.911701765946;4416.183006143874;4418.050119224382;6305.68471576424;6385.297617262097;6395.616263024233;6397.008463688136;6397.584157029778;4510.887227819331;4515.254982927846;4518.661100417482;4520.0507893342765;4524.909501809945;4496.9325460118125;4559.931601025987;4564.612007979359;4565.315202719552;4652.558006989399;5308.973455132699;5312.096943519611;5313.760880760375;5316.65441746293;5317.441668624816;6318.241810982711;6325.9051114233325;6326.114343991845;6328.968355158195;6329.715162817684;1640.409452597064;1662.4347721774413;1665.5253252822888;1665.608581983233;1665.8500734933914;1114.0419239454063;1116.1126904111252;1116.8827273136367;1119.2165484160957;1146.3179408252076;6025.367336429699;6026.156338112668;6029.60807547508;6032.969835150884;6034.577579569388;6334.334894835976;6347.587406818542;6348.714307856158;6349.333320001426;6377.23473183221;6028.246469191351;6031.909521357183;6037.577369584087;6038.215032045806;6040.033594624839;3493.9301213975577;3547.6629720176543;3550.041488798062;3550.8757193498104;3552.5914519830035;6598.1422415085735;6610.471162307004;6612.107365505595;6618.768343107968;6623.437007854386;6529.183852018497;6558.573692709959;6559.344065593403;6565.80302590923;6572.309907459744;6470.320616335312;6596.80209593713;6607.735690572324;6615.040819081206;6726.730930762715;6263;6335.2714437838395;6528.37980391862;6532.738690452328;6546.80359589194;6256.468200203034;6287.497000239879;6307.274663413767;6331.746730130743;6354.3963323484195;6560.9343906560325;6584.144061272195;6584.506162037962;6614.338566143347;6615;4445.221736414741;4482.260427029503;4515.164694531522;4519.61583265413;4566.360709500739;6537.084808126961;6537.346265373425;6543.869122617426;6547.443467305332;6552.410283074546;6338.683065846782;6347.270063942521;6353.8729225416155;6358.1734374531015;6365.363463653597;6242.375762423721;6341.968290158519;6343.9682801585695;6350.1744773181035;6364.236291645032;6261.467775239155;6264.812055638338;6272.247330320391;6275.905861411991;6282.026285925689;6331.9366806331345;6387.105805187371;6394;6394.360563943568;6396.584222025554;6105.236845394325;6242.875142497215;6251.937480625135;6257.937420625735;6258.593191442542 19:10:01 INFO - 2016-01-12 19:10:01,745 DEBUG : BROWSER_OUTPUT: |5;yui.html;796.9960150199248;854.6495936665966;909.5906841921137;920.1725570902756;928.4522131942152;14313.499027534039;14593.708125837476;14603.050801697882;14679.853201467982;14751.77872331916;14399;14444.927775361108;14451.566453006426;14471.78292325616;14477.5656730298;776.4526009163526;787.3937068457284;925.3661242049184;932.4125800745554;947.3652652722658;941.6468824190929;943.9622415103422;948.6442584030989;950.719537736366;951.362587066667;821.7247222180577;991.1773228220595;999.535216124502;1001.4141727089625;1002.5588740953957;828.6850996621275;976.9462679552622;995.975100622483;998.1465846701111;998.5207100591684;735.0848193998453;737.2480070328289;737.2848337112991;737.71229220604;737.881938889775;13828.585142445743;13990.510332138327;13990.790138147935;14003.929980350033;14051.718965620732;13951.093178943438;13999.930000350036;14089.436422543085;14129.646758831008;14162;11386.658400248005;11584.652460426201;11624.302541847432;11662.650120496399;11914.225575337576;3506.491558723996;3508.2808024354945;3520.3839328117733;3548.946765798515;3645.5807582127863;53041;53535.46464535344;53564.46435535634;53935.730321348536;54194.45805541934;8119.431639785158;8197.877031844528;8217.383696222807;8221.588920553948;8261.38039647029;14134.08128471646;14136.575902722934;14155;14158.29208539569;14159.716805664035;7271.381932535688;7380.77857664271;7391.334779869838;7434.925650743423;7462.96268518654;4582.839600614029;4611.769411529477;4683.906321873544;4703.95296047042;4729.5034021427255;3055.9694403055687;3085.9074227772753;3086.90739277822;3088.027271409499;3089.274020605156;459.2697610798846;465.664721400591;472.7777944366142;482.5873877834471;485.7886819233645;621.1211136242167;635.9936400636027;653.5523166630778;664.9168853893264;673.4746897419923;8512;8901.376903616814;8995.460272383678;9073.682421115229;9243.218882632355;497.01094821305344;614.4040280927493;625.3433894410941;637.0635166305524;660.7852447954338;4048.0891799344913;4315.395844581761;4396.450443694538;4591.150637132141;4701.741404222732;69431.65284173648;69933;69981;70021.29978700148;70066.29933700597;59668.70165649231;60723.69638151781;61637.69181154066;61978.380216198166;62002.689986549776;54597;55581.166282505794;55749.442505575236;55827.72086139624;55947.16079258815;8891.822163556822;9243.722688319362;9479.668211612836;9489.098535639103;9677.564509597225;37346.81326593459;37799.62200377961;38221.235575287785;39067.21865562616;40915.7954210227;52930.4706952941;53172.73413633062;55159;55273.17090243569;55847.16229256483;8246.257836794717;8571.95714021426;8643.654253830027;8987.101289870961;9099.044600317136;11388;11644.76710465769;11700.941495292469;11800.645980620595;11980.28118312904;11456.083513319081;11781.646550603497;11939.641810745692;12120.090993175723;12581.433835477243;10741.24811263204;11164.665060047886;11550.768984620092;12155.513779448724;12233.571824986082;10377.221708372053;11329.773404532029;11443.427828608703;11781.646550603497;12065.517379304729;9927.354721943051;10147.137493313152;10325.225608079574;10375.84436233442;10480.895191047992;10148.898511014795;10227.43749093807;10253.538590763583;10298.794024119625;10343.120834728832 19:10:01 INFO - 2016-01-12 19:10:01,745 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:10:01 INFO - 2016-01-12 19:10:01,745 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:10:01 INFO - 2016-01-12 19:10:01,745 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,820 19:10:01 INFO - 2016-01-12 19:10:01,745 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:10:01 INFO - 2016-01-12 19:10:01,746 DEBUG : BROWSER_OUTPUT: __startTimestamp1452654601736__endTimestamp 19:10:01 INFO - 2016-01-12 19:10:01,746 DEBUG : BROWSER_OUTPUT: 19:10:01 INFO - 2016-01-12 19:10:01,746 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:10:01 INFO - 2016-01-12 19:10:01,746 DEBUG : BROWSER_OUTPUT: Number of tests: 6 19:10:01 INFO - 2016-01-12 19:10:01,746 DEBUG : BROWSER_OUTPUT: 19:10:01 INFO - 2016-01-12 19:10:01,746 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5579.96 Median:5813.83 stddev:634.78 (10.9%) stddev-sans-first:609.03 19:10:01 INFO - 2016-01-12 19:10:01,746 DEBUG : BROWSER_OUTPUT: Values: 3216.1 3276.2 3307.3 3312.0 3334.1 5528.9 5578.5 5580.4 5599.1 5606.5 5558.1 5599.9 5610.7 5612.9 5622.8 4981.2 5070.6 5104.3 5188.6 5195.0 4937.8 5020.3 5047.7 5124.2 5125.3 5118.6 5137.3 5141.7 5146.5 5161.9 4900.8 5062.4 5085.9 5172.4 5194.3 4874.3 4961.3 4983.9 4993.9 5012.4 5297.9 5393.6 5408.5 5410.9 5469.8 5830.4 5935.5 6052.0 6069.4 6072.5 5971.6 6059.4 6066.0 6067.5 6099.9 5686.4 5702.4 5719.4 5726.1 5728.2 5977.9 5989.5 6100.7 6180.6 6195.2 5957.0 5998.4 6009.5 6011.3 6012.0 6068.6 6079.0 6082.1 6087.9 6092.0 5813.8 5856.9 5874.4 5982.3 6048.4 6008.0 6017.9 6024.8 6025.8 6054.3 4634.4 4637.0 4637.6 4640.3 4666.5 4038.2 4052.3 4125.8 4158.9 4170.8 5723.1 5727.9 5742.8 5746.5 5746.7 5913.1 6071.5 6086.2 6089.7 6091.5 5579.5 5638.8 5651.3 5667.8 5682.7 5189.3 5218.9 5220.4 5220.6 5237.0 6018.5 6120.3 6203.7 6235.0 6239.8 5961.4 6047.8 6056.7 6065.2 6081.4 6148.1 6165.5 6221.8 6229.1 6229.5 5828.0 5853.9 5916.3 6002.2 6039.3 5985.9 6068.7 6074.8 6083.8 6085.9 5898.0 5914.4 5917.5 5919.6 5919.7 5689.5 5745.9 5803.2 5865.6 5892.8 5783.8 5841.3 5842.1 5848.1 5848.6 5908.9 5941.0 5943.7 5949.9 5965.2 5622.3 5725.4 5773.5 5869.0 5892.9 19:10:01 INFO - 2016-01-12 19:10:01,746 DEBUG : BROWSER_OUTPUT: 19:10:01 INFO - 2016-01-12 19:10:01,746 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:54525.37 Median:34892.91 stddev:92724.04 (265.7%) stddev-sans-first:92895.68 19:10:01 INFO - 2016-01-12 19:10:01,748 DEBUG : BROWSER_OUTPUT: Values: 576.9 608.4 614.2 625.9 627.7 42251.4 42717.8 42736.6 42765.0 42820.8 48369.0 48997.5 49076.3 49122.8 49189.8 8180.8 8298.6 9093.7 9266.7 10457.2 10575.2 10603.3 10661.5 10678.9 10687.4 10647.5 10649.8 10749.6 10750.6 10750.8 10434.1 10493.6 10518.1 10527.1 10531.1 10532.9 10997.8 11004.6 11022.8 11032.4 46122.5 46236.5 46316.8 46415.5 46544.8 3707.7 3826.8 4157.8 4187.4 4535.5 33744.5 34702.1 34865.8 34920.0 34931.3 3421.3 3442.0 3455.3 3523.7 3524.7 329187.0 329275.0 329363.0 330312.0 331070.0 61210.7 62200.4 78501.0 79315.0 79420.0 73315.0 73428.6 73616.3 73801.6 73915.3 18884.8 18994.0 19021.7 19141.7 19196.0 14439.0 14551.0 14556.8 14758.6 14777.0 22661.7 22721.9 22722.1 22746.5 22835.3 7912.3 8065.2 8186.7 8239.8 8255.9 1050.7 1125.9 1129.6 1134.3 1156.5 31882.2 32079.8 32483.2 32768.8 32917.0 1160.6 1196.6 1205.7 1231.1 1272.1 14622.7 16442.7 16635.6 16700.5 16921.5 406470.0 410625.0 412196.0 412657.0 414087.0 62078.4 62079.4 62296.0 62348.0 62527.0 1295.8 1364.7 1432.7 1477.8 1480.1 29717.7 29935.9 30992.4 31467.8 32297.5 51926.2 52113.2 52142.5 52282.0 52332.0 310752.0 311573.0 312275.0 312930.0 313817.0 35462.5 36726.8 36905.4 36907.0 36978.0 43182.0 43436.0 44121.1 44308.8 44366.1 41353.6 41884.6 42644.6 43357.8 43458.6 42127.6 42310.0 42664.4 43216.4 43284.1 41586.0 41697.2 42164.8 42224.0 42234.4 32538.2 33077.8 33198.0 33770.8 35577.6 26132.6 27916.7 28055.1 28092.0 28092.7 19:10:01 INFO - 2016-01-12 19:10:01,748 DEBUG : BROWSER_OUTPUT: 19:10:01 INFO - 2016-01-12 19:10:01,748 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31882.39 Median:391.86 stddev:104060.19 (26555.2%) stddev-sans-first:104325.24 19:10:01 INFO - 2016-01-12 19:10:01,748 DEBUG : BROWSER_OUTPUT: Values: 389.4 390.6 391.6 392.2 393.4 14372.6 18057.3 19936.0 19940.4 20834.6 19149.4 19185.5 19341.3 19459.1 19462.1 5701.7 5820.7 5825.9 5828.6 5830.7 5717.9 5798.0 5845.2 5852.7 5880.2 4374.0 4490.7 5914.9 5924.5 5926.4 6059.5 6280.5 6334.6 6334.8 6336.0 5591.2 5680.5 5810.4 5813.9 5832.7 17773.3 17786.1 20988.5 21532.4 23431.8 2340.1 2361.7 2373.2 2430.6 2529.2 15077.8 15215.1 15386.4 15440.9 15666.3 1815.7 1820.5 1840.4 1850.3 1852.7 72625.0 73673.6 74996.3 75009.6 75165.6 11708.6 12039.6 12177.9 12181.6 12198.5 16205.7 16299.8 16322.6 16360.7 16365.2 6183.3 6379.9 6461.8 6555.0 6602.5 7047.4 7050.2 7053.6 7104.9 7107.2 3685.0 3734.6 3743.6 3746.7 3760.0 1272.9 1286.8 1293.1 1297.5 1344.6 474.6 506.1 511.6 525.4 539.8 12284.3 12541.7 13243.3 13360.0 13621.0 536.9 540.6 541.9 544.8 557.2 5917.0 7077.8 7085.7 7277.7 7503.7 623183.0 627000.0 632520.0 647706.0 648544.0 81954.6 82921.0 84132.0 84170.2 87060.1 624.1 625.1 626.0 627.6 628.5 12531.9 12788.4 12833.0 12944.4 13026.2 37853.0 38227.8 38577.2 38957.4 40161.2 48745.8 49342.0 50597.2 50634.2 50664.2 7647.6 7712.0 7730.7 7762.1 7798.2 13136.3 13309.2 13649.0 13730.2 14020.0 11810.4 11868.6 11973.9 12079.0 12101.4 11535.6 11621.5 11819.8 11933.4 12066.6 12048.9 12122.3 12151.5 12244.9 12439.4 6847.1 7555.9 7616.2 7730.0 7733.7 9709.5 11765.9 11926.4 12033.8 12147.1 19:10:01 INFO - 2016-01-12 19:10:01,748 DEBUG : BROWSER_OUTPUT: 19:10:01 INFO - 2016-01-12 19:10:01,748 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5944.24 Median:5102.52 stddev:3087.85 (60.5%) stddev-sans-first:3079.52 19:10:01 INFO - 2016-01-12 19:10:01,750 DEBUG : BROWSER_OUTPUT: Values: 1634.4 1643.4 1908.3 1911.6 1933.9 8321.5 8665.4 8683.1 8690.7 8692.5 7508.7 7668.7 8246.3 8400.3 8517.8 2347.1 2358.9 2384.0 2387.2 2388.1 2384.3 2423.7 2428.1 2442.5 2443.5 2402.1 2414.8 2434.5 2439.0 2464.2 2307.4 2356.5 2358.5 2377.6 2384.5 2365.3 2376.5 2396.5 2400.1 2400.2 8335.4 8395.3 8403.2 8406.7 8422.7 5082.0 5114.5 5141.3 5142.4 5149.7 3280.8 3281.3 3314.0 3320.0 3340.1 1114.3 1152.8 1161.4 1164.6 1174.3 11586.8 11671.6 11698.6 11717.4 11721.7 4464.6 4507.3 4517.2 4553.0 4553.9 10091.8 10107.2 10110.0 10115.7 10121.2 5100.7 5101.0 5104.0 5108.3 5109.2 4232.6 4315.3 4617.7 4628.7 4685.9 3991.3 4037.1 4076.7 4079.2 4133.1 1309.3 1322.7 1327.5 1335.5 1338.9 1124.7 1178.4 1276.9 1496.7 1510.5 8142.8 8181.2 8199.9 8200.2 8224.4 1131.0 1138.3 1191.9 1243.1 1251.4 6508.3 6528.6 6544.5 6584.4 6586.2 10290.5 10446.1 10447.4 10449.7 10459.8 9624.0 9811.0 10311.5 10344.3 10354.4 7908.4 7959.5 7969.5 7971.8 7987.5 7905.4 7997.6 8007.6 8027.8 8047.8 8968.1 9092.0 9120.5 9127.0 9141.1 9121.2 9179.1 9219.6 9393.0 9418.6 6579.0 6677.1 7332.7 7606.4 7665.8 8019.5 8037.3 8049.4 8057.5 8067.0 7228.3 7535.7 7555.8 7604.7 7609.4 7412.3 7425.1 7513.5 7569.0 7622.1 7618.2 7643.8 7655.3 7657.0 7658.4 6980.7 7072.0 7101.4 7126.7 7227.2 7172.3 7179.2 7219.4 7225.9 7232.1 19:10:01 INFO - 2016-01-12 19:10:01,750 DEBUG : BROWSER_OUTPUT: 19:10:01 INFO - 2016-01-12 19:10:01,750 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5255.88 Median:5994.67 stddev:1550.93 (25.9%) stddev-sans-first:1511.16 19:10:01 INFO - 2016-01-12 19:10:01,750 DEBUG : BROWSER_OUTPUT: Values: 362.5 363.2 366.6 367.8 375.5 5796.3 5881.8 5887.5 5935.2 5946.7 5701.4 5799.0 5819.6 5820.1 5823.8 4586.2 4588.4 4591.5 4591.5 4592.7 4443.8 4498.2 4519.6 4588.6 4595.7 4506.9 4513.0 4520.8 4523.8 4528.2 4531.7 4531.8 4538.1 4538.9 4546.9 4023.9 4025.9 4030.4 4031.4 4037.2 5528.8 5576.0 5582.2 5617.3 5645.5 5256.6 5259.3 5259.8 5260.3 5262.5 5964.0 6204.1 6211.0 6212.8 6219.1 4317.3 4365.1 4414.9 4416.2 4418.1 6305.7 6385.3 6395.6 6397.0 6397.6 4510.9 4515.3 4518.7 4520.1 4524.9 4496.9 4559.9 4564.6 4565.3 4652.6 5309.0 5312.1 5313.8 5316.7 5317.4 6318.2 6325.9 6326.1 6329.0 6329.7 1640.4 1662.4 1665.5 1665.6 1665.9 1114.0 1116.1 1116.9 1119.2 1146.3 6025.4 6026.2 6029.6 6033.0 6034.6 6334.3 6347.6 6348.7 6349.3 6377.2 6028.2 6031.9 6037.6 6038.2 6040.0 3493.9 3547.7 3550.0 3550.9 3552.6 6598.1 6610.5 6612.1 6618.8 6623.4 6529.2 6558.6 6559.3 6565.8 6572.3 6470.3 6596.8 6607.7 6615.0 6726.7 6263.0 6335.3 6528.4 6532.7 6546.8 6256.5 6287.5 6307.3 6331.7 6354.4 6560.9 6584.1 6584.5 6614.3 6615.0 4445.2 4482.3 4515.2 4519.6 4566.4 6537.1 6537.3 6543.9 6547.4 6552.4 6338.7 6347.3 6353.9 6358.2 6365.4 6242.4 6342.0 6344.0 6350.2 6364.2 6261.5 6264.8 6272.2 6275.9 6282.0 6331.9 6387.1 6394.0 6394.4 6396.6 6105.2 6242.9 6251.9 6257.9 6258.6 19:10:01 INFO - 2016-01-12 19:10:01,750 DEBUG : BROWSER_OUTPUT: 19:10:01 INFO - 2016-01-12 19:10:01,750 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15245.54 Median:491.40 stddev:19080.58 (3882.9%) stddev-sans-first:19103.26 19:10:01 INFO - 2016-01-12 19:10:01,750 DEBUG : BROWSER_OUTPUT: Values: 797.0 854.6 909.6 920.2 928.5 14313.5 14593.7 14603.1 14679.9 14751.8 14399.0 14444.9 14451.6 14471.8 14477.6 776.5 787.4 925.4 932.4 947.4 941.6 944.0 948.6 950.7 951.4 821.7 991.2 999.5 1001.4 1002.6 828.7 976.9 996.0 998.1 998.5 735.1 737.2 737.3 737.7 737.9 13828.6 13990.5 13990.8 14003.9 14051.7 13951.1 13999.9 14089.4 14129.6 14162.0 11386.7 11584.7 11624.3 11662.7 11914.2 3506.5 3508.3 3520.4 3548.9 3645.6 53041.0 53535.5 53564.5 53935.7 54194.5 8119.4 8197.9 8217.4 8221.6 8261.4 14134.1 14136.6 14155.0 14158.3 14159.7 7271.4 7380.8 7391.3 7434.9 7463.0 4582.8 4611.8 4683.9 4704.0 4729.5 3056.0 3085.9 3086.9 3088.0 3089.3 459.3 465.7 472.8 482.6 485.8 621.1 636.0 653.6 664.9 673.5 8512.0 8901.4 8995.5 9073.7 9243.2 497.0 614.4 625.3 637.1 660.8 4048.1 4315.4 4396.5 4591.2 4701.7 69431.7 69933.0 69981.0 70021.3 70066.3 59668.7 60723.7 61637.7 61978.4 62002.7 54597.0 55581.2 55749.4 55827.7 55947.2 8891.8 9243.7 9479.7 9489.1 9677.6 37346.8 37799.6 38221.2 39067.2 40915.8 52930.5 53172.7 55159.0 55273.2 55847.2 8246.3 8572.0 8643.7 8987.1 9099.0 11388.0 11644.8 11700.9 11800.6 11980.3 11456.1 11781.6 11939.6 12120.1 12581.4 10741.2 11164.7 11550.8 12155.5 12233.6 10377.2 11329.8 11443.4 11781.6 12065.5 9927.4 10147.1 10325.2 10375.8 10480.9 10148.9 10227.4 10253.5 10298.8 10343.1 19:10:01 INFO - 2016-01-12 19:10:01,750 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:10:01 INFO - 2016-01-12 19:10:01,750 DEBUG : BROWSER_OUTPUT: 19:10:02 INFO - 2016-01-12 19:10:02,321 INFO : Browser exited with error code: 0 19:10:02 INFO - 2016-01-12 19:10:02,344 INFO : Completed test dromaeo_css (00:18:29) 19:10:02 INFO - 2016-01-12 19:10:02,345 INFO : Starting test kraken 19:10:02 INFO - 2016-01-12 19:10:02,345 DEBUG : operating with platform_type : linux_ 19:10:02 INFO - 2016-01-12 19:10:02,345 INFO : Initialising browser for kraken test... 19:10:02 INFO - 2016-01-12 19:10:02,351 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGm2sIQ/profile http://localhost:50687/getInfo.html 19:10:05 INFO - 2016-01-12 19:10:05,725 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:10:05 INFO - 2016-01-12 19:10:05,725 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:10:05 INFO - 2016-01-12 19:10:05,725 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:10:05 INFO - 2016-01-12 19:10:05,725 DEBUG : BROWSER_OUTPUT: __metrics 19:10:05 INFO - 2016-01-12 19:10:05,833 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] 19:10:07 INFO - 2016-01-12 19:10:07,153 INFO : Browser initialized. 19:10:07 INFO - 2016-01-12 19:10:07,154 INFO : Running cycle 1/1 for kraken test... 19:10:07 INFO - 2016-01-12 19:10:07,154 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGm2sIQ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:10:18 INFO - 2016-01-12 19:10:18,661 DEBUG : BROWSER_OUTPUT: RSS: Main: 128860160 19:10:18 INFO - 2016-01-12 19:10:18,661 DEBUG : BROWSER_OUTPUT: 19:10:21 INFO - 2016-01-12 19:10:21,096 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50687/tests/kraken/driver.html?testName=audio-beat-detection) 19:10:21 INFO - 2016-01-12 19:10:21,662 DEBUG : BROWSER_OUTPUT: RSS: Main: 164491264 19:10:21 INFO - 2016-01-12 19:10:21,663 DEBUG : BROWSER_OUTPUT: 19:10:24 INFO - 2016-01-12 19:10:24,239 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50687/tests/kraken/driver.html?testName=audio-dft) 19:10:24 INFO - 2016-01-12 19:10:24,800 DEBUG : BROWSER_OUTPUT: RSS: Main: 161153024 19:10:24 INFO - 2016-01-12 19:10:24,801 DEBUG : BROWSER_OUTPUT: 19:10:27 INFO - 2016-01-12 19:10:27,550 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50687/tests/kraken/driver.html?testName=audio-fft) 19:10:28 INFO - 2016-01-12 19:10:28,124 DEBUG : BROWSER_OUTPUT: RSS: Main: 158339072 19:10:28 INFO - 2016-01-12 19:10:28,125 DEBUG : BROWSER_OUTPUT: 19:10:30 INFO - 2016-01-12 19:10:30,070 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50687/tests/kraken/driver.html?testName=audio-oscillator) 19:10:30 INFO - 2016-01-12 19:10:30,637 DEBUG : BROWSER_OUTPUT: RSS: Main: 157605888 19:10:30 INFO - 2016-01-12 19:10:30,637 DEBUG : BROWSER_OUTPUT: 19:10:32 INFO - 2016-01-12 19:10:32,763 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50687/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:10:33 INFO - 2016-01-12 19:10:33,324 DEBUG : BROWSER_OUTPUT: RSS: Main: 157044736 19:10:33 INFO - 2016-01-12 19:10:33,324 DEBUG : BROWSER_OUTPUT: 19:10:36 INFO - 2016-01-12 19:10:36,181 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50687/tests/kraken/driver.html?testName=imaging-darkroom) 19:10:36 INFO - 2016-01-12 19:10:36,743 DEBUG : BROWSER_OUTPUT: RSS: Main: 156160000 19:10:36 INFO - 2016-01-12 19:10:36,743 DEBUG : BROWSER_OUTPUT: 19:10:40 INFO - 2016-01-12 19:10:40,076 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50687/tests/kraken/driver.html?testName=imaging-desaturate) 19:10:40 INFO - 2016-01-12 19:10:40,643 DEBUG : BROWSER_OUTPUT: RSS: Main: 156495872 19:10:40 INFO - 2016-01-12 19:10:40,643 DEBUG : BROWSER_OUTPUT: 19:10:43 INFO - 2016-01-12 19:10:43,385 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50687/tests/kraken/driver.html?testName=json-parse-financial) 19:10:43 INFO - 2016-01-12 19:10:43,952 DEBUG : BROWSER_OUTPUT: RSS: Main: 154939392 19:10:43 INFO - 2016-01-12 19:10:43,952 DEBUG : BROWSER_OUTPUT: 19:10:45 INFO - 2016-01-12 19:10:45,453 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50687/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:10:46 INFO - 2016-01-12 19:10:46,014 DEBUG : BROWSER_OUTPUT: RSS: Main: 161333248 19:10:46 INFO - 2016-01-12 19:10:46,014 DEBUG : BROWSER_OUTPUT: 19:10:47 INFO - 2016-01-12 19:10:47,756 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50687/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:10:48 INFO - 2016-01-12 19:10:48,346 DEBUG : BROWSER_OUTPUT: RSS: Main: 158867456 19:10:48 INFO - 2016-01-12 19:10:48,346 DEBUG : BROWSER_OUTPUT: 19:10:50 INFO - 2016-01-12 19:10:50,282 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50687/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:10:50 INFO - 2016-01-12 19:10:50,851 DEBUG : BROWSER_OUTPUT: RSS: Main: 165482496 19:10:50 INFO - 2016-01-12 19:10:50,851 DEBUG : BROWSER_OUTPUT: 19:10:53 INFO - 2016-01-12 19:10:53,165 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50687/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:10:53 INFO - 2016-01-12 19:10:53,739 DEBUG : BROWSER_OUTPUT: RSS: Main: 168337408 19:10:53 INFO - 2016-01-12 19:10:53,739 DEBUG : BROWSER_OUTPUT: 19:10:56 INFO - 2016-01-12 19:10:56,173 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50687/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:10:56 INFO - 2016-01-12 19:10:56,735 DEBUG : BROWSER_OUTPUT: RSS: Main: 167456768 19:10:56 INFO - 2016-01-12 19:10:56,735 DEBUG : BROWSER_OUTPUT: 19:10:58 INFO - 2016-01-12 19:10:58,345 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50687/tests/kraken/driver.html?testName=ai-astar) 19:10:58 INFO - 2016-01-12 19:10:58,595 DEBUG : BROWSER_OUTPUT: RSS: Main: 225472512 19:10:58 INFO - 2016-01-12 19:10:58,596 DEBUG : BROWSER_OUTPUT: 19:10:58 INFO - 2016-01-12 19:10:58,596 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:10:58 INFO - 2016-01-12 19:10:58,597 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:10:58 INFO - 2016-01-12 19:10:58,597 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:10:58 INFO - 2016-01-12 19:10:58,597 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:10:58 INFO - 2016-01-12 19:10:58,598 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;110;106;110;110;110;100;109;111;110 19:10:58 INFO - 2016-01-12 19:10:58,598 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;143;125;148;162;123;150;149;131;167;135 19:10:58 INFO - 2016-01-12 19:10:58,598 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;163;161;160;158;160;158;160;161;160 19:10:58 INFO - 2016-01-12 19:10:58,598 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;72;152;70;72;71;73;151;71 19:10:58 INFO - 2016-01-12 19:10:58,598 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;90;133;89;89;90;130;88;90;130 19:10:58 INFO - 2016-01-12 19:10:58,598 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;109;109;109;109;110;110;109 19:10:58 INFO - 2016-01-12 19:10:58,598 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;145;145;145;145;145;145;144;146 19:10:58 INFO - 2016-01-12 19:10:58,599 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;96;95;96;96;95;95;95;95 19:10:58 INFO - 2016-01-12 19:10:58,599 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;63;55;56;54;55;55;54;55;51;54 19:10:58 INFO - 2016-01-12 19:10:58,599 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;62;64;64;65;64;64;64;67;64 19:10:58 INFO - 2016-01-12 19:10:58,599 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;75;77;77;78;76;84;78;78;78;77 19:10:58 INFO - 2016-01-12 19:10:58,599 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;126;110;109;108;114;109;117;124;112;115 19:10:58 INFO - 2016-01-12 19:10:58,599 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;134;152;137;136;136;132;126;143;133;139 19:10:58 INFO - 2016-01-12 19:10:58,599 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;62;61;63;63;62;62;61;63;63;61 19:10:58 INFO - 2016-01-12 19:10:58,599 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:10:58 INFO - 2016-01-12 19:10:58,600 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:10:58 INFO - 2016-01-12 19:10:58,600 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,861 19:10:58 INFO - 2016-01-12 19:10:58,600 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:10:58 INFO - 2016-01-12 19:10:58,600 DEBUG : BROWSER_OUTPUT: __startTimestamp1452654658595__endTimestamp 19:10:58 INFO - 2016-01-12 19:10:58,600 DEBUG : BROWSER_OUTPUT: 19:10:58 INFO - 2016-01-12 19:10:58,604 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:10:58 INFO - 2016-01-12 19:10:58,604 DEBUG : BROWSER_OUTPUT: Number of tests: 14 19:10:58 INFO - 2016-01-12 19:10:58,604 DEBUG : BROWSER_OUTPUT: 19:10:58 INFO - 2016-01-12 19:10:58,604 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.60 Median:110.00 stddev:3.31 (3.0%) stddev-sans-first:3.47 19:10:58 INFO - 2016-01-12 19:10:58,604 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 106.0 110.0 110.0 110.0 100.0 109.0 111.0 110.0 19:10:58 INFO - 2016-01-12 19:10:58,604 DEBUG : BROWSER_OUTPUT: 19:10:58 INFO - 2016-01-12 19:10:58,605 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.30 Median:148.50 stddev:14.83 (10.0%) stddev-sans-first:15.72 19:10:58 INFO - 2016-01-12 19:10:58,605 DEBUG : BROWSER_OUTPUT: Values: 143.0 125.0 148.0 162.0 123.0 150.0 149.0 131.0 167.0 135.0 19:10:58 INFO - 2016-01-12 19:10:58,605 DEBUG : BROWSER_OUTPUT: 19:10:58 INFO - 2016-01-12 19:10:58,605 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:1.71 (1.1%) stddev-sans-first:1.54 19:10:58 INFO - 2016-01-12 19:10:58,605 DEBUG : BROWSER_OUTPUT: Values: 163.0 163.0 161.0 160.0 158.0 160.0 158.0 160.0 161.0 160.0 19:10:58 INFO - 2016-01-12 19:10:58,605 DEBUG : BROWSER_OUTPUT: 19:10:58 INFO - 2016-01-12 19:10:58,606 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.40 Median:71.00 stddev:33.79 (47.6%) stddev-sans-first:35.32 19:10:58 INFO - 2016-01-12 19:10:58,606 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 72.0 152.0 70.0 72.0 71.0 73.0 151.0 71.0 19:10:58 INFO - 2016-01-12 19:10:58,606 DEBUG : BROWSER_OUTPUT: 19:10:58 INFO - 2016-01-12 19:10:58,606 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:101.80 Median:89.00 stddev:20.18 (22.7%) stddev-sans-first:20.86 19:10:58 INFO - 2016-01-12 19:10:58,606 DEBUG : BROWSER_OUTPUT: Values: 89.0 90.0 133.0 89.0 89.0 90.0 130.0 88.0 90.0 130.0 19:10:58 INFO - 2016-01-12 19:10:58,606 DEBUG : BROWSER_OUTPUT: 19:10:58 INFO - 2016-01-12 19:10:58,607 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 19:10:58 INFO - 2016-01-12 19:10:58,607 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 109.0 109.0 109.0 109.0 110.0 110.0 109.0 19:10:58 INFO - 2016-01-12 19:10:58,607 DEBUG : BROWSER_OUTPUT: 19:10:58 INFO - 2016-01-12 19:10:58,607 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.00 Median:145.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 19:10:58 INFO - 2016-01-12 19:10:58,607 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 144.0 146.0 19:10:58 INFO - 2016-01-12 19:10:58,608 DEBUG : BROWSER_OUTPUT: 19:10:58 INFO - 2016-01-12 19:10:58,608 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 19:10:58 INFO - 2016-01-12 19:10:58,608 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 96.0 95.0 96.0 96.0 95.0 95.0 95.0 95.0 19:10:58 INFO - 2016-01-12 19:10:58,608 DEBUG : BROWSER_OUTPUT: 19:10:58 INFO - 2016-01-12 19:10:58,608 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.00 stddev:3.05 (5.5%) stddev-sans-first:1.41 19:10:58 INFO - 2016-01-12 19:10:58,608 DEBUG : BROWSER_OUTPUT: Values: 63.0 55.0 56.0 54.0 55.0 55.0 54.0 55.0 51.0 54.0 19:10:58 INFO - 2016-01-12 19:10:58,609 DEBUG : BROWSER_OUTPUT: 19:10:58 INFO - 2016-01-12 19:10:58,609 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:64.00 stddev:1.29 (2.0%) stddev-sans-first:1.30 19:10:58 INFO - 2016-01-12 19:10:58,609 DEBUG : BROWSER_OUTPUT: Values: 63.0 62.0 64.0 64.0 65.0 64.0 64.0 64.0 67.0 64.0 19:10:58 INFO - 2016-01-12 19:10:58,609 DEBUG : BROWSER_OUTPUT: 19:10:58 INFO - 2016-01-12 19:10:58,609 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.80 Median:78.00 stddev:2.39 (3.1%) stddev-sans-first:2.32 19:10:58 INFO - 2016-01-12 19:10:58,609 DEBUG : BROWSER_OUTPUT: Values: 75.0 77.0 77.0 78.0 76.0 84.0 78.0 78.0 78.0 77.0 19:10:58 INFO - 2016-01-12 19:10:58,609 DEBUG : BROWSER_OUTPUT: 19:10:58 INFO - 2016-01-12 19:10:58,609 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:114.40 Median:114.50 stddev:6.31 (5.5%) stddev-sans-first:5.11 19:10:58 INFO - 2016-01-12 19:10:58,609 DEBUG : BROWSER_OUTPUT: Values: 126.0 110.0 109.0 108.0 114.0 109.0 117.0 124.0 112.0 115.0 19:10:58 INFO - 2016-01-12 19:10:58,609 DEBUG : BROWSER_OUTPUT: 19:10:58 INFO - 2016-01-12 19:10:58,609 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:136.80 Median:136.50 stddev:6.97 (5.1%) stddev-sans-first:7.32 19:10:58 INFO - 2016-01-12 19:10:58,609 DEBUG : BROWSER_OUTPUT: Values: 134.0 152.0 137.0 136.0 136.0 132.0 126.0 143.0 133.0 139.0 19:10:58 INFO - 2016-01-12 19:10:58,610 DEBUG : BROWSER_OUTPUT: 19:10:58 INFO - 2016-01-12 19:10:58,610 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.50 stddev:0.88 (1.4%) stddev-sans-first:0.93 19:10:58 INFO - 2016-01-12 19:10:58,610 DEBUG : BROWSER_OUTPUT: Values: 62.0 61.0 63.0 63.0 62.0 62.0 61.0 63.0 63.0 61.0 19:10:58 INFO - 2016-01-12 19:10:58,610 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:10:58 INFO - 2016-01-12 19:10:58,610 DEBUG : BROWSER_OUTPUT: 19:10:59 INFO - 2016-01-12 19:10:59,289 INFO : Browser exited with error code: 0 19:10:59 INFO - 2016-01-12 19:10:59,342 INFO : Completed test kraken (00:00:56) 19:10:59 INFO - 2016-01-12 19:10:59,343 INFO : Starting test v8_7 19:10:59 INFO - 2016-01-12 19:10:59,343 DEBUG : operating with platform_type : linux_ 19:10:59 INFO - 2016-01-12 19:10:59,343 INFO : Initialising browser for v8_7 test... 19:10:59 INFO - 2016-01-12 19:10:59,349 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaV5GYA/profile http://localhost:50687/getInfo.html 19:11:02 INFO - 2016-01-12 19:11:02,825 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:11:02 INFO - 2016-01-12 19:11:02,825 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:11:02 INFO - 2016-01-12 19:11:02,825 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:11:02 INFO - 2016-01-12 19:11:02,825 DEBUG : BROWSER_OUTPUT: __metrics 19:11:02 INFO - 2016-01-12 19:11:02,901 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] 19:11:04 INFO - 2016-01-12 19:11:04,197 INFO : Browser initialized. 19:11:04 INFO - 2016-01-12 19:11:04,198 INFO : Running cycle 1/1 for v8_7 test... 19:11:04 INFO - 2016-01-12 19:11:04,198 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaV5GYA/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:11:15 INFO - 2016-01-12 19:11:15,723 DEBUG : BROWSER_OUTPUT: RSS: Main: 128188416 19:11:15 INFO - 2016-01-12 19:11:15,723 DEBUG : BROWSER_OUTPUT: 19:11:37 INFO - 2016-01-12 19:11:37,067 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50687/tests/v8_7/run.html (next: http://localhost:50687/tests/v8_7/run.html) 19:11:37 INFO - 2016-01-12 19:11:37,316 DEBUG : BROWSER_OUTPUT: RSS: Main: 416333824 19:11:37 INFO - 2016-01-12 19:11:37,316 DEBUG : BROWSER_OUTPUT: 19:11:37 INFO - 2016-01-12 19:11:37,316 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:11:37 INFO - 2016-01-12 19:11:37,316 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:11:37 INFO - 2016-01-12 19:11:37,317 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:11:37 INFO - 2016-01-12 19:11:37,317 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:11:37 INFO - 2016-01-12 19:11:37,317 DEBUG : BROWSER_OUTPUT: |0;Richards;163.21201240411295 19:11:37 INFO - 2016-01-12 19:11:37,317 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.67759003242875 19:11:37 INFO - 2016-01-12 19:11:37,317 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.9400278940028 19:11:37 INFO - 2016-01-12 19:11:37,317 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5000 19:11:37 INFO - 2016-01-12 19:11:37,317 DEBUG : BROWSER_OUTPUT: |4;RayTrace;939.8496240601504 19:11:37 INFO - 2016-01-12 19:11:37,318 DEBUG : BROWSER_OUTPUT: |5;Earley;556.1735261401557 19:11:37 INFO - 2016-01-12 19:11:37,318 DEBUG : BROWSER_OUTPUT: |6;Boyer;10458.333333333334 19:11:37 INFO - 2016-01-12 19:11:37,318 DEBUG : BROWSER_OUTPUT: |7;RegExp;25846.153846153848 19:11:37 INFO - 2016-01-12 19:11:37,318 DEBUG : BROWSER_OUTPUT: |8;Splay;531.9148936170212 19:11:37 INFO - 2016-01-12 19:11:37,318 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4817.307692307692 19:11:37 INFO - 2016-01-12 19:11:37,318 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:11:37 INFO - 2016-01-12 19:11:37,319 DEBUG : BROWSER_OUTPUT: __startTimestamp1452654697313__endTimestamp 19:11:37 INFO - 2016-01-12 19:11:37,319 DEBUG : BROWSER_OUTPUT: 19:11:37 INFO - 2016-01-12 19:11:37,319 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:11:37 INFO - 2016-01-12 19:11:37,319 DEBUG : BROWSER_OUTPUT: Number of tests: 10 19:11:37 INFO - 2016-01-12 19:11:37,319 DEBUG : BROWSER_OUTPUT: 19:11:37 INFO - 2016-01-12 19:11:37,319 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.21 Median:163.21 stddev:0.00 (0.0%) 19:11:37 INFO - 2016-01-12 19:11:37,319 DEBUG : BROWSER_OUTPUT: Values: 163.2 19:11:37 INFO - 2016-01-12 19:11:37,319 DEBUG : BROWSER_OUTPUT: 19:11:37 INFO - 2016-01-12 19:11:37,320 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.68 Median:170.68 stddev:0.00 (0.0%) 19:11:37 INFO - 2016-01-12 19:11:37,320 DEBUG : BROWSER_OUTPUT: Values: 170.7 19:11:37 INFO - 2016-01-12 19:11:37,320 DEBUG : BROWSER_OUTPUT: 19:11:37 INFO - 2016-01-12 19:11:37,320 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.94 Median:278.94 stddev:0.00 (0.0%) 19:11:37 INFO - 2016-01-12 19:11:37,320 DEBUG : BROWSER_OUTPUT: Values: 278.9 19:11:37 INFO - 2016-01-12 19:11:37,320 DEBUG : BROWSER_OUTPUT: 19:11:37 INFO - 2016-01-12 19:11:37,320 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5000.00 Median:5000.00 stddev:0.00 (0.0%) 19:11:37 INFO - 2016-01-12 19:11:37,321 DEBUG : BROWSER_OUTPUT: Values: 5000.0 19:11:37 INFO - 2016-01-12 19:11:37,321 DEBUG : BROWSER_OUTPUT: 19:11:37 INFO - 2016-01-12 19:11:37,321 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:939.85 Median:939.85 stddev:0.00 (0.0%) 19:11:37 INFO - 2016-01-12 19:11:37,321 DEBUG : BROWSER_OUTPUT: Values: 939.8 19:11:37 INFO - 2016-01-12 19:11:37,321 DEBUG : BROWSER_OUTPUT: 19:11:37 INFO - 2016-01-12 19:11:37,321 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:556.17 Median:556.17 stddev:0.00 (0.0%) 19:11:37 INFO - 2016-01-12 19:11:37,321 DEBUG : BROWSER_OUTPUT: Values: 556.2 19:11:37 INFO - 2016-01-12 19:11:37,321 DEBUG : BROWSER_OUTPUT: 19:11:37 INFO - 2016-01-12 19:11:37,321 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10458.33 Median:10458.33 stddev:0.00 (0.0%) 19:11:37 INFO - 2016-01-12 19:11:37,321 DEBUG : BROWSER_OUTPUT: Values: 10458.3 19:11:37 INFO - 2016-01-12 19:11:37,321 DEBUG : BROWSER_OUTPUT: 19:11:37 INFO - 2016-01-12 19:11:37,321 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25846.15 Median:25846.15 stddev:0.00 (0.0%) 19:11:37 INFO - 2016-01-12 19:11:37,321 DEBUG : BROWSER_OUTPUT: Values: 25846.2 19:11:37 INFO - 2016-01-12 19:11:37,322 DEBUG : BROWSER_OUTPUT: 19:11:37 INFO - 2016-01-12 19:11:37,322 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:531.91 Median:531.91 stddev:0.00 (0.0%) 19:11:37 INFO - 2016-01-12 19:11:37,322 DEBUG : BROWSER_OUTPUT: Values: 531.9 19:11:37 INFO - 2016-01-12 19:11:37,322 DEBUG : BROWSER_OUTPUT: 19:11:37 INFO - 2016-01-12 19:11:37,322 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4817.31 Median:4817.31 stddev:0.00 (0.0%) 19:11:37 INFO - 2016-01-12 19:11:37,322 DEBUG : BROWSER_OUTPUT: Values: 4817.3 19:11:37 INFO - 2016-01-12 19:11:37,322 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:11:37 INFO - 2016-01-12 19:11:37,322 DEBUG : BROWSER_OUTPUT: 19:11:37 INFO - 2016-01-12 19:11:37,953 INFO : Browser exited with error code: 0 19:11:37 INFO - 2016-01-12 19:11:37,972 INFO : Completed test v8_7 (00:00:38) 19:11:38 INFO - 2016-01-12 19:11:38,397 INFO : Completed test suite (00:20:05) 19:11:38 INFO - 2016-01-12 19:11:38,397 DEBUG : Working with test: dromaeo_css 19:11:38 INFO - 2016-01-12 19:11:38,397 DEBUG : Generating results file: dromaeo_css 19:11:38 INFO - 2016-01-12 19:11:38,399 DEBUG : Working with test: kraken 19:11:38 INFO - 2016-01-12 19:11:38,399 DEBUG : Generating results file: kraken 19:11:38 INFO - 2016-01-12 19:11:38,399 INFO : javascript benchmark 19:11:38 INFO - 2016-01-12 19:11:38,399 DEBUG : Working with test: v8_7 19:11:38 INFO - 2016-01-12 19:11:38,400 DEBUG : Generating results file: v8_7 19:11:38 INFO - 2016-01-12 19:11:38,400 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:11:38 INFO - 2016-01-12 19:11:38,454 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 19:11:38 INFO - 2016-01-12 19:11:38,454 DEBUG : process_Request line: dromaeo_css 7286.18 graph.html#tests=[[72,53,35]] 19:11:38 INFO - 2016-01-12 19:11:38,454 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:11:38 INFO - 2016-01-12 19:11:38,495 DEBUG : process_Request line: kraken 1461.50 graph.html#tests=[[232,53,35]] 19:11:38 INFO - 2016-01-12 19:11:38,495 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:11:38 INFO - 2016-01-12 19:11:38,602 DEBUG : process_Request line: v8_7 23153.27 graph.html#tests=[[230,53,35]] 19:11:38 INFO - 2016-01-12 19:11:38,603 INFO : javascript benchmark 19:11:38 INFO - 2016-01-12 19:11:38,606 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3216.0994921422002, 3276.197331653745, 3307.338532293542, 3312.039508542523, 3334.0664613908107, 5528.917066244009, 5578.470045345685, 5580.414056524068, 5599.1041433370665, 5606.495415412612, 5558.055130627793, 5599.888002239963, 5610.663360198391, 5612.915806262909, 5622.803201887935, 4981.178105612569, 5070.569001634856, 5104.285400043978, 5188.584913206953, 5195.0, 4937.827176048839, 5020.272060551218, 5047.722375269359, 5124.205748109049, 5125.282460455521, 5118.564921981636, 5137.3064636274, 5141.688869338313, 5146.536811686948, 5161.896762064756, 4900.754962251909, 5062.443131255559, 5085.872853178663, 5172.353455818023, 5194.272801807731, 4874.341963834872, 4961.305417241589, 4983.90032199354, 4993.875153121165, 5012.423571289312, 5297.894042119136, 5393.649412788156, 5408.486193811621, 5410.864728381783, 5469.808556700496, 5830.3586605473365, 5935.465808077293, 6052.031674932034, 6069.423404776539, 6072.45347918685, 5971.611845230046, 6059.36376680451, 6066.0, 6067.514598832083, 6099.878002439971, 5686.43135686428, 5702.372738998707, 5719.370869204384, 5726.141078838166, 5728.169415434739, 5977.850553736192, 5989.460948514655, 6100.72546735398, 6180.598261113013, 6195.19462469885, 5957.0, 5998.430149135826, 6009.549283803644, 6011.338752737195, 6012.038073908241, 6068.605540639845, 6079.0273556230795, 6082.087686847009, 6087.908681369783, 6091.969540152271, 5813.82558523245, 5856.853578660568, 5874.412558744091, 5982.312034116046, 6048.425399587032, 6008.0, 6017.909731354033, 6024.819255422344, 6025.8192254232445, 6054.273487181566, 4634.397528321297, 4637.04940487206, 4637.605803506673, 4640.32715256286, 4666.486686464486, 4038.2327357801923, 4052.250333688277, 4125.752454852718, 4158.875233742992, 4170.791460426966, 5723.141528770634, 5727.885442291131, 5742.827715168552, 5746.454086861741, 5746.741396637161, 5913.113033044991, 6071.544634152457, 6086.239220097488, 6089.725962331706, 6091.451769340781, 5579.46995035471, 5638.830835074954, 5651.2935883014625, 5667.773289068391, 5682.687452190086, 5189.273501709764, 5218.869528261824, 5220.3735551734035, 5220.556252718561, 5237.004969055867, 6018.518518518508, 6120.326764055951, 6203.720832562545, 6235.033569796688, 6239.844003899939, 5961.374055724086, 6047.758089676364, 6056.727447264796, 6065.211522502046, 6081.422264884829, 6148.077788331786, 6165.537584681257, 6221.844453888689, 6229.0967809668255, 6229.5327850410495, 5827.970860145672, 5853.94146058534, 5916.26046744157, 6002.219711437485, 6039.275286965417, 5985.850353741017, 6068.6965651716355, 6074.757009719562, 6083.7870674526175, 6085.878282434238, 5897.970510147594, 5914.378990206139, 5917.526597872074, 5919.585629005928, 5919.733611987384, 5689.459501347365, 5745.8563535911935, 5803.216565763568, 5865.560082993878, 5892.823215303548, 5783.797567085132, 5841.269841269841, 5842.123681447817, 5848.093545500455, 5848.561357898089, 5908.881822363614, 5940.97029514867, 5943.702814859326, 5949.851253718692, 5965.224520812267, 5622.32532096151, 5725.370209277059, 5773.538116950718, 5868.970655146697, 5892.94107058924], "unit": "ms", "name": "dojo.html", "value": 5537.431229525349}, {"lowerIsBetter": false, "replicates": [576.8759716660917, 608.3764141754701, 614.2138063279003, 625.9436650701439, 627.7457629659983, 42251.36622950658, 42717.7864110679, 42736.572634273645, 42765.0, 42820.785896070476, 48369.0, 48997.51002489974, 49076.26385604219, 49122.75438622802, 49189.754051229786, 8180.836383272331, 8298.585070746438, 9093.681721139761, 9266.721998340061, 10457.21570882183, 10575.153987681006, 10603.310784798963, 10661.520231589559, 10678.946605266963, 10687.412192329419, 10647.467626618676, 10649.84025239622, 10749.62376316829, 10750.569977200941, 10750.78498430031, 10434.113100386476, 10493.632722854663, 10518.105960993325, 10527.052565269125, 10531.104856087204, 10532.894671053267, 10997.780044399067, 11004.559817607285, 11022.834657480144, 11032.44837758109, 46122.53877461216, 46236.5376346239, 46316.76841615804, 46415.535844641796, 46544.767276163744, 3707.6663100321102, 3826.827792749333, 4157.771322577257, 4187.43469631602, 4535.546445355471, 33744.49383259253, 34702.13244668878, 34865.82567087174, 34920.0, 34931.30137397263, 3421.264428147935, 3442.0, 3455.2916652086515, 3523.735719820998, 3524.7004004659634, 329187.0, 329275.0, 329363.0, 330312.0, 331070.0, 61210.69394652998, 62200.37799621946, 78501.0, 79315.0, 79420.0, 73315.0, 73428.63285683538, 73616.26383736094, 73801.6309918447, 73915.26084739083, 18884.811151888305, 18994.0, 19021.714674279898, 19141.712874306897, 19196.0, 14439.0, 14551.0, 14556.781648275284, 14758.55724328272, 14777.0, 22661.660075098887, 22721.886390567943, 22722.091116355492, 22746.545069098527, 22835.31494055181, 7912.28789408956, 8065.193480652005, 8186.713465028695, 8239.835203296021, 8255.917440825635, 1050.726811029138, 1125.8700811743367, 1129.615930583589, 1134.319408354997, 1156.5258244120039, 31882.20294492656, 32079.839600801846, 32483.18792030218, 32768.836155819066, 32917.01248962535, 1160.5937921727498, 1196.5632544121333, 1205.7287110400266, 1231.1382032577233, 1272.1349482352089, 14622.707545849024, 16442.671146577002, 16635.584110397336, 16700.49898503047, 16921.492355229362, 406470.0, 410625.0, 412196.0, 412657.0, 414087.0, 62078.379216208166, 62079.379206208265, 62296.0, 62348.0, 62527.0, 1295.7602843473987, 1364.6793003644136, 1432.7349440353569, 1477.8078849749463, 1480.104536755269, 29717.702822971492, 29935.850320749127, 30992.380152396374, 31467.84266078655, 32297.51553726743, 51926.221106684185, 52113.218301724744, 52142.47857521376, 52282.0, 52332.0, 310752.0, 311573.0, 312275.0, 312930.0, 313817.0, 35462.46806297856, 36726.816365918, 36905.44641830429, 36907.0, 36978.0, 43182.0, 43436.0, 44121.11757764891, 44308.77845610751, 44366.11267774691, 41353.586464134976, 41884.58115418807, 42644.57355426406, 43357.78321108374, 43458.56541434545, 42127.57872421236, 42310.0, 42664.36003459888, 43216.35175472307, 43284.13431731285, 41586.0, 41697.1660566793, 42164.78917605392, 42224.0, 42234.36648450214, 32538.186545336557, 33077.834610826794, 33198.0, 33770.8311458451, 35577.64422355847, 26132.60801087947, 27916.720832791412, 28055.117679527368, 28092.0, 28092.71907280901], "unit": "ms", "name": "ext.html", "value": 21085.852031641574}, {"lowerIsBetter": false, "replicates": [389.4178203585639, 390.63280516314643, 391.5555844116926, 392.17055926715005, 393.38828122269865, 14372.63459971301, 18057.277708891645, 19936.0, 19940.401787946368, 20834.583308333826, 19149.42551723447, 19185.52036199096, 19341.323053693122, 19459.124339404727, 19462.1242044108, 5701.657900525971, 5820.650760954346, 5825.912611310834, 5828.562857785661, 5830.708464576775, 5717.942820571782, 5797.971010144965, 5845.210896528956, 5852.707364631773, 5880.235569375996, 4373.956260437387, 4490.708103973231, 5914.9408505915035, 5924.526037916957, 5926.436988486087, 6059.515238780909, 6280.497560195174, 6334.556581039329, 6334.778282760081, 6335.968320158393, 5591.189277554772, 5680.460356266148, 5810.3899090595305, 5813.883722325572, 5832.708364581754, 17773.289068437247, 17786.110694465355, 20988.475288117766, 21532.354029379145, 23431.765682343128, 2340.1107579119994, 2361.6929799126005, 2373.2287006722886, 2430.562498750242, 2529.190658989124, 15077.849221507864, 15215.08709477435, 15386.384544618202, 15440.922795385952, 15666.295016724274, 1815.682255605277, 1820.5266630675987, 1840.411068458094, 1850.2783914273443, 1852.7220916862443, 72625.0, 73673.6316318415, 74996.25003750002, 75009.62495187543, 75165.62417187933, 11708.590199343153, 12039.63881083569, 12177.878221217852, 12181.634550963485, 12198.512059517698, 16205.675886482204, 16299.837001630069, 16322.591935201715, 16360.67278654444, 16365.181740913145, 6183.319834818257, 6379.904301435482, 6461.8061458156335, 6555.016747487915, 6602.537822352485, 7047.43620510358, 7050.189228238818, 7053.612051337226, 7104.92895071053, 7107.218205997337, 3684.981575092161, 3734.57052438973, 3743.6256374362347, 3746.662800347982, 3759.9624003759614, 1272.8790764877322, 1286.7619490394306, 1293.0948336164533, 1297.5264028629483, 1344.5562964221979, 474.59896387552465, 506.12693104394623, 511.60095125801934, 525.3931708876254, 539.7625044980197, 12284.324362159989, 12541.749165016648, 13243.337833108306, 13359.998000150028, 13621.0, 536.9301990741257, 540.5891522442893, 541.8780774325824, 544.8256557901512, 557.1698169727077, 5917.0, 7077.752278670222, 7085.716571337191, 7277.708891644381, 7503.699852005859, 623183.0, 627000.0, 632520.0, 647706.0, 648544.0, 81954.5914073281, 82921.0, 84132.0, 84170.15829841624, 87060.1293987052, 624.09506215987, 625.1185827982523, 625.9749610015548, 627.5732501898661, 628.4940622798599, 12531.87468125307, 12788.424520896768, 12833.0, 12944.41750121228, 13026.218426894417, 37853.0, 38227.80886095552, 38577.228455431294, 38957.415638764876, 40161.1967760649, 48745.756271218415, 49342.0, 50597.24104138515, 50634.24048639347, 50664.240036400224, 7647.579383133981, 7711.961440192763, 7730.729424470116, 7762.146163921851, 7798.181190975093, 13136.343182840628, 13309.201447913156, 13649.0, 13730.244836533688, 14020.0, 11810.350430726392, 11868.643940681794, 11973.880261197277, 12079.0, 12101.394930253276, 11535.596254131066, 11621.535138594361, 11819.82270265964, 11933.4033298333, 12066.577669781516, 12048.879511205127, 12122.27266364021, 12151.453184606891, 12244.938775306422, 12439.440225190068, 6847.07564478789, 7555.886661700189, 7616.20029896875, 7729.961350193213, 7733.729319473791, 9709.465979371202, 11765.882341176479, 11926.403679816149, 12033.75932481328, 12147.149699520949], "unit": "ms", "name": "jquery.html", "value": 8321.34303870207}, {"lowerIsBetter": false, "replicates": [1634.419780977753, 1643.3919449803568, 1908.2939312454384, 1911.636789010088, 1933.8839669619815, 8321.542315172663, 8665.436746611465, 8683.088275731052, 8690.695825646104, 8692.478451292927, 7508.66211020504, 7668.693252269916, 8246.299064579518, 8400.285975692073, 8517.829643407144, 2347.0963678983544, 2358.8584684918874, 2383.9642405363934, 2387.200287903546, 2388.10446082719, 2384.2608791274674, 2423.7091549014053, 2428.0530593068715, 2442.535918175541, 2443.4868677577642, 2402.1352313167245, 2414.7705967933107, 2434.464417828075, 2438.9512209755794, 2464.2114523352534, 2307.3654744945106, 2356.4815740537056, 2358.5282943411303, 2377.6076947303673, 2384.5350156719446, 2365.27859003004, 2376.4771750214927, 2396.5446565152565, 2400.135951057618, 2400.1599440195964, 8335.416520843544, 8395.328373730086, 8403.201695838885, 8406.705765298186, 8422.663093476254, 5082.0, 5114.488551144893, 5141.280220769096, 5142.357205349332, 5149.6910185389, 3280.7867488613165, 3281.278118813857, 3314.0, 3319.9834000830083, 3340.0981734931433, 1114.3369695031524, 1152.8328392383141, 1161.372858656333, 1164.604034628222, 1174.3364998775664, 11586.768264634744, 11671.649850504498, 11698.649040528799, 11717.355545444998, 11721.706957326134, 4464.598186163196, 4507.2788353863225, 4517.2094883395275, 4552.9772351138035, 4553.886152846206, 10091.848622270672, 10107.241956853266, 10110.039546243095, 10115.747106322253, 10121.24090693201, 5100.69395836251, 5101.0, 5104.03023425548, 5108.2848401223855, 5109.20807274873, 4232.619064284229, 4315.28797748368, 4617.676762626651, 4628.652851036186, 4685.906281874343, 3991.26161660088, 4037.0916543778003, 4076.673866090765, 4079.204555111784, 4133.111381053088, 1309.319154039913, 1322.7023919618202, 1327.5287273017939, 1335.5058628307588, 1338.9263590502421, 1124.6569796212234, 1178.4166837415362, 1276.8850803427736, 1496.7081419123167, 1510.5317351621034, 8142.837143257102, 8181.222783835526, 8199.877001844978, 8200.220979006985, 8224.38317126218, 1130.9547618095348, 1138.299945533491, 1191.8569771627288, 1243.0911666699965, 1251.443107817031, 6508.349165083454, 6528.640924749185, 6544.509161812883, 6584.440322572539, 6586.209654841449, 10290.485475726184, 10446.112080473245, 10447.425391603534, 10449.68650940473, 10459.84310235347, 9623.951880240695, 9811.0, 10311.484425778832, 10344.32761870476, 10354.378737275787, 7908.367330613537, 7959.482633628795, 7969.481983671043, 7971.800704982538, 7987.520748755093, 7905.4070944680525, 7997.560134192442, 8007.599620018859, 8027.759167224993, 8047.83904321922, 8968.10318968098, 9092.0, 9120.452772833385, 9126.954365228132, 9141.085891410805, 9121.179093881583, 9179.082091791035, 9219.585118669542, 9392.95303523478, 9418.576164072496, 6579.0, 6677.09859169006, 7332.670029848777, 7606.353459955966, 7665.846683066419, 8019.478733882279, 8037.276645101969, 8049.39629527776, 8057.476264042818, 8066.959665201871, 7228.31331023552, 7535.660895259617, 7555.773326800205, 7604.733834315773, 7609.39124869998, 7412.332890039922, 7425.071866016748, 7513.4740568159705, 7569.0, 7622.123455802654, 7618.238176182337, 7643.847123057397, 7655.349295309738, 7657.004589403342, 7658.425618078778, 6980.685869136003, 7071.964640176766, 7101.360877521048, 7126.714931402686, 7227.205007449282, 7172.318629730167, 7179.174394944648, 7219.350258476762, 7225.891611625935, 7232.0959880015], "unit": "ms", "name": "mootools.html", "value": 4888.827727641042}, {"lowerIsBetter": false, "replicates": [362.5214716574122, 363.2372018760602, 366.6058986589417, 367.7793324005597, 375.530586766542, 5796.275465566804, 5881.823545293648, 5887.470127688507, 5935.169076329312, 5946.702664866755, 5701.372848986619, 5799.0, 5819.563532735041, 5820.097884827858, 5823.825285241439, 4586.151561961031, 4588.357629931812, 4591.494935557086, 4591.540845915415, 4592.72443653381, 4443.800028998703, 4498.167838949788, 4519.5932366087045, 4588.564086411802, 4595.678302518825, 4506.932396014063, 4513.029698614797, 4520.796564154604, 4523.841665541707, 4528.20756367636, 4531.705439146462, 4531.796069176862, 4538.069695712371, 4538.909221815562, 4546.931796023029, 4023.8591649292134, 4025.8993525161814, 4030.375291829771, 4031.4355990161403, 4037.192561487685, 5528.75120619573, 5576.0, 5582.246396736429, 5617.297837770278, 5645.463680950304, 5256.553192978601, 5259.316288882459, 5259.815906443295, 5260.316158899357, 5262.500062494057, 5963.970180149072, 6204.100405441231, 6211.0372892201785, 6212.844678883064, 6219.129321894939, 4317.266064768997, 4365.083332500179, 4414.911701765946, 4416.183006143874, 4418.050119224382, 6305.68471576424, 6385.297617262097, 6395.616263024233, 6397.008463688136, 6397.584157029778, 4510.887227819331, 4515.254982927846, 4518.661100417482, 4520.0507893342765, 4524.909501809945, 4496.9325460118125, 4559.931601025987, 4564.612007979359, 4565.315202719552, 4652.558006989399, 5308.973455132699, 5312.096943519611, 5313.760880760375, 5316.65441746293, 5317.441668624816, 6318.241810982711, 6325.9051114233325, 6326.114343991845, 6328.968355158195, 6329.715162817684, 1640.409452597064, 1662.4347721774413, 1665.5253252822888, 1665.608581983233, 1665.8500734933914, 1114.0419239454063, 1116.1126904111252, 1116.8827273136367, 1119.2165484160957, 1146.3179408252076, 6025.367336429699, 6026.156338112668, 6029.60807547508, 6032.969835150884, 6034.577579569388, 6334.334894835976, 6347.587406818542, 6348.714307856158, 6349.333320001426, 6377.23473183221, 6028.246469191351, 6031.909521357183, 6037.577369584087, 6038.215032045806, 6040.033594624839, 3493.9301213975577, 3547.6629720176543, 3550.041488798062, 3550.8757193498104, 3552.5914519830035, 6598.1422415085735, 6610.471162307004, 6612.107365505595, 6618.768343107968, 6623.437007854386, 6529.183852018497, 6558.573692709959, 6559.344065593403, 6565.80302590923, 6572.309907459744, 6470.320616335312, 6596.80209593713, 6607.735690572324, 6615.040819081206, 6726.730930762715, 6263.0, 6335.2714437838395, 6528.37980391862, 6532.738690452328, 6546.80359589194, 6256.468200203034, 6287.497000239879, 6307.274663413767, 6331.746730130743, 6354.3963323484195, 6560.9343906560325, 6584.144061272195, 6584.506162037962, 6614.338566143347, 6615.0, 4445.221736414741, 4482.260427029503, 4515.164694531522, 4519.61583265413, 4566.360709500739, 6537.084808126961, 6537.346265373425, 6543.869122617426, 6547.443467305332, 6552.410283074546, 6338.683065846782, 6347.270063942521, 6353.8729225416155, 6358.1734374531015, 6365.363463653597, 6242.375762423721, 6341.968290158519, 6343.9682801585695, 6350.1744773181035, 6364.236291645032, 6261.467775239155, 6264.812055638338, 6272.247330320391, 6275.905861411991, 6282.026285925689, 6331.9366806331345, 6387.105805187371, 6394.0, 6394.360563943568, 6396.584222025554, 6105.236845394325, 6242.875142497215, 6251.937480625135, 6257.937420625735, 6258.593191442542], "unit": "ms", "name": "prototype.html", "value": 4767.24695827783}, {"lowerIsBetter": false, "replicates": [796.9960150199248, 854.6495936665966, 909.5906841921137, 920.1725570902756, 928.4522131942152, 14313.499027534039, 14593.708125837476, 14603.050801697882, 14679.853201467982, 14751.77872331916, 14399.0, 14444.927775361108, 14451.566453006426, 14471.78292325616, 14477.5656730298, 776.4526009163526, 787.3937068457284, 925.3661242049184, 932.4125800745554, 947.3652652722658, 941.6468824190929, 943.9622415103422, 948.6442584030989, 950.719537736366, 951.362587066667, 821.7247222180577, 991.1773228220595, 999.535216124502, 1001.4141727089625, 1002.5588740953957, 828.6850996621275, 976.9462679552622, 995.975100622483, 998.1465846701111, 998.5207100591684, 735.0848193998453, 737.2480070328289, 737.2848337112991, 737.71229220604, 737.881938889775, 13828.585142445743, 13990.510332138327, 13990.790138147935, 14003.929980350033, 14051.718965620732, 13951.093178943438, 13999.930000350036, 14089.436422543085, 14129.646758831008, 14162.0, 11386.658400248005, 11584.652460426201, 11624.302541847432, 11662.650120496399, 11914.225575337576, 3506.491558723996, 3508.2808024354945, 3520.3839328117733, 3548.946765798515, 3645.5807582127863, 53041.0, 53535.46464535344, 53564.46435535634, 53935.730321348536, 54194.45805541934, 8119.431639785158, 8197.877031844528, 8217.383696222807, 8221.588920553948, 8261.38039647029, 14134.08128471646, 14136.575902722934, 14155.0, 14158.29208539569, 14159.716805664035, 7271.381932535688, 7380.77857664271, 7391.334779869838, 7434.925650743423, 7462.96268518654, 4582.839600614029, 4611.769411529477, 4683.906321873544, 4703.95296047042, 4729.5034021427255, 3055.9694403055687, 3085.9074227772753, 3086.90739277822, 3088.027271409499, 3089.274020605156, 459.2697610798846, 465.664721400591, 472.7777944366142, 482.5873877834471, 485.7886819233645, 621.1211136242167, 635.9936400636027, 653.5523166630778, 664.9168853893264, 673.4746897419923, 8512.0, 8901.376903616814, 8995.460272383678, 9073.682421115229, 9243.218882632355, 497.01094821305344, 614.4040280927493, 625.3433894410941, 637.0635166305524, 660.7852447954338, 4048.0891799344913, 4315.395844581761, 4396.450443694538, 4591.150637132141, 4701.741404222732, 69431.65284173648, 69933.0, 69981.0, 70021.29978700148, 70066.29933700597, 59668.70165649231, 60723.69638151781, 61637.69181154066, 61978.380216198166, 62002.689986549776, 54597.0, 55581.166282505794, 55749.442505575236, 55827.72086139624, 55947.16079258815, 8891.822163556822, 9243.722688319362, 9479.668211612836, 9489.098535639103, 9677.564509597225, 37346.81326593459, 37799.62200377961, 38221.235575287785, 39067.21865562616, 40915.7954210227, 52930.4706952941, 53172.73413633062, 55159.0, 55273.17090243569, 55847.16229256483, 8246.257836794717, 8571.95714021426, 8643.654253830027, 8987.101289870961, 9099.044600317136, 11388.0, 11644.76710465769, 11700.941495292469, 11800.645980620595, 11980.28118312904, 11456.083513319081, 11781.646550603497, 11939.641810745692, 12120.090993175723, 12581.433835477243, 10741.24811263204, 11164.665060047886, 11550.768984620092, 12155.513779448724, 12233.571824986082, 10377.221708372053, 11329.773404532029, 11443.427828608703, 11781.646550603497, 12065.517379304729, 9927.354721943051, 10147.137493313152, 10325.225608079574, 10375.84436233442, 10480.895191047992, 10148.898511014795, 10227.43749093807, 10253.538590763583, 10298.794024119625, 10343.120834728832], "unit": "ms", "name": "yui.html", "value": 6606.8285467823835}], "name": "dromaeo_css", "value": 7286.17500360179}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 110.0, 106.0, 110.0, 110.0, 110.0, 100.0, 109.0, 111.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 108.6}, {"lowerIsBetter": true, "replicates": [143.0, 125.0, 148.0, 162.0, 123.0, 150.0, 149.0, 131.0, 167.0, 135.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.3}, {"lowerIsBetter": true, "replicates": [163.0, 163.0, 161.0, 160.0, 158.0, 160.0, 158.0, 160.0, 161.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.4}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 72.0, 152.0, 70.0, 72.0, 71.0, 73.0, 151.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 87.4}, {"lowerIsBetter": true, "replicates": [89.0, 90.0, 133.0, 89.0, 89.0, 90.0, 130.0, 88.0, 90.0, 130.0], "unit": "ms", "name": "audio-oscillator", "value": 101.8}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 144.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.0}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [63.0, 55.0, 56.0, 54.0, 55.0, 55.0, 54.0, 55.0, 51.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 55.2}, {"lowerIsBetter": true, "replicates": [63.0, 62.0, 64.0, 64.0, 65.0, 64.0, 64.0, 64.0, 67.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.1}, {"lowerIsBetter": true, "replicates": [75.0, 77.0, 77.0, 78.0, 76.0, 84.0, 78.0, 78.0, 78.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.8}, {"lowerIsBetter": true, "replicates": [126.0, 110.0, 109.0, 108.0, 114.0, 109.0, 117.0, 124.0, 112.0, 115.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 114.4}, {"lowerIsBetter": true, "replicates": [134.0, 152.0, 137.0, 136.0, 136.0, 132.0, 126.0, 143.0, 133.0, 139.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 136.8}, {"lowerIsBetter": true, "replicates": [62.0, 61.0, 63.0, 63.0, 62.0, 62.0, 61.0, 63.0, 63.0, 61.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 62.1}], "name": "kraken", "value": 1461.4999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.21201240411295], "unit": "score", "name": "Richards", "value": 216.29535399999995}, {"lowerIsBetter": false, "replicates": [170.67759003242875], "unit": "score", "name": "DeltaBlue", "value": 387.3853620000001}, {"lowerIsBetter": false, "replicates": [278.9400278940028], "unit": "score", "name": "Encrypt", "value": 954.2588850000001}, {"lowerIsBetter": false, "replicates": [5000.0], "unit": "score", "name": "Decrypt", "value": 53.23619999999997}, {"lowerIsBetter": false, "replicates": [939.8496240601504], "unit": "score", "name": "RayTrace", "value": 787.3482960000001}, {"lowerIsBetter": false, "replicates": [556.1735261401557], "unit": "score", "name": "Earley", "value": 1198.3004740000003}, {"lowerIsBetter": false, "replicates": [10458.333333333334], "unit": "score", "name": "Boyer", "value": 63.725545816733074}, {"lowerIsBetter": false, "replicates": [25846.153846153848], "unit": "score", "name": "RegExp", "value": 35.24644345238097}, {"lowerIsBetter": false, "replicates": [531.9148936170212], "unit": "score", "name": "Splay", "value": 153.20307999999997}, {"lowerIsBetter": false, "replicates": [4817.307692307692], "unit": "score", "name": "NavierStokes", "value": 308.055888223553}], "name": "v8_7", "value": 23153.273319278313}]} 19:11:38 INFO - RETURN: dromaeo_css: 7286.18 19:11:38 INFO - RETURN: kraken: 1461.50 19:11:38 INFO - RETURN: v8_7: 23153.27 19:11:38 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23153.27"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1461.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7286.18"}}} 19:11:38 INFO - Return code: 0 19:11:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 19:11:38 INFO - # TBPL SUCCESS # 19:11:38 INFO - Running post-action listener: _resource_record_post_action 19:11:38 INFO - Running post-run listener: _resource_record_post_run 19:11:39 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 315392; Write bytes: 276328448; Read time: 76; Write time: 204320 19:11:39 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 14352384; Read time: 0; Write time: 1736 19:11:39 INFO - run-tests - Wall time: 1207s; CPU: 13.0%; Read bytes: 315392; Write bytes: 261976064; Read time: 76; Write time: 202584 19:11:39 INFO - Running post-run listener: _upload_blobber_files 19:11:39 INFO - Blob upload gear active. 19:11:39 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 19:11:39 INFO - Copying logs to upload dir... 19:11:39 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1273.877092 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-01-12 19:11:39.842808) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-12 19:11:39.846520) ========= 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=4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009814 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452644491/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-12 19:11:39.900109) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 19:11:39.900444) ========= 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=4ac8d263c7293b36a1be1aed000001be-1452653411.445550-810540052 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004533 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 19:11:39.949927) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-12 19:11:39.950239) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-12 19:11:39.950571) ========= ========= Total master_lag: 0.25 =========