builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-066 starttime: 1452594685.45 results: success (0) buildid: 20160112001452 builduid: 32749fe6ec6a47a98dd7668629456dc1 revision: 40e457de9ce8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:25.452360) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:25.453012) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:25.453540) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006339 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:25.481713) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:25.482043) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:25.498837) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:25.499429) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004642 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:25.558899) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:25.559217) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:25.559595) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:25.559941) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-12 02:31:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 64.1M=0s 2016-01-12 02:31:26 (64.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.153004 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:25.726232) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:25.726648) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019676 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:25.808822) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:25.809183) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 40e457de9ce8 --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 40e457de9ce8 --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=82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-12 02:31:26,909 Setting DEBUG logging. 2016-01-12 02:31:26,909 attempt 1/10 2016-01-12 02:31:26,909 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/40e457de9ce8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-12 02:31:27,198 unpacking tar archive at: mozilla-beta-40e457de9ce8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.664239 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:26.486608) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:26.486954) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:26.496510) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:26.496837) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-12 02:31:26.497286) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-01-12 02:31:26.497620) ========= /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=82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:31:27 INFO - MultiFileLogger online at 20160112 02:31:27 in /builds/slave/test-pgo 02:31:27 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 02:31:27 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 02:31:27 INFO - {'append_to_log': False, 02:31:27 INFO - 'base_work_dir': '/builds/slave/test-pgo', 02:31:27 INFO - 'blob_upload_branch': 'Mozilla-Beta', 02:31:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 02:31:27 INFO - 'branch': 'Mozilla-Beta', 02:31:27 INFO - 'buildbot_json_path': 'buildprops.json', 02:31:27 INFO - 'config_files': ('talos/linux_config.py',), 02:31:27 INFO - 'default_actions': ('clobber', 02:31:27 INFO - 'read-buildbot-config', 02:31:27 INFO - 'download-and-extract', 02:31:27 INFO - 'populate-webroot', 02:31:27 INFO - 'create-virtualenv', 02:31:27 INFO - 'install', 02:31:27 INFO - 'run-tests'), 02:31:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:31:27 INFO - 'download_minidump_stackwalk': True, 02:31:27 INFO - 'download_symbols': 'ondemand', 02:31:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:31:27 INFO - 'tooltool.py': '/tools/tooltool.py', 02:31:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:31:27 INFO - '/tools/misc-python/virtualenv.py')}, 02:31:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:31:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:31:27 INFO - 'installer_path': 'installer.exe', 02:31:27 INFO - 'log_level': 'info', 02:31:27 INFO - 'log_name': 'talos', 02:31:27 INFO - 'log_to_console': True, 02:31:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:31:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:31:27 INFO - 'opt_config_files': (), 02:31:27 INFO - 'pip_index': False, 02:31:27 INFO - 'sps_profile': False, 02:31:27 INFO - 'sps_profile_interval': 0, 02:31:27 INFO - 'suite': 'dromaeojs', 02:31:27 INFO - 'system_bits': '32', 02:31:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:31:27 INFO - 'title': 'talos-linux64-ix-066', 02:31:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:31:27 INFO - 'use_talos_json': True, 02:31:27 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 02:31:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:31:27 INFO - 'work_dir': 'build'} 02:31:27 INFO - ##### 02:31:27 INFO - ##### Running clobber step. 02:31:27 INFO - ##### 02:31:27 INFO - Running pre-action listener: _resource_record_pre_action 02:31:27 INFO - Running main action method: clobber 02:31:27 INFO - rmtree: /builds/slave/test-pgo/build 02:31:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 02:31:28 INFO - Running post-action listener: _resource_record_post_action 02:31:28 INFO - ##### 02:31:28 INFO - ##### Running read-buildbot-config step. 02:31:28 INFO - ##### 02:31:28 INFO - Running pre-action listener: _resource_record_pre_action 02:31:28 INFO - Running main action method: read_buildbot_config 02:31:28 INFO - Using buildbot properties: 02:31:28 INFO - { 02:31:28 INFO - "properties": { 02:31:28 INFO - "buildnumber": 84, 02:31:28 INFO - "product": "firefox", 02:31:28 INFO - "script_repo_revision": "production", 02:31:28 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 02:31:28 INFO - "repository": "", 02:31:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 02:31:28 INFO - "buildid": "20160112001452", 02:31:28 INFO - "slavename": "talos-linux64-ix-066", 02:31:28 INFO - "pgo_build": "True", 02:31:28 INFO - "basedir": "/builds/slave/test-pgo", 02:31:28 INFO - "project": "", 02:31:28 INFO - "platform": "ubuntu64_hw", 02:31:28 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:31:28 INFO - "slavebuilddir": "test", 02:31:28 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 02:31:28 INFO - "branch": "mozilla-beta", 02:31:28 INFO - "repo_path": "releases/mozilla-beta", 02:31:28 INFO - "stage_platform": "linux64-pgo", 02:31:28 INFO - "builduid": "32749fe6ec6a47a98dd7668629456dc1", 02:31:28 INFO - "revision": "40e457de9ce8" 02:31:28 INFO - }, 02:31:28 INFO - "sourcestamp": { 02:31:28 INFO - "repository": "", 02:31:28 INFO - "hasPatch": false, 02:31:28 INFO - "project": "", 02:31:28 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 02:31:28 INFO - "changes": [ 02:31:28 INFO - { 02:31:28 INFO - "category": null, 02:31:28 INFO - "files": [ 02:31:28 INFO - { 02:31:28 INFO - "url": null, 02:31:28 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/firefox-44.0.en-US.linux-x86_64.tar.bz2" 02:31:28 INFO - } 02:31:28 INFO - ], 02:31:28 INFO - "repository": "", 02:31:28 INFO - "rev": "40e457de9ce8", 02:31:28 INFO - "who": "sendchange", 02:31:28 INFO - "when": 1452594638, 02:31:28 INFO - "number": 7447668, 02:31:28 INFO - "comments": "Bug 1237171 - Improve a case where ICO and BMP files disagree on an image size. r=tn, a=sylvestre", 02:31:28 INFO - "project": "", 02:31:28 INFO - "at": "Tue 12 Jan 2016 02:30:38", 02:31:28 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 02:31:28 INFO - "revlink": "", 02:31:28 INFO - "properties": [ 02:31:28 INFO - [ 02:31:28 INFO - "buildid", 02:31:28 INFO - "20160112001452", 02:31:28 INFO - "Change" 02:31:28 INFO - ], 02:31:28 INFO - [ 02:31:28 INFO - "builduid", 02:31:28 INFO - "32749fe6ec6a47a98dd7668629456dc1", 02:31:28 INFO - "Change" 02:31:28 INFO - ], 02:31:28 INFO - [ 02:31:28 INFO - "pgo_build", 02:31:28 INFO - "True", 02:31:28 INFO - "Change" 02:31:28 INFO - ] 02:31:28 INFO - ], 02:31:28 INFO - "revision": "40e457de9ce8" 02:31:28 INFO - } 02:31:28 INFO - ], 02:31:28 INFO - "revision": "40e457de9ce8" 02:31:28 INFO - } 02:31:28 INFO - } 02:31:28 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/firefox-44.0.en-US.linux-x86_64.tar.bz2. 02:31:28 INFO - Running post-action listener: _resource_record_post_action 02:31:28 INFO - ##### 02:31:28 INFO - ##### Running download-and-extract step. 02:31:28 INFO - ##### 02:31:28 INFO - Running pre-action listener: _resource_record_pre_action 02:31:28 INFO - Running main action method: download_and_extract 02:31:28 INFO - mkdir: /builds/slave/test-pgo/build/tests 02:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:28 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/test_packages.json 02:31:28 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 02:31:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 02:31:28 INFO - Downloaded 1270 bytes. 02:31:28 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 02:31:28 INFO - Using the following test package requirements: 02:31:28 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 02:31:28 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 02:31:28 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 02:31:28 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 02:31:28 INFO - u'jsshell-linux-x86_64.zip'], 02:31:28 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 02:31:28 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 02:31:28 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 02:31:28 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 02:31:28 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 02:31:28 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 02:31:28 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 02:31:28 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 02:31:28 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 02:31:28 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 02:31:28 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 02:31:28 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 02:31:28 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:28 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/firefox-44.0.en-US.linux-x86_64.common.tests.zip 02:31:28 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/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 02:31:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/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 02:31:31 INFO - Downloaded 22572113 bytes. 02:31:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 02:31:31 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 02:31:31 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 02:31:32 INFO - Return code: 0 02:31:32 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 02:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:32 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/firefox-44.0.en-US.linux-x86_64.common.tests.zip 02:31:32 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/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 02:31:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/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 02:31:34 INFO - Downloaded 22572113 bytes. 02:31:34 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 02:31:34 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 02:31:34 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 02:31:35 INFO - Return code: 0 02:31: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')]} 02:31:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 02:31:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/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 02:31:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/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 02:31:40 INFO - Downloaded 11131970 bytes. 02:31:40 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 02:31:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 02:31:40 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 02:31:40 INFO - Return code: 0 02:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/firefox-44.0.en-US.linux-x86_64.tar.bz2 02:31:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/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 02:31:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/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 02:31:52 INFO - Downloaded 56625636 bytes. 02:31:52 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/firefox-44.0.en-US.linux-x86_64.tar.bz2 02:31:52 INFO - mkdir: /builds/slave/test-pgo/properties 02:31:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 02:31:52 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 02:31:52 INFO - Contents: 02:31:52 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/firefox-44.0.en-US.linux-x86_64.tar.bz2 02:31:52 INFO - Running post-action listener: _resource_record_post_action 02:31:52 INFO - Running post-action listener: set_extra_try_arguments 02:31:52 INFO - ##### 02:31:52 INFO - ##### Running populate-webroot step. 02:31:52 INFO - ##### 02:31:52 INFO - Running pre-action listener: _resource_record_pre_action 02:31:52 INFO - Running main action method: populate_webroot 02:31:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:31:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:31:52 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 02:31:52 INFO - '../fennec_ids.txt'], 02:31:52 INFO - 'tests': ['tcheck2']}, 02:31:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:31:52 INFO - '--tppagecycles', 02:31:52 INFO - '7'], 02:31:52 INFO - 'tests': ['tsvgm']}}, 02:31:52 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 02:31:52 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 02:31:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:31:52 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 02:31:52 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 02:31:52 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 02:31:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:31:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:52 INFO - 'talos_options': ['--e10s'], 02:31:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:31:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:52 INFO - 'tests': ['damp', 'tps']}, 02:31:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:52 INFO - 'talos_options': ['--e10s'], 02:31:52 INFO - 'tests': ['damp', 'tps']}, 02:31:52 INFO - 'g3': {'tests': ['dromaeo_dom']}, 02:31:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:31:52 INFO - 'other': {'tests': ['a11yr', 02:31:52 INFO - 'ts_paint', 02:31:52 INFO - 'tpaint', 02:31:52 INFO - 'sessionrestore', 02:31:52 INFO - 'sessionrestore_no_auto_restore']}, 02:31:52 INFO - 'other-e10s': {'talos_options': ['--e10s'], 02:31:52 INFO - 'tests': ['a11yr', 02:31:52 INFO - 'ts_paint', 02:31:52 INFO - 'tpaint', 02:31:52 INFO - 'sessionrestore', 02:31:52 INFO - 'sessionrestore_no_auto_restore']}, 02:31:52 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 02:31:52 INFO - 'tests': ['a11yr', 02:31:52 INFO - 'ts_paint', 02:31:52 INFO - 'tpaint', 02:31:52 INFO - 'sessionrestore', 02:31:52 INFO - 'sessionrestore_no_auto_restore']}, 02:31:52 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 02:31:52 INFO - 'tests': ['a11yr', 02:31:52 INFO - 'ts_paint', 02:31:52 INFO - 'tpaint', 02:31:52 INFO - 'sessionrestore', 02:31:52 INFO - 'sessionrestore_no_auto_restore']}, 02:31:52 INFO - 'other_l64': {'tests': ['a11yr', 02:31:52 INFO - 'ts_paint', 02:31:52 INFO - 'tpaint', 02:31:52 INFO - 'sessionrestore', 02:31:52 INFO - 'sessionrestore_no_auto_restore']}, 02:31:52 INFO - 'other_nol64': {'tests': ['a11yr', 02:31:52 INFO - 'ts_paint', 02:31:52 INFO - 'tpaint', 02:31:52 INFO - 'sessionrestore', 02:31:52 INFO - 'sessionrestore_no_auto_restore']}, 02:31:52 INFO - 'svgr': {'tests': ['tsvgx', 02:31:52 INFO - 'tsvgr_opacity', 02:31:52 INFO - 'tart', 02:31:52 INFO - 'tscrollx', 02:31:52 INFO - 'cart']}, 02:31:52 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 02:31:52 INFO - 'tests': ['tsvgx', 02:31:52 INFO - 'tsvgr_opacity', 02:31:52 INFO - 'tart', 02:31:52 INFO - 'tscrollx', 02:31:52 INFO - 'cart']}, 02:31:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:52 INFO - 'tests': ['tp5o']}, 02:31:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:52 INFO - 'talos_options': ['--e10s'], 02:31:52 INFO - 'tests': ['tp5o']}, 02:31:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:52 INFO - 'talos_options': ['--xperf_path', 02:31:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:31:52 INFO - 'tests': ['tp5n']}, 02:31:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:52 INFO - 'talos_options': ['--e10s', 02:31:52 INFO - '--xperf_path', 02:31:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:31:52 INFO - 'tests': ['tp5n']}}, 02:31:52 INFO - 'talos.zip': {'path': '', 02:31:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:31:52 INFO - Running post-action listener: _resource_record_post_action 02:31:52 INFO - ##### 02:31:52 INFO - ##### Running create-virtualenv step. 02:31:52 INFO - ##### 02:31:52 INFO - Running pre-action listener: _resource_record_pre_action 02:31:52 INFO - Running main action method: create_virtualenv 02:31:52 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 02:31:52 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 02:31:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 02:31:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:31:52 INFO - Using real prefix '/usr' 02:31:52 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 02:31:53 INFO - Installing distribute.............................................................................................................................................................................................done. 02:31:55 INFO - Installing pip.................done. 02:31:55 INFO - Return code: 0 02:31:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 02:31:55 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')]} 02:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:55 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')]} 02:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:55 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 0x7f44ae2bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f54600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe3350>, '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 0x1fe4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe46e0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', '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 02:31:55 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 02:31:55 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 02:31:55 INFO - Using env: {'DISPLAY': ':0', 02:31:55 INFO - 'HOME': '/home/cltbld', 02:31:55 INFO - 'LANG': 'en_US.UTF-8', 02:31:55 INFO - 'LANGUAGE': 'en_US:en', 02:31:55 INFO - 'LOGNAME': 'cltbld', 02:31:55 INFO - 'MAIL': '/var/mail/cltbld', 02:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:55 INFO - 'MOZ_NO_REMOTE': '1', 02:31:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:31:55 INFO - 'NO_EM_RESTART': '1', 02:31:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 02:31:55 INFO - 'PWD': '/builds/slave/test-pgo', 02:31:55 INFO - 'SHELL': '/bin/bash', 02:31:55 INFO - 'SHLVL': '1', 02:31:55 INFO - 'TERM': 'linux', 02:31:55 INFO - 'TMOUT': '86400', 02:31:55 INFO - 'USER': 'cltbld', 02:31:55 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', 02:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:55 INFO - '_': '/tools/buildbot/bin/python'} 02:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:55 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 02:31:55 INFO - Cleaning up... 02:31:55 INFO - Return code: 0 02:31:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 02:31:55 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')]} 02:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:55 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')]} 02:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:55 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 0x7f44ae2bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f54600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe3350>, '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 0x1fe4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe46e0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', '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 02:31:55 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 02:31:55 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 02:31:55 INFO - Using env: {'DISPLAY': ':0', 02:31:55 INFO - 'HOME': '/home/cltbld', 02:31:55 INFO - 'LANG': 'en_US.UTF-8', 02:31:55 INFO - 'LANGUAGE': 'en_US:en', 02:31:55 INFO - 'LOGNAME': 'cltbld', 02:31:55 INFO - 'MAIL': '/var/mail/cltbld', 02:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:55 INFO - 'MOZ_NO_REMOTE': '1', 02:31:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:31:55 INFO - 'NO_EM_RESTART': '1', 02:31:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 02:31:55 INFO - 'PWD': '/builds/slave/test-pgo', 02:31:55 INFO - 'SHELL': '/bin/bash', 02:31:55 INFO - 'SHLVL': '1', 02:31:55 INFO - 'TERM': 'linux', 02:31:55 INFO - 'TMOUT': '86400', 02:31:55 INFO - 'USER': 'cltbld', 02:31:55 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', 02:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:55 INFO - '_': '/tools/buildbot/bin/python'} 02:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:55 INFO - Downloading/unpacking psutil>=0.7.1 02:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:57 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 02:31:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 02:31:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 02:31:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:31:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:31:57 INFO - Installing collected packages: psutil 02:31:57 INFO - Running setup.py install for psutil 02:31:57 INFO - building 'psutil._psutil_linux' extension 02:31:57 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 02:31:58 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 02:31:58 INFO - building 'psutil._psutil_posix' extension 02:31:58 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 02:31:58 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 02:31:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:31:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:31:58 INFO - Successfully installed psutil 02:31:58 INFO - Cleaning up... 02:31:58 INFO - Return code: 0 02:31:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 02:31:58 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')]} 02:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:58 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')]} 02:31:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:58 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 0x7f44ae2bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f54600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe3350>, '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 0x1fe4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe46e0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', '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 02:31:58 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 02:31:58 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 02:31:58 INFO - Using env: {'DISPLAY': ':0', 02:31:58 INFO - 'HOME': '/home/cltbld', 02:31:58 INFO - 'LANG': 'en_US.UTF-8', 02:31:58 INFO - 'LANGUAGE': 'en_US:en', 02:31:58 INFO - 'LOGNAME': 'cltbld', 02:31:58 INFO - 'MAIL': '/var/mail/cltbld', 02:31:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:58 INFO - 'MOZ_NO_REMOTE': '1', 02:31:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:31:58 INFO - 'NO_EM_RESTART': '1', 02:31:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:31:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 02:31:58 INFO - 'PWD': '/builds/slave/test-pgo', 02:31:58 INFO - 'SHELL': '/bin/bash', 02:31:58 INFO - 'SHLVL': '1', 02:31:58 INFO - 'TERM': 'linux', 02:31:58 INFO - 'TMOUT': '86400', 02:31:58 INFO - 'USER': 'cltbld', 02:31:58 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', 02:31:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:58 INFO - '_': '/tools/buildbot/bin/python'} 02:31:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:31:58 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 02:31:58 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 02:31:58 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 02:31:58 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 02:31:58 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 02:31:58 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 02:32:00 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:32:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 02:32:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:32:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:32:00 INFO - Installing collected packages: mozsystemmonitor 02:32:00 INFO - Running setup.py install for mozsystemmonitor 02:32:00 INFO - Successfully installed mozsystemmonitor 02:32:00 INFO - Cleaning up... 02:32:00 INFO - Return code: 0 02:32:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 02:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:00 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 0x7f44ae2bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f54600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe3350>, '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 0x1fe4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe46e0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', '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 02:32:00 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 02:32:00 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 02:32:00 INFO - Using env: {'DISPLAY': ':0', 02:32:00 INFO - 'HOME': '/home/cltbld', 02:32:00 INFO - 'LANG': 'en_US.UTF-8', 02:32:00 INFO - 'LANGUAGE': 'en_US:en', 02:32:00 INFO - 'LOGNAME': 'cltbld', 02:32:00 INFO - 'MAIL': '/var/mail/cltbld', 02:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:00 INFO - 'MOZ_NO_REMOTE': '1', 02:32:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:00 INFO - 'NO_EM_RESTART': '1', 02:32:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 02:32:00 INFO - 'PWD': '/builds/slave/test-pgo', 02:32:00 INFO - 'SHELL': '/bin/bash', 02:32:00 INFO - 'SHLVL': '1', 02:32:00 INFO - 'TERM': 'linux', 02:32:00 INFO - 'TMOUT': '86400', 02:32:00 INFO - 'USER': 'cltbld', 02:32:00 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', 02:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:00 INFO - '_': '/tools/buildbot/bin/python'} 02:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:00 INFO - Downloading/unpacking blobuploader==1.2.4 02:32:00 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 02:32:00 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 02:32:00 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 02:32:00 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 02:32:00 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 02:32:00 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 02:32:02 INFO - Downloading blobuploader-1.2.4.tar.gz 02:32:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 02:32:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:32:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:32:02 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 02:32:02 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 02:32:02 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 02:32:02 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 02:32:02 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 02:32:02 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 02:32:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 02:32:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 02:32:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:32:03 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 02:32:03 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 02:32:03 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 02:32:03 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 02:32:03 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 02:32:03 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 02:32:03 INFO - Downloading docopt-0.6.1.tar.gz 02:32:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 02:32:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 02:32:03 INFO - Installing collected packages: blobuploader, requests, docopt 02:32:03 INFO - Running setup.py install for blobuploader 02:32:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:32:03 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 02:32:03 INFO - Running setup.py install for requests 02:32:03 INFO - Running setup.py install for docopt 02:32:03 INFO - Successfully installed blobuploader requests docopt 02:32:03 INFO - Cleaning up... 02:32:03 INFO - Return code: 0 02:32:03 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 02:32:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:03 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 0x7f44ae2bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f54600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe3350>, '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 0x1fe4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe46e0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', '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 02:32:03 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 02:32:03 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 02:32:03 INFO - Using env: {'DISPLAY': ':0', 02:32:03 INFO - 'HOME': '/home/cltbld', 02:32:03 INFO - 'LANG': 'en_US.UTF-8', 02:32:03 INFO - 'LANGUAGE': 'en_US:en', 02:32:03 INFO - 'LOGNAME': 'cltbld', 02:32:03 INFO - 'MAIL': '/var/mail/cltbld', 02:32:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:03 INFO - 'MOZ_NO_REMOTE': '1', 02:32:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:03 INFO - 'NO_EM_RESTART': '1', 02:32:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 02:32:03 INFO - 'PWD': '/builds/slave/test-pgo', 02:32:03 INFO - 'SHELL': '/bin/bash', 02:32:03 INFO - 'SHLVL': '1', 02:32:03 INFO - 'TERM': 'linux', 02:32:03 INFO - 'TMOUT': '86400', 02:32:03 INFO - 'USER': 'cltbld', 02:32:03 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', 02:32:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:03 INFO - '_': '/tools/buildbot/bin/python'} 02:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 02:32:04 INFO - Running setup.py (path:/tmp/pip-kOePEk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 02:32:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 02:32:04 INFO - Running setup.py (path:/tmp/pip-MioAhC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 02:32:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 02:32:04 INFO - Running setup.py (path:/tmp/pip-UD8Ser-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 02:32:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 02:32:04 INFO - Running setup.py (path:/tmp/pip-JQ2v9i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 02:32:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 02:32:04 INFO - Running setup.py (path:/tmp/pip-l7bRo6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 02:32:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 02:32:04 INFO - Running setup.py (path:/tmp/pip-ZUiYId-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 02:32:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 02:32:04 INFO - Running setup.py (path:/tmp/pip-nIzfP9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 02:32:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 02:32:04 INFO - Running setup.py (path:/tmp/pip-9o0IxO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 02:32:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 02:32:04 INFO - Running setup.py (path:/tmp/pip-S9eK_C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 02:32:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 02:32:04 INFO - Running setup.py (path:/tmp/pip-mDA8UA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 02:32:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 02:32:04 INFO - Running setup.py (path:/tmp/pip-Z66FYs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 02:32:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 02:32:04 INFO - Running setup.py (path:/tmp/pip-EiuA6g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 02:32:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 02:32:05 INFO - Running setup.py (path:/tmp/pip-gpDzSL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 02:32:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 02:32:05 INFO - Running setup.py (path:/tmp/pip-EvKdy3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 02:32:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 02:32:05 INFO - Running setup.py (path:/tmp/pip-hR9ZVF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 02:32:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 02:32:05 INFO - Running setup.py (path:/tmp/pip-89GwQt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 02:32:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 02:32:05 INFO - Running setup.py (path:/tmp/pip-xHr04O-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 02:32:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:32:05 INFO - Running setup.py install for manifestparser 02:32:05 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 02:32:05 INFO - Running setup.py install for mozcrash 02:32:05 INFO - Running setup.py install for mozdebug 02:32:05 INFO - Running setup.py install for mozdevice 02:32:05 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 02:32:05 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 02:32:05 INFO - Running setup.py install for mozfile 02:32:05 INFO - Running setup.py install for mozhttpd 02:32:05 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 02:32:05 INFO - Running setup.py install for mozinfo 02:32:06 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 02:32:06 INFO - Running setup.py install for mozInstall 02:32:06 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 02:32:06 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 02:32:06 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 02:32:06 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 02:32:06 INFO - Running setup.py install for mozleak 02:32:06 INFO - Running setup.py install for mozlog 02:32:06 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 02:32:06 INFO - Running setup.py install for moznetwork 02:32:06 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 02:32:06 INFO - Running setup.py install for mozprocess 02:32:06 INFO - Running setup.py install for mozprofile 02:32:06 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 02:32:06 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 02:32:06 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 02:32:06 INFO - Running setup.py install for mozrunner 02:32:06 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 02:32:06 INFO - Running setup.py install for mozscreenshot 02:32:06 INFO - Running setup.py install for moztest 02:32:07 INFO - Running setup.py install for mozversion 02:32:07 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 02:32:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:32:07 INFO - Cleaning up... 02:32:07 INFO - Return code: 0 02:32:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 02:32:07 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')]} 02:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:07 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')]} 02:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:07 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 0x7f44ae2bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f54600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe3350>, '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 0x1fe4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe46e0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', '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 02:32:07 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 02:32:07 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 02:32:07 INFO - Using env: {'DISPLAY': ':0', 02:32:07 INFO - 'HOME': '/home/cltbld', 02:32:07 INFO - 'LANG': 'en_US.UTF-8', 02:32:07 INFO - 'LANGUAGE': 'en_US:en', 02:32:07 INFO - 'LOGNAME': 'cltbld', 02:32:07 INFO - 'MAIL': '/var/mail/cltbld', 02:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:07 INFO - 'MOZ_NO_REMOTE': '1', 02:32:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:07 INFO - 'NO_EM_RESTART': '1', 02:32:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 02:32:07 INFO - 'PWD': '/builds/slave/test-pgo', 02:32:07 INFO - 'SHELL': '/bin/bash', 02:32:07 INFO - 'SHLVL': '1', 02:32:07 INFO - 'TERM': 'linux', 02:32:07 INFO - 'TMOUT': '86400', 02:32:07 INFO - 'USER': 'cltbld', 02:32:07 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', 02:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:07 INFO - '_': '/tools/buildbot/bin/python'} 02:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 02:32:07 INFO - Running setup.py (path:/tmp/pip-8kOUNW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 02:32:07 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)) 02:32:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 02:32:07 INFO - Running setup.py (path:/tmp/pip-FtrWvA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 02:32:07 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)) 02:32:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 02:32:07 INFO - Running setup.py (path:/tmp/pip-48K2LG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 02:32:07 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)) 02:32:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 02:32:07 INFO - Running setup.py (path:/tmp/pip-VM7WGu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 02:32:07 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)) 02:32:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 02:32:07 INFO - Running setup.py (path:/tmp/pip-qABiQ1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 02:32:08 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)) 02:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 02:32:08 INFO - Running setup.py (path:/tmp/pip-NxAWG7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 02:32:08 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)) 02:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 02:32:08 INFO - Running setup.py (path:/tmp/pip-rnsKJD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 02:32:08 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)) 02:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 02:32:08 INFO - Running setup.py (path:/tmp/pip-j_S_GH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 02:32:08 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)) 02:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 02:32:08 INFO - Running setup.py (path:/tmp/pip-3aXesf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 02:32:08 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)) 02:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 02:32:08 INFO - Running setup.py (path:/tmp/pip-wDfdud-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 02:32:08 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)) 02:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 02:32:08 INFO - Running setup.py (path:/tmp/pip-S1JAkW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 02:32:08 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)) 02:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 02:32:08 INFO - Running setup.py (path:/tmp/pip-tgUWec-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 02:32:08 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)) 02:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 02:32:08 INFO - Running setup.py (path:/tmp/pip-jEYX9f-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 02:32:08 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)) 02:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 02:32:08 INFO - Running setup.py (path:/tmp/pip-iSqS8H-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 02:32:08 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)) 02:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 02:32:08 INFO - Running setup.py (path:/tmp/pip-TIAAXB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 02:32:08 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)) 02:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 02:32:08 INFO - Running setup.py (path:/tmp/pip-LQs5uK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 02:32:08 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)) 02:32:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 02:32:08 INFO - Running setup.py (path:/tmp/pip-0UPn9m-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 02:32:08 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)) 02:32:08 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)) 02:32:08 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)) 02:32:08 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)) 02:32:08 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)) 02:32:08 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)) 02:32:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 02:32:09 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 02:32:09 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 02:32:09 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 02:32:09 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 02:32:09 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 02:32:09 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 02:32:10 INFO - Downloading blessings-1.5.1.tar.gz 02:32:11 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 02:32:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 02:32:11 INFO - Installing collected packages: blessings 02:32:11 INFO - Running setup.py install for blessings 02:32:11 INFO - Successfully installed blessings 02:32:11 INFO - Cleaning up... 02:32:11 INFO - Return code: 0 02:32:11 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 02:32:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 02:32:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 02:32:11 INFO - Reading from file tmpfile_stdout 02:32:11 INFO - Current package versions: 02:32:11 INFO - argparse == 1.2.1 02:32:11 INFO - blessings == 1.5.1 02:32:11 INFO - blobuploader == 1.2.4 02:32:11 INFO - docopt == 0.6.1 02:32:11 INFO - manifestparser == 1.1 02:32:11 INFO - mozInstall == 1.12 02:32:11 INFO - mozcrash == 0.16 02:32:11 INFO - mozdebug == 0.1 02:32:11 INFO - mozdevice == 0.46 02:32:11 INFO - mozfile == 1.2 02:32:11 INFO - mozhttpd == 0.7 02:32:11 INFO - mozinfo == 0.8 02:32:11 INFO - mozleak == 0.1 02:32:11 INFO - mozlog == 3.0 02:32:11 INFO - moznetwork == 0.27 02:32:11 INFO - mozprocess == 0.22 02:32:11 INFO - mozprofile == 0.27 02:32:11 INFO - mozrunner == 6.11 02:32:11 INFO - mozscreenshot == 0.1 02:32:11 INFO - mozsystemmonitor == 0.0 02:32:11 INFO - moztest == 0.7 02:32:11 INFO - mozversion == 1.4 02:32:11 INFO - psutil == 3.1.1 02:32:11 INFO - requests == 1.2.3 02:32:11 INFO - wsgiref == 0.1.2 02:32:11 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 02:32: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')]} 02:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32: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')]} 02:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32: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 0x7f44ae2bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f54600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe3350>, '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 0x1fe4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe46e0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', '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 02:32: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 02:32: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 02:32:11 INFO - Using env: {'DISPLAY': ':0', 02:32:11 INFO - 'HOME': '/home/cltbld', 02:32:11 INFO - 'LANG': 'en_US.UTF-8', 02:32:11 INFO - 'LANGUAGE': 'en_US:en', 02:32:11 INFO - 'LOGNAME': 'cltbld', 02:32:11 INFO - 'MAIL': '/var/mail/cltbld', 02:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:11 INFO - 'MOZ_NO_REMOTE': '1', 02:32:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:11 INFO - 'NO_EM_RESTART': '1', 02:32:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 02:32:11 INFO - 'PWD': '/builds/slave/test-pgo', 02:32:11 INFO - 'SHELL': '/bin/bash', 02:32:11 INFO - 'SHLVL': '1', 02:32:11 INFO - 'TERM': 'linux', 02:32:11 INFO - 'TMOUT': '86400', 02:32:11 INFO - 'USER': 'cltbld', 02:32:11 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', 02:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:11 INFO - '_': '/tools/buildbot/bin/python'} 02:32:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32: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)) 02:32: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)) 02:32: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)) 02:32: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)) 02:32: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)) 02:32: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)) 02:32: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)) 02:32: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)) 02:32: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)) 02:32: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)) 02:32: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)) 02:32:11 INFO - Cleaning up... 02:32:11 INFO - Return code: 0 02:32:11 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 02:32: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')]} 02:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32: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')]} 02:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32: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 0x7f44ae2bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f54600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe3350>, '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 0x1fe4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe46e0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', '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 02:32: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 02:32: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 02:32:11 INFO - Using env: {'DISPLAY': ':0', 02:32:11 INFO - 'HOME': '/home/cltbld', 02:32:11 INFO - 'LANG': 'en_US.UTF-8', 02:32:11 INFO - 'LANGUAGE': 'en_US:en', 02:32:11 INFO - 'LOGNAME': 'cltbld', 02:32:11 INFO - 'MAIL': '/var/mail/cltbld', 02:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:11 INFO - 'MOZ_NO_REMOTE': '1', 02:32:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:11 INFO - 'NO_EM_RESTART': '1', 02:32:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 02:32:11 INFO - 'PWD': '/builds/slave/test-pgo', 02:32:11 INFO - 'SHELL': '/bin/bash', 02:32:11 INFO - 'SHLVL': '1', 02:32:11 INFO - 'TERM': 'linux', 02:32:11 INFO - 'TMOUT': '86400', 02:32:11 INFO - 'USER': 'cltbld', 02:32:11 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', 02:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:11 INFO - '_': '/tools/buildbot/bin/python'} 02:32:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:11 INFO - Downloading/unpacking jsonschema 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:32: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 02:32:13 INFO - Downloading/unpacking functools32 (from jsonschema) 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32:13 INFO - Downloading functools32-3.2.3-2.tar.gz 02:32: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 02:32:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 02:32:13 INFO - warning: no files found matching '*.txt' 02:32:13 INFO - no previously-included directories found matching 'build' 02:32:13 INFO - no previously-included directories found matching 'dist' 02:32:13 INFO - no previously-included directories found matching '.git*' 02:32:13 INFO - Installing collected packages: jsonschema, functools32 02:32:13 INFO - Running setup.py install for functools32 02:32:14 INFO - warning: no files found matching '*.txt' 02:32:14 INFO - no previously-included directories found matching 'build' 02:32:14 INFO - no previously-included directories found matching 'dist' 02:32:14 INFO - no previously-included directories found matching '.git*' 02:32:14 INFO - Successfully installed jsonschema functools32 02:32:14 INFO - Cleaning up... 02:32:14 INFO - Return code: 0 02:32:14 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 02:32: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')]} 02:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32: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')]} 02:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32: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 0x7f44ae2bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f54600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe3350>, '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 0x1fe4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe46e0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', '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 02:32: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 02:32: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 02:32:14 INFO - Using env: {'DISPLAY': ':0', 02:32:14 INFO - 'HOME': '/home/cltbld', 02:32:14 INFO - 'LANG': 'en_US.UTF-8', 02:32:14 INFO - 'LANGUAGE': 'en_US:en', 02:32:14 INFO - 'LOGNAME': 'cltbld', 02:32:14 INFO - 'MAIL': '/var/mail/cltbld', 02:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:14 INFO - 'MOZ_NO_REMOTE': '1', 02:32:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:14 INFO - 'NO_EM_RESTART': '1', 02:32:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 02:32:14 INFO - 'PWD': '/builds/slave/test-pgo', 02:32:14 INFO - 'SHELL': '/bin/bash', 02:32:14 INFO - 'SHLVL': '1', 02:32:14 INFO - 'TERM': 'linux', 02:32:14 INFO - 'TMOUT': '86400', 02:32:14 INFO - 'USER': 'cltbld', 02:32:14 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', 02:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:14 INFO - '_': '/tools/buildbot/bin/python'} 02:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:14 INFO - Downloading/unpacking flake8 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:32: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 02:32:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:32: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 02:32:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 02:32:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 02:32:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:32:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:32:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:32:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:32:16 INFO - no previously-included directories found matching 'docs/_build' 02:32:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:32:16 INFO - Running setup.py install for pep8 02:32:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:32:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:32:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:32:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:32:16 INFO - no previously-included directories found matching 'docs/_build' 02:32:16 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 02:32:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:32:16 INFO - Cleaning up... 02:32:16 INFO - Return code: 0 02:32:16 INFO - Running post-action listener: _resource_record_post_action 02:32:16 INFO - Running post-action listener: _start_resource_monitoring 02:32:16 INFO - Starting resource monitoring. 02:32:16 INFO - ##### 02:32:16 INFO - ##### Running install step. 02:32:16 INFO - ##### 02:32:16 INFO - Running pre-action listener: _resource_record_pre_action 02:32:16 INFO - Running main action method: install 02:32:16 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 02:32:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 02:32:17 INFO - Reading from file tmpfile_stdout 02:32:17 INFO - Detecting whether we're running mozinstall >=1.0... 02:32:17 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 02:32:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 02:32:17 INFO - Reading from file tmpfile_stdout 02:32:17 INFO - Output received: 02:32:17 INFO - Usage: mozinstall [options] installer 02:32:17 INFO - Options: 02:32:17 INFO - -h, --help show this help message and exit 02:32:17 INFO - -d DEST, --destination=DEST 02:32:17 INFO - Directory to install application into. [default: 02:32:17 INFO - "/builds/slave/test-pgo"] 02:32:17 INFO - --app=APP Application being installed. [default: firefox] 02:32:17 INFO - mkdir: /builds/slave/test-pgo/build/application 02:32: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'] 02:32: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 02:32:31 INFO - Reading from file tmpfile_stdout 02:32:31 INFO - Output received: 02:32:31 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 02:32:31 INFO - Running post-action listener: _resource_record_post_action 02:32:31 INFO - ##### 02:32:31 INFO - ##### Running run-tests step. 02:32:31 INFO - ##### 02:32:31 INFO - Running pre-action listener: _resource_record_pre_action 02:32:31 INFO - Running main action method: run_tests 02:32:31 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 02:32:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 02:32:31 INFO - Python 2.7.3 02:32:31 INFO - Return code: 0 02:32:31 INFO - grabbing minidump binary from tooltool 02:32: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')]} 02:32: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 0x1fe3350>, '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 0x1fe4250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe46e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 02:32: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 02:32: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 02:32:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:32:31 INFO - Return code: 0 02:32:31 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 02:32:31 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 02:32:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 02:32:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 02:32:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 02:32:31 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 02:32:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 02:32:32 INFO - Return code: 0 02:32: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/1452586492/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 02:32: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/1452586492/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --webServer localhost 02:32:32 INFO - Using env: {'DISPLAY': ':0', 02:32:32 INFO - 'HOME': '/home/cltbld', 02:32:32 INFO - 'LANG': 'en_US.UTF-8', 02:32:32 INFO - 'LANGUAGE': 'en_US:en', 02:32:32 INFO - 'LOGNAME': 'cltbld', 02:32:32 INFO - 'MAIL': '/var/mail/cltbld', 02:32:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 02:32:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 02:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:32 INFO - 'MOZ_NO_REMOTE': '1', 02:32:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 02:32:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:32 INFO - 'NO_EM_RESTART': '1', 02:32:32 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 02:32:32 INFO - 'PWD': '/builds/slave/test-pgo', 02:32:32 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 02:32:32 INFO - 'SHELL': '/bin/bash', 02:32:32 INFO - 'SHLVL': '1', 02:32:32 INFO - 'TERM': 'linux', 02:32:32 INFO - 'TMOUT': '86400', 02:32:32 INFO - 'USER': 'cltbld', 02:32:32 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725', 02:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:32 INFO - '_': '/tools/buildbot/bin/python'} 02:32: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/1452586492/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost'] with output_timeout 3600 02:32:32 INFO - mozversion INFO | application_buildid: 20160112001452 02:32:32 INFO - mozversion INFO | application_changeset: 40e457de9ce8b1a167caf74d9e464d1d51e4fda7 02:32:32 INFO - mozversion INFO | application_display_name: Nightly 02:32:32 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:32:32 INFO - mozversion INFO | application_name: Firefox 02:32:32 INFO - mozversion INFO | application_remotingname: firefox 02:32:32 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 02:32:32 INFO - mozversion INFO | application_vendor: Mozilla 02:32:32 INFO - mozversion INFO | application_version: 44.0 02:32:32 INFO - mozversion INFO | platform_buildid: 20160112001452 02:32:32 INFO - mozversion INFO | platform_changeset: 40e457de9ce8b1a167caf74d9e464d1d51e4fda7 02:32:32 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 02:32:32 INFO - mozversion INFO | platform_version: 44.0 02:32:32 INFO - 2016-01-12 02:32:32,633 DEBUG : using testdate: 1452594752 02:32:32 INFO - 2016-01-12 02:32:32,633 DEBUG : actual date: 1452594752 02:32:32 INFO - 2016-01-12 02:32:32,642 INFO : starting webserver on 'localhost:52443' 02:32:32 INFO - 2016-01-12 02:32:32,643 INFO : Starting test suite talos-linux64-ix-066 02:32:32 INFO - 2016-01-12 02:32:32,643 INFO : Starting test dromaeo_css 02:32:32 INFO - 2016-01-12 02:32:32,643 DEBUG : operating with platform_type : linux_ 02:32:32 INFO - 2016-01-12 02:32:32,644 INFO : Initialising browser for dromaeo_css test... 02:32:32 INFO - 2016-01-12 02:32:32,660 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpiZRInP/profile http://localhost:52443/getInfo.html 02:32:36 INFO - 2016-01-12 02:32:36,387 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:32:36 INFO - 2016-01-12 02:32:36,388 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:32:36 INFO - 2016-01-12 02:32:36,388 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 02:32:36 INFO - 2016-01-12 02:32:36,388 DEBUG : BROWSER_OUTPUT: __metrics 02:32:36 INFO - 2016-01-12 02:32:36,545 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] 02:32:37 INFO - 2016-01-12 02:32:37,798 INFO : Browser initialized. 02:32:37 INFO - 2016-01-12 02:32:37,798 INFO : Running cycle 1/1 for dromaeo_css test... 02:32:37 INFO - 2016-01-12 02:32:37,799 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpiZRInP/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 02:32:49 INFO - 2016-01-12 02:32:49,303 DEBUG : BROWSER_OUTPUT: RSS: Main: 129003520 02:32:49 INFO - 2016-01-12 02:32:49,303 DEBUG : BROWSER_OUTPUT: 02:33:40 INFO - 2016-01-12 02:33:40,103 DEBUG : BROWSER_OUTPUT: 1452594820099 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] 02:35:48 INFO - 2016-01-12 02:35:48,053 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52443/tests/dromaeo/cssquery-ext.html) 02:35:49 INFO - 2016-01-12 02:35:49,118 DEBUG : BROWSER_OUTPUT: RSS: Main: 204222464 02:35:49 INFO - 2016-01-12 02:35:49,118 DEBUG : BROWSER_OUTPUT: 02:38:51 INFO - 2016-01-12 02:38:51,141 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/dromaeo/cssquery-ext.html (next: http://localhost:52443/tests/dromaeo/cssquery-jquery.html) 02:38:51 INFO - 2016-01-12 02:38:51,715 DEBUG : BROWSER_OUTPUT: RSS: Main: 205508608 02:38:51 INFO - 2016-01-12 02:38:51,715 DEBUG : BROWSER_OUTPUT: 02:41:53 INFO - 2016-01-12 02:41:53,429 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52443/tests/dromaeo/cssquery-mootools.html) 02:41:53 INFO - 2016-01-12 02:41:53,991 DEBUG : BROWSER_OUTPUT: RSS: Main: 201695232 02:41:53 INFO - 2016-01-12 02:41:53,991 DEBUG : BROWSER_OUTPUT: 02:44:55 INFO - 2016-01-12 02:44:55,715 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52443/tests/dromaeo/cssquery-prototype.html) 02:44:56 INFO - 2016-01-12 02:44:56,269 DEBUG : BROWSER_OUTPUT: RSS: Main: 191057920 02:44:56 INFO - 2016-01-12 02:44:56,269 DEBUG : BROWSER_OUTPUT: 02:47:58 INFO - 2016-01-12 02:47:58,623 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52443/tests/dromaeo/cssquery-yui.html) 02:47:59 INFO - 2016-01-12 02:47:59,184 DEBUG : BROWSER_OUTPUT: RSS: Main: 194236416 02:47:59 INFO - 2016-01-12 02:47:59,184 DEBUG : BROWSER_OUTPUT: 02:51:01 INFO - 2016-01-12 02:51:01,283 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/dromaeo/cssquery-yui.html (next: http://localhost:52443/tests/dromaeo/cssquery-dojo.html) 02:51:01 INFO - 2016-01-12 02:51:01,534 DEBUG : BROWSER_OUTPUT: RSS: Main: 225443840 02:51:01 INFO - 2016-01-12 02:51:01,534 DEBUG : BROWSER_OUTPUT: 02:51:01 INFO - 2016-01-12 02:51:01,534 DEBUG : BROWSER_OUTPUT: __start_tp_report 02:51:01 INFO - 2016-01-12 02:51:01,534 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 02:51:01 INFO - 2016-01-12 02:51:01,534 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 02:51:01 INFO - 2016-01-12 02:51:01,535 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 02:51:01 INFO - 2016-01-12 02:51:01,538 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3298.2743796364794;3374.2913988062505;3395.575553055868;3400.5409269748575;3409.7272218222543;5554.333479982402;5571.247881535992;5594.272744543214;5598.888022239553;5615.831525054255;5592.105263157896;5598.916016259759;5607.719614019303;5612.242347283115;5622.072358060915;4964.329815474901;5111.744412779355;5138.794448222066;5191.091558977183;5205.427402985688;5132.8973420531565;5152.665076770017;5154.948450515484;5163.457836927125;5164.664296820713;4952.479989601094;5095.3885533736;5101.489851014906;5159.638825282232;5167.224916262572;5183.585313174937;5187.844364669066;5241.895162096775;5241.895162096775;5244.685318880879;5148.871278218037;5184.170532714786;5203.427622961472;5225.451327610622;5246.475352464761;5244.81643142492;5284.154535274376;5429.56563474921;5445.047116754552;5468.234447177399;5995.52035837132;6007.279126504804;6040.728167232485;6045.516358691294;6048.727807248684;6065.8180254592435;6075;6084.117802918597;6086.421789929949;6086.7565297388055;5403.567714582824;5484.561235101182;5514.393416724157;5608.523275521586;5613.943860561342;6054.182685337423;6064.120702498201;6075.563133806005;6097.847553811191;6111.816645500642;5901.527877769769;5932.665150341225;5942.762289508371;5956.821295361146;5969.910451343233;5842.357340692521;5853.209816674695;5978.043513037893;5996.580239383201;6030.4572588467245;5942.524598032147;5986.401359864066;5990.430909063632;5997.640141591519;6020.69896505173;5986.850328741729;6002.789902353484;6003.7898673546215;6006.5495087868585;6015.759369625166;4450.443694538183;4462.509123996358;4534.501204867462;4547.590716835501;4550.294704320836;4118.588141185917;4127.133302006552;4143.875683729493;4155.044339801862;4155.8337666493;5462.098753705594;5729.799457018984;5737.053386191315;5739.827805165852;5739.913901291484;5904.793332233351;5949.672767997802;5958.314793798769;5965.850853728692;5973.462388384979;5723.141528770634;5734.77060917567;5737.741801618937;5743;5745.080787074048;4968.701877887266;5196.68819870809;5256.2904007959205;5260.763265653055;5262.947370526323;6009.699515024231;6103.542234332443;6106.095145186544;6113.402113262514;6139.171211886338;6048.485878700184;6052.636841789506;6059.030555111161;6059.787907423219;6061.696915154136;5973.790917317873;6070.423309785563;6077.817665470043;6159.538034647329;6161.661108638888;5913.793017244376;5953.910691339547;5956.672383018976;5960;5975.850603734942;6091.390860914051;6092.238470191226;6102.389761023862;6105.419985101408;6113.694315284129;5676.602637815313;5803.7968671096305;5838.32859221195;5925.970370148294;5983.521318294439;5858.736356863866;5872.412758724094;5874.823755287348;5880.970595146997;5881.617694849821;5769.07694768835;5769.53843692512;5774;5785.681787501728;5790.1893734877995;5718.685472299064;5731;5834.795782147605;5860;5874.206982057539;5784.161296612052;5805.245318108618;5814.476697097281;5818.592698511064;5824.068149096124 02:51:01 INFO - 2016-01-12 02:51:01,538 DEBUG : BROWSER_OUTPUT: |1;ext.html;596.826920193144;622.6264241455127;629.3549112160036;645.3449748505271;647.359114476668;35368.938931832025;43813.34279985799;44265.33601995972;44529.109417811625;44568;49387.753061234645;50021.49978500214;50067.749661251735;50132.24801627969;50159;8324.042735085482;8452;9086.77283067922;9229.492377919212;10724;10915.345079295268;11009.064229540498;11016.504257308441;11030.944845275762;11034.889651103467;11083.390413527253;11184.38485883276;11205.271657342246;11209.663710088711;11210;10978.725531861728;11073.169512286611;11075.16936229782;11082.833757493685;11091.279066860629;10854.185936054828;11017.394043327613;11038.558457661684;11041.171912106623;11043.50912626793;43963.56036439627;44333.556664433265;44389.33415998763;44487;44487.55512444866;3418.3334249821296;3760.7555508891833;4275.4228179195725;4347.174036932973;4361.367601697736;33103;33404.832975835205;33572.1606959828;33692.6630733692;33826.32347353039;3649.7262705296944;3668.4497325401403;3686.391745362012;3908.2965066288057;3992.321305378093;318277;320289;320624;321694;322328;62662.686686566274;63647;70811.64594176997;71270.64364678143;71286.28713712901;72514.27485725076;72663.27336726565;73151.63424182845;73242.26757732354;73369.26630733625;17540.912295438695;17589.560260993323;17636.911815440842;17650.205740741698;17692;14032.08791428554;14087.43650253999;14137.293135343398;14137.85862141386;14143.85856141446;22416.43958901008;22752.886235568716;22768.886155569115;22967.54064918692;22988.195413160458;8649.956750216208;8845;8913.420627659181;8968.68609598661;9049.276057915482;1039.308859608352;1054.715226888751;1065.7229120428744;1076.923076923077;1138;29929.850350748107;29978.55032174519;30731;30839.691603083684;31008.689913101032;1072.871255449351;1109.6892869996414;1139.3961200563715;1201.1111777284746;1210.0984766349127;13956.720865582834;15047.924760376347;15258.16080115582;15661.608459788597;16730.83269167317;459260;465800;466652;467285;468352;59119;59566.40433595609;59623.403765962656;59717.701411492664;59733.402665973656;1331.8401791785047;1415.6782876083232;1441.80535627691;1462.4223431744188;1480.5262316058759;30118.247043824078;30292.69707302987;30669.693303066684;31081.68918310788;31109.533357000117;49757.75121124371;50203.74898125632;50253;50255;50282.24576631426;300080;303150;303677;304596;305073;35268.29463410769;35715.82142089377;36371.63628363788;36407.63592364149;36450.27099458049;46885.260514938134;47558.28662570134;47561;47572;48035;38520.42219366768;38530.80734596309;39566.20867582575;39809.00477488086;39851.60148398479;38362.61637383702;38710.612893870704;39033.41449878311;39171.608283916794;39537.406938895365;38256;38891.41662875003;39705.80147099246;39824;39835;32754.67245327516;34893.476597850546;34925.65074349224;35262.647373525935;35341.64658353384;26484.602730959436;26747.86626066935;26898.596521051808;26947.461050778482;27147.457050858477 02:51:01 INFO - 2016-01-12 02:51:01,542 DEBUG : BROWSER_OUTPUT: |2;jquery.html;388.1441421665228;389.87523992322457;391.5751409720455;392.72116797074074;395.44242617908753;13784.483706792245;14118.223497707622;18341.358052468167;21069.578608427822;21075.262365817198;13174;13600.183988960669;13618.387172577233;18871.716924246146;19318.420447386565;4854.320395144682;4957.330760347343;4959.156943319626;4963.875903102416;4970.080535100999;4927.384076990376;4945.579625731817;4950.6287028472825;4953.0341583391255;4953.628477864157;3900.0444891001744;4897.191963326047;4908.067467181224;4909.975450122744;5111.488851114877;5351.063563876306;5381.650192737459;5384.3807962084275;5395.7032363220005;5402.756875940572;4537.410136682243;4653.697509661861;4678.602318802906;4680.7191568506;4682.274247491644;15618.375264989387;15777.447789327432;15812.209389530593;15894.76157857633;15911.124888131148;2125.84567676577;2139.465133716571;2171.598254322955;2197.04428573571;2251.4821591034156;11157.497912593872;11246.212765106446;11307.943460282728;11396.60111896088;11424.143189260838;1809.185866360143;1881.7741870975433;1935.8741681790639;1937.5156210947264;1944.057132290838;65555.67222163954;65837.67081164611;66756.3324366755;66939.3306066938;67822;7709.768706938801;7804.492707973964;8028.518288902685;8079.878801817978;8084.434089613671;9573.760655983517;9605.855912161323;9628.422294662343;9632.229421646252;9632.229421646392;6475.384838440341;6559.934400656028;6571.868562628816;6585;6601.735930562723;6823.93176068233;6825.146856642919;6860.142482189726;6901.240863505011;6902.240753517109;3647.507586475845;3691.6308369163407;3695.3163664721574;3720.4233343831747;3724.2365315110337;1273.6433798536425;1275.7384736129072;1283.9165454245444;1286.228263042167;1323.9404226809816;489.82856000399573;524.7612336386935;545.1577313051346;550.2022068001414;579.9855003624943;12797.424115914806;12830.16603920742;13436.126651767603;13486.325683715775;13504;518.3235877180241;536.083297561167;550.796205403995;552.1662289942216;586.416515567005;6068.21113255274;6788.015737718003;6829.282925292872;7243.60160191184;7326.890096648554;571505;575449;590629;590873;596208;83874;85422.14577854142;85476;85980.57009715037;86125.56937215399;626.3360837512251;628.2743431336813;633.0851918977125;634.3719717479662;634.8285962790021;12062.51749930028;12170.634880953585;12186.817197741864;12211.633650990485;12253.264804111783;37981.430278546395;38014.42978355272;38157;38444;38973.805130974164;53385;53553;53845.19232211442;54698.453015469335;54830;7304.123505179413;7640.80897977555;7646.655900484603;7651.890475880855;7658.923410765821;12828.29444380568;13103;13167.144135631153;13178.341082946006;13352.399142038827;11802.52789888395;11834;11921.403929803648;12003;12112.273263604213;11519.769604607693;11522.942385288356;11594.420278986187;11686.18196726221;12171.02631789437;10413.635522756667;10588.523516441932;12043.337616431181;12072.275663460221;12164.148509604243;7575.621218939141;7609;7625.580593067434;7655.578943157957;7662.501937374052;11470.369129697612;11508.539658413905;11769.470373833026;11838.763224735629;11849.881501184878 02:51:01 INFO - 2016-01-12 02:51:01,542 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1567.2633862084815;1587.2301933562221;1799.5860951981047;1804.431604044725;1835.0916296433263;7227.9277207227915;7447.218042105582;8027.719029833957;8208.09710931797;8214.794630134242;7925.524468531878;7970.36237101032;8102.18978102191;8108;8132.26809587137;2410.3251089694913;2455.0548039004934;2455.4966231922504;2455.950880982379;2459.692538432696;2185.6830759539857;2230.3754948614414;2406.181898154627;2472.777450029506;2492.8130390220804;2450.742672019439;2467.592847180213;2476.1333533263396;2497.5379554782335;2501.5122051200024;2449.265220433863;2463.4334103156193;2478.0707234786955;2478.7521247875247;2479.90080396783;2483.2301986384277;2496.3135137837057;2503.7621425964503;2503.8372505787247;2504.9874750626313;8193.426460147792;8278.337732981347;8281.46792843326;8287.378446616527;8314.875276870851;5093.567046801027;5161.406438259573;5170.302009228743;5170.482951704837;5172.896542069175;3210.614726232867;3247.902562923116;3309.851056702454;3334;3344.0803778960735;1080.6541906589894;1086.576235268246;1099.901008909194;1102.9779404411868;1141.566204842163;11350.20548561601;11392.430378481125;11402.315861048364;11402.94298528502;11408.828867566994;4521.525239849834;4584.220682483986;4594.862154135381;4625.722456652545;4675.766211689402;9698.854517182248;9705.6603018894;9725.513724313756;9727.805443891224;9753.609855605839;5128.051310507568;5128.1794912813775;5130.538251557377;5132.153194722903;5134.05020071288;4662.836800711959;4694.413198350207;4706.058788242365;4706.764661766898;4735.147673418749;3504.123969007748;4034.515858097047;4049.6962727794944;4073.409355643415;4073.674106071507;1225.9754804903853;1291.3285091752236;1321.3129172830074;1328.4885319152197;1334.292824802856;1383.6125884752284;1430.9427622895175;1446.4142022480912;1451.252604908473;1457.234951650371;7885.053793544811;7932.484388514844;8137.79655508617;8141.22658347456;8193.13972032928;1204.7229137298295;1206.8974137198436;1214.9453274602663;1225.5587988324196;1236.2335352081766;6275.403836635513;6425.839354016187;6469;6503.739850406026;6516.804495865132;9903.25725570585;10028.799424011479;10055.849162262572;10060.195184385231;10062.69811905644;9590.472524011246;9920.4543750093;9964.50177491137;9975.600975961026;10002.749931251632;7834.4907581007055;7883.448158628956;7904.881426778603;7905.695560232497;7915.485493442908;7632.656530456254;7637.043499937679;7663.425243106678;7744.922550774646;7769.261920117579;8721.65113395457;8809.647614095364;8831.91168088311;8837.337199710177;8944.329175311748;8835.11648835137;8986.19124278818;9015.909840901508;9032.412893161923;9063.818723625622;7337.0828646419195;7422.591757453392;7438.702451901863;7439.107307123179;7445.627718614156;7379.446541509516;7533.886991695019;7771.145174030965;8012.118666946748;8065.83868322642;7122.821929451805;7157.1769246537315;7438.144613369315;7457.81355466096;7711.614419279126;7442.06974128234;7500.9249907500225;7543.283388078124;7579.54522728638;7612.581308028111;7324.121105467378;7606.543607383574;7631.3131818136635;7644.235576442311;7645.808854778675;6716.597004179765;6930.272321406382;6948.374646281859;6949.722011119498;6949.7567585134275;6946.4095551876635;7088.645567721696;7229.891551626835;7239.058922340273;7245.021922040457 02:51:01 INFO - 2016-01-12 02:51:01,543 DEBUG : BROWSER_OUTPUT: |4;prototype.html;355.1122194513715;357.05202686866363;358.6771905285243;363.313337791574;363.96178401267883;5957.731902064406;5994.580379373446;6014.609050411726;6029.397060293968;6085.056816193486;5861.882762344761;5953.523718102552;5970.582059255854;5992.0712289595185;5996.070609055603;4661.207594708908;4674.7195168289845;4679.064187162564;4679.204535229002;4682.7892744826395;4555.134524440346;4593.265077587586;4603.240465323218;4665.860024199263;4670.813167473297;4624.028953919664;4626.213543697562;4647.209974304376;4647.34937108805;4657.5575320344515;4596.172688915994;4612.3542704021465;4627.745473998929;4628.791704373311;4629.421322334708;4091.3862920561855;4102.651274641629;4103.4050062740735;4103.466549348595;4104.486939132608;5616.550675945915;5716.714164291769;5721.170430287627;5729.88540229193;5820.35976042635;5440.047991601454;5446.046941785172;5447.809326673548;5448.536874365684;5448.945510544883;6399.26408463028;6414.102025716404;6419.51853610981;6421.165248517709;6421.93578064218;4363.410939523187;4402.449693788277;4487.439070116236;4530.184566777979;4531.365608814768;6470.676466176672;6474.090143407706;6481.125048118491;6484.448821850148;6498.057781621638;4627.768611569408;4639.559241872017;4641.280601506704;4642.605378542794;4643.164230438486;4601;4690.695104818176;4694.295855621615;4697.13103075932;4727.952720472751;5406.8918621627345;5414.675119492843;5415.160650099241;5420.512153906088;5423.942331245369;6451;6453.419192272718;6454.774082907075;6459.644719540376;6460.3862633049785;1718.7250039993792;1755.1575243882753;1763.065575245122;1763.1713094845659;1764.3648286616808;1125.8086125358545;1133.625903451859;1138.971525711864;1174.9412529373496;1192.3263356203718;6166.321704612489;6209.161763161916;6216.036514340284;6222.875542489125;6224.439800417984;6634.601923884582;6638.336166383323;6640.601563906181;6644.534882558174;6645.800625981228;6039.8792024159275;6201.658908759971;6209.2548894133;6212.968935155386;6214.347493513206;3653.707703383723;3683.5027271318563;3684.797336146484;3685.1155722626377;3687.2809802088336;6734.696938637773;6736.494762892803;6736.730530778812;6741.730330786713;6744.696488657924;6659.6670166491485;6772.25505194438;6776.525643205027;6777.322267773183;6780.1524809398825;6733.494987875929;6734.057231987624;6734.56225345351;6739.326067393222;6743.1908171019795;6418.679066046772;6624.370684784938;6630.436412904957;6637.53537252407;6637.971114477264;6424;6451.838704032437;6480.060391243149;6525.314841941527;6534.281229064894;6654.434373078343;6670.032845237511;6694.698738556874;6696;6707.5304728668525;4601.28680054592;4665.5567721066445;4669.649776266861;4671.462781780003;4671.883202919818;6579.671016449254;6594;6628.27089020217;6630.90053649186;6734.831629209309;6393.488520918222;6395.360463953567;6398.424141827258;6473.708683109177;6476.4495017924;6247.375262473716;6441.420272175526;6444.355564443518;6449.6130232186215;6453.354664533509;6314.431701146918;6339.809805705836;6352.142460767737;6353.777617783356;6355.5551111421755;6463.353664633499;6467.126937863328;6473.676316184266;6477.125588045742;6478.838029049311;6160.969195153995;6248.312685604671;6315.936840631719;6322.557420980487;6332.176817013758 02:51:01 INFO - 2016-01-12 02:51:01,544 DEBUG : BROWSER_OUTPUT: |5;yui.html;891.6032365597309;899.3704406915161;901.4095767272431;917.7017469322471;918.8529835226365;14808.481703140393;15350.846491535081;15376.692466150671;15408.84591154088;15410.152441615706;13913.095648782823;13998.230097344642;14019.649508762286;14023.719525609507;14034.578962631113;770.1912991359079;779.8752199648058;896.7085697148436;902.9864552031725;925.694520808135;924.944503329799;925.6436272035247;930.2046750028729;931.4457897550947;933.1508327422048;964.7684555706614;972.1348000279756;972.7811242470458;972.8297547929119;973;804.6178065418909;818.4598165210962;953.1612181280464;961.0581630002566;968.3560432312504;713.3401603516726;715.1204019056589;715.3954908102697;715.6207210178613;717.3722992381666;13917.58247252584;14060.92969535156;14070.085444446078;14079.084859484203;14107.083039602496;13635.795463068062;13671.86328136716;13767.862321376759;13823.239721815296;13840.515581954684;10584.100351470135;10782.029617334478;10785.029347358775;11683.41582920859;12008.63974080779;3567.678908898212;3586.497890295361;3607.1703508193264;3607.567091948983;3618.276344731038;46022.53977460216;46069.30896036562;46720;46740.766296168644;47065;8468.534230617252;8574.056853746082;8603.827923441497;8672.219500245008;8674.436161649473;13994.510192143227;14007.649808754863;14038.649033774238;14041.5085472008;14048.08687435334;7291.963540182265;7314.926850731531;7365.2634736525915;7406.481546291816;7551.169371369145;4522.4573051234065;4647.674662773574;4649.3026046093355;4674.906501869944;4680.46174689915;3028.227801910499;3042.3154790172475;3068.2329417645587;3069.892553760608;3070.4933685942015;469.73459995102655;481.5232919409829;482.2428786804683;482.84548944337473;493.1837808427059;569.2059576890298;569.6212019007314;584.9385814489502;628.3465196195997;652.5073569455126;8765.956170219235;9320.813583728288;9464.574094165779;9636.614535418645;9749.415035097916;475.46272711835405;658.6180015590945;669.7421492725335;708.1679027143086;709.8048036789821;3963.425303331001;4177.791110444466;4210.5157906841105;4213.936790948138;4219.472565929259;64873.351266487676;65992.34007659862;66006.66996665082;66126.33873661202;66151;57477.71261143751;57812.71093644589;57935;58731.99999999915;59638;53220.73389633105;53266.7336663322;54067.45932540624;54643.45356546463;55033.1745023825;8343.41596088268;8496.235338819535;8583;8594.398392112493;8657.134286571292;39611.405828912015;40000.799995999834;40401.191976159724;40483.59516404916;40587.59412405838;49314.75342623264;51637;51703;51824.481755181965;52521;7675.5010924289745;7704.460687752028;7708.306252437307;7732.961335193288;7801.804954876174;12229.449674764835;12509.186902851285;12592.055595830532;12597.244165350108;13061.934690326487;10808.135349171891;10859.945700271765;11041.723956901142;11308.151888608223;11439.256448330832;11258.943705281421;11315.434228288717;11696.122790790554;11789.941050294694;11887.28676279426;10911.890881091087;11181;11366.886331136582;11367.772644547229;11700.47347869365;9941.453220072965;10390.376577405077;10512.211584131373;10746.516406761559;10846.728831779268;9313.39462934907;9595.376300540442;10280.588776449158;10573.207009474167;10589.364638121737 02:51:01 INFO - 2016-01-12 02:51:01,544 DEBUG : BROWSER_OUTPUT: __end_tp_report 02:51:01 INFO - 2016-01-12 02:51:01,544 DEBUG : BROWSER_OUTPUT: __start_cc_report 02:51:01 INFO - 2016-01-12 02:51:01,545 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,808 02:51:01 INFO - 2016-01-12 02:51:01,545 DEBUG : BROWSER_OUTPUT: __end_cc_report 02:51:01 INFO - 2016-01-12 02:51:01,545 DEBUG : BROWSER_OUTPUT: __startTimestamp1452595861533__endTimestamp 02:51:01 INFO - 2016-01-12 02:51:01,545 DEBUG : BROWSER_OUTPUT: 02:51:01 INFO - 2016-01-12 02:51:01,545 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 02:51:01 INFO - 2016-01-12 02:51:01,545 DEBUG : BROWSER_OUTPUT: Number of tests: 6 02:51:01 INFO - 2016-01-12 02:51:01,545 DEBUG : BROWSER_OUTPUT: 02:51:01 INFO - 2016-01-12 02:51:01,545 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5573.65 Median:5785.68 stddev:609.96 (10.5%) stddev-sans-first:585.13 02:51:01 INFO - 2016-01-12 02:51:01,545 DEBUG : BROWSER_OUTPUT: Values: 3298.3 3374.3 3395.6 3400.5 3409.7 5554.3 5571.2 5594.3 5598.9 5615.8 5592.1 5598.9 5607.7 5612.2 5622.1 4964.3 5111.7 5138.8 5191.1 5205.4 5132.9 5152.7 5154.9 5163.5 5164.7 4952.5 5095.4 5101.5 5159.6 5167.2 5183.6 5187.8 5241.9 5241.9 5244.7 5148.9 5184.2 5203.4 5225.5 5246.5 5244.8 5284.2 5429.6 5445.0 5468.2 5995.5 6007.3 6040.7 6045.5 6048.7 6065.8 6075.0 6084.1 6086.4 6086.8 5403.6 5484.6 5514.4 5608.5 5613.9 6054.2 6064.1 6075.6 6097.8 6111.8 5901.5 5932.7 5942.8 5956.8 5969.9 5842.4 5853.2 5978.0 5996.6 6030.5 5942.5 5986.4 5990.4 5997.6 6020.7 5986.9 6002.8 6003.8 6006.5 6015.8 4450.4 4462.5 4534.5 4547.6 4550.3 4118.6 4127.1 4143.9 4155.0 4155.8 5462.1 5729.8 5737.1 5739.8 5739.9 5904.8 5949.7 5958.3 5965.9 5973.5 5723.1 5734.8 5737.7 5743.0 5745.1 4968.7 5196.7 5256.3 5260.8 5262.9 6009.7 6103.5 6106.1 6113.4 6139.2 6048.5 6052.6 6059.0 6059.8 6061.7 5973.8 6070.4 6077.8 6159.5 6161.7 5913.8 5953.9 5956.7 5960.0 5975.9 6091.4 6092.2 6102.4 6105.4 6113.7 5676.6 5803.8 5838.3 5926.0 5983.5 5858.7 5872.4 5874.8 5881.0 5881.6 5769.1 5769.5 5774.0 5785.7 5790.2 5718.7 5731.0 5834.8 5860.0 5874.2 5784.2 5805.2 5814.5 5818.6 5824.1 02:51:01 INFO - 2016-01-12 02:51:01,545 DEBUG : BROWSER_OUTPUT: 02:51:01 INFO - 2016-01-12 02:51:01,545 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:54952.89 Median:35265.47 stddev:97468.85 (276.4%) stddev-sans-first:97656.84 02:51:01 INFO - 2016-01-12 02:51:01,545 DEBUG : BROWSER_OUTPUT: Values: 596.8 622.6 629.4 645.3 647.4 35368.9 43813.3 44265.3 44529.1 44568.0 49387.8 50021.5 50067.7 50132.2 50159.0 8324.0 8452.0 9086.8 9229.5 10724.0 10915.3 11009.1 11016.5 11030.9 11034.9 11083.4 11184.4 11205.3 11209.7 11210.0 10978.7 11073.2 11075.2 11082.8 11091.3 10854.2 11017.4 11038.6 11041.2 11043.5 43963.6 44333.6 44389.3 44487.0 44487.6 3418.3 3760.8 4275.4 4347.2 4361.4 33103.0 33404.8 33572.2 33692.7 33826.3 3649.7 3668.4 3686.4 3908.3 3992.3 318277.0 320289.0 320624.0 321694.0 322328.0 62662.7 63647.0 70811.6 71270.6 71286.3 72514.3 72663.3 73151.6 73242.3 73369.3 17540.9 17589.6 17636.9 17650.2 17692.0 14032.1 14087.4 14137.3 14137.9 14143.9 22416.4 22752.9 22768.9 22967.5 22988.2 8650.0 8845.0 8913.4 8968.7 9049.3 1039.3 1054.7 1065.7 1076.9 1138.0 29929.9 29978.6 30731.0 30839.7 31008.7 1072.9 1109.7 1139.4 1201.1 1210.1 13956.7 15047.9 15258.2 15661.6 16730.8 459260.0 465800.0 466652.0 467285.0 468352.0 59119.0 59566.4 59623.4 59717.7 59733.4 1331.8 1415.7 1441.8 1462.4 1480.5 30118.2 30292.7 30669.7 31081.7 31109.5 49757.8 50203.7 50253.0 50255.0 50282.2 300080.0 303150.0 303677.0 304596.0 305073.0 35268.3 35715.8 36371.6 36407.6 36450.3 46885.3 47558.3 47561.0 47572.0 48035.0 38520.4 38530.8 39566.2 39809.0 39851.6 38362.6 38710.6 39033.4 39171.6 39537.4 38256.0 38891.4 39705.8 39824.0 39835.0 32754.7 34893.5 34925.7 35262.6 35341.6 26484.6 26747.9 26898.6 26947.5 27147.5 02:51:01 INFO - 2016-01-12 02:51:01,546 DEBUG : BROWSER_OUTPUT: 02:51:01 INFO - 2016-01-12 02:51:01,546 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29553.64 Median:4768.30 stddev:95931.85 (2011.9%) stddev-sans-first:96175.97 02:51:01 INFO - 2016-01-12 02:51:01,547 DEBUG : BROWSER_OUTPUT: Values: 388.1 389.9 391.6 392.7 395.4 13784.5 14118.2 18341.4 21069.6 21075.3 13174.0 13600.2 13618.4 18871.7 19318.4 4854.3 4957.3 4959.2 4963.9 4970.1 4927.4 4945.6 4950.6 4953.0 4953.6 3900.0 4897.2 4908.1 4910.0 5111.5 5351.1 5381.7 5384.4 5395.7 5402.8 4537.4 4653.7 4678.6 4680.7 4682.3 15618.4 15777.4 15812.2 15894.8 15911.1 2125.8 2139.5 2171.6 2197.0 2251.5 11157.5 11246.2 11307.9 11396.6 11424.1 1809.2 1881.8 1935.9 1937.5 1944.1 65555.7 65837.7 66756.3 66939.3 67822.0 7709.8 7804.5 8028.5 8079.9 8084.4 9573.8 9605.9 9628.4 9632.2 9632.2 6475.4 6559.9 6571.9 6585.0 6601.7 6823.9 6825.1 6860.1 6901.2 6902.2 3647.5 3691.6 3695.3 3720.4 3724.2 1273.6 1275.7 1283.9 1286.2 1323.9 489.8 524.8 545.2 550.2 580.0 12797.4 12830.2 13436.1 13486.3 13504.0 518.3 536.1 550.8 552.2 586.4 6068.2 6788.0 6829.3 7243.6 7326.9 571505.0 575449.0 590629.0 590873.0 596208.0 83874.0 85422.1 85476.0 85980.6 86125.6 626.3 628.3 633.1 634.4 634.8 12062.5 12170.6 12186.8 12211.6 12253.3 37981.4 38014.4 38157.0 38444.0 38973.8 53385.0 53553.0 53845.2 54698.5 54830.0 7304.1 7640.8 7646.7 7651.9 7658.9 12828.3 13103.0 13167.1 13178.3 13352.4 11802.5 11834.0 11921.4 12003.0 12112.3 11519.8 11522.9 11594.4 11686.2 12171.0 10413.6 10588.5 12043.3 12072.3 12164.1 7575.6 7609.0 7625.6 7655.6 7662.5 11470.4 11508.5 11769.5 11838.8 11849.9 02:51:01 INFO - 2016-01-12 02:51:01,547 DEBUG : BROWSER_OUTPUT: 02:51:01 INFO - 2016-01-12 02:51:01,548 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5850.25 Median:6350.62 stddev:2971.57 (46.8%) stddev-sans-first:2962.47 02:51:01 INFO - 2016-01-12 02:51:01,548 DEBUG : BROWSER_OUTPUT: Values: 1567.3 1587.2 1799.6 1804.4 1835.1 7227.9 7447.2 8027.7 8208.1 8214.8 7925.5 7970.4 8102.2 8108.0 8132.3 2410.3 2455.1 2455.5 2456.0 2459.7 2185.7 2230.4 2406.2 2472.8 2492.8 2450.7 2467.6 2476.1 2497.5 2501.5 2449.3 2463.4 2478.1 2478.8 2479.9 2483.2 2496.3 2503.8 2503.8 2505.0 8193.4 8278.3 8281.5 8287.4 8314.9 5093.6 5161.4 5170.3 5170.5 5172.9 3210.6 3247.9 3309.9 3334.0 3344.1 1080.7 1086.6 1099.9 1103.0 1141.6 11350.2 11392.4 11402.3 11402.9 11408.8 4521.5 4584.2 4594.9 4625.7 4675.8 9698.9 9705.7 9725.5 9727.8 9753.6 5128.1 5128.2 5130.5 5132.2 5134.1 4662.8 4694.4 4706.1 4706.8 4735.1 3504.1 4034.5 4049.7 4073.4 4073.7 1226.0 1291.3 1321.3 1328.5 1334.3 1383.6 1430.9 1446.4 1451.3 1457.2 7885.1 7932.5 8137.8 8141.2 8193.1 1204.7 1206.9 1214.9 1225.6 1236.2 6275.4 6425.8 6469.0 6503.7 6516.8 9903.3 10028.8 10055.8 10060.2 10062.7 9590.5 9920.5 9964.5 9975.6 10002.7 7834.5 7883.4 7904.9 7905.7 7915.5 7632.7 7637.0 7663.4 7744.9 7769.3 8721.7 8809.6 8831.9 8837.3 8944.3 8835.1 8986.2 9015.9 9032.4 9063.8 7337.1 7422.6 7438.7 7439.1 7445.6 7379.4 7533.9 7771.1 8012.1 8065.8 7122.8 7157.2 7438.1 7457.8 7711.6 7442.1 7500.9 7543.3 7579.5 7612.6 7324.1 7606.5 7631.3 7644.2 7645.8 6716.6 6930.3 6948.4 6949.7 6949.8 6946.4 7088.6 7229.9 7239.1 7245.0 02:51:01 INFO - 2016-01-12 02:51:01,548 DEBUG : BROWSER_OUTPUT: 02:51:01 INFO - 2016-01-12 02:51:01,549 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5375.43 Median:6123.01 stddev:1578.68 (25.8%) stddev-sans-first:1537.48 02:51:01 INFO - 2016-01-12 02:51:01,549 DEBUG : BROWSER_OUTPUT: Values: 355.1 357.1 358.7 363.3 364.0 5957.7 5994.6 6014.6 6029.4 6085.1 5861.9 5953.5 5970.6 5992.1 5996.1 4661.2 4674.7 4679.1 4679.2 4682.8 4555.1 4593.3 4603.2 4665.9 4670.8 4624.0 4626.2 4647.2 4647.3 4657.6 4596.2 4612.4 4627.7 4628.8 4629.4 4091.4 4102.7 4103.4 4103.5 4104.5 5616.6 5716.7 5721.2 5729.9 5820.4 5440.0 5446.0 5447.8 5448.5 5448.9 6399.3 6414.1 6419.5 6421.2 6421.9 4363.4 4402.4 4487.4 4530.2 4531.4 6470.7 6474.1 6481.1 6484.4 6498.1 4627.8 4639.6 4641.3 4642.6 4643.2 4601.0 4690.7 4694.3 4697.1 4728.0 5406.9 5414.7 5415.2 5420.5 5423.9 6451.0 6453.4 6454.8 6459.6 6460.4 1718.7 1755.2 1763.1 1763.2 1764.4 1125.8 1133.6 1139.0 1174.9 1192.3 6166.3 6209.2 6216.0 6222.9 6224.4 6634.6 6638.3 6640.6 6644.5 6645.8 6039.9 6201.7 6209.3 6213.0 6214.3 3653.7 3683.5 3684.8 3685.1 3687.3 6734.7 6736.5 6736.7 6741.7 6744.7 6659.7 6772.3 6776.5 6777.3 6780.2 6733.5 6734.1 6734.6 6739.3 6743.2 6418.7 6624.4 6630.4 6637.5 6638.0 6424.0 6451.8 6480.1 6525.3 6534.3 6654.4 6670.0 6694.7 6696.0 6707.5 4601.3 4665.6 4669.6 4671.5 4671.9 6579.7 6594.0 6628.3 6630.9 6734.8 6393.5 6395.4 6398.4 6473.7 6476.4 6247.4 6441.4 6444.4 6449.6 6453.4 6314.4 6339.8 6352.1 6353.8 6355.6 6463.4 6467.1 6473.7 6477.1 6478.8 6161.0 6248.3 6315.9 6322.6 6332.2 02:51:01 INFO - 2016-01-12 02:51:01,549 DEBUG : BROWSER_OUTPUT: 02:51:01 INFO - 2016-01-12 02:51:01,549 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14717.17 Median:482.54 stddev:17996.11 (3729.4%) stddev-sans-first:18016.65 02:51:01 INFO - 2016-01-12 02:51:01,550 DEBUG : BROWSER_OUTPUT: Values: 891.6 899.4 901.4 917.7 918.9 14808.5 15350.8 15376.7 15408.8 15410.2 13913.1 13998.2 14019.6 14023.7 14034.6 770.2 779.9 896.7 903.0 925.7 924.9 925.6 930.2 931.4 933.2 964.8 972.1 972.8 972.8 973.0 804.6 818.5 953.2 961.1 968.4 713.3 715.1 715.4 715.6 717.4 13917.6 14060.9 14070.1 14079.1 14107.1 13635.8 13671.9 13767.9 13823.2 13840.5 10584.1 10782.0 10785.0 11683.4 12008.6 3567.7 3586.5 3607.2 3607.6 3618.3 46022.5 46069.3 46720.0 46740.8 47065.0 8468.5 8574.1 8603.8 8672.2 8674.4 13994.5 14007.6 14038.6 14041.5 14048.1 7292.0 7314.9 7365.3 7406.5 7551.2 4522.5 4647.7 4649.3 4674.9 4680.5 3028.2 3042.3 3068.2 3069.9 3070.5 469.7 481.5 482.2 482.8 493.2 569.2 569.6 584.9 628.3 652.5 8766.0 9320.8 9464.6 9636.6 9749.4 475.5 658.6 669.7 708.2 709.8 3963.4 4177.8 4210.5 4213.9 4219.5 64873.4 65992.3 66006.7 66126.3 66151.0 57477.7 57812.7 57935.0 58732.0 59638.0 53220.7 53266.7 54067.5 54643.5 55033.2 8343.4 8496.2 8583.0 8594.4 8657.1 39611.4 40000.8 40401.2 40483.6 40587.6 49314.8 51637.0 51703.0 51824.5 52521.0 7675.5 7704.5 7708.3 7733.0 7801.8 12229.4 12509.2 12592.1 12597.2 13061.9 10808.1 10859.9 11041.7 11308.2 11439.3 11258.9 11315.4 11696.1 11789.9 11887.3 10911.9 11181.0 11366.9 11367.8 11700.5 9941.5 10390.4 10512.2 10746.5 10846.7 9313.4 9595.4 10280.6 10573.2 10589.4 02:51:01 INFO - 2016-01-12 02:51:01,550 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 02:51:01 INFO - 2016-01-12 02:51:01,550 DEBUG : BROWSER_OUTPUT: 02:51:02 INFO - 2016-01-12 02:51:02,138 INFO : Browser exited with error code: 0 02:51:02 INFO - 2016-01-12 02:51:02,159 INFO : Completed test dromaeo_css (00:18:29) 02:51:02 INFO - 2016-01-12 02:51:02,159 INFO : Starting test kraken 02:51:02 INFO - 2016-01-12 02:51:02,159 DEBUG : operating with platform_type : linux_ 02:51:02 INFO - 2016-01-12 02:51:02,159 INFO : Initialising browser for kraken test... 02:51:02 INFO - 2016-01-12 02:51:02,165 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzSCdVs/profile http://localhost:52443/getInfo.html 02:51:05 INFO - 2016-01-12 02:51:05,881 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:51:05 INFO - 2016-01-12 02:51:05,882 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:51:05 INFO - 2016-01-12 02:51:05,882 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 02:51:05 INFO - 2016-01-12 02:51:05,882 DEBUG : BROWSER_OUTPUT: __metrics 02:51:05 INFO - 2016-01-12 02:51:05,998 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] 02:51:07 INFO - 2016-01-12 02:51:07,254 INFO : Browser initialized. 02:51:07 INFO - 2016-01-12 02:51:07,254 INFO : Running cycle 1/1 for kraken test... 02:51:07 INFO - 2016-01-12 02:51:07,255 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzSCdVs/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 02:51:18 INFO - 2016-01-12 02:51:18,901 DEBUG : BROWSER_OUTPUT: RSS: Main: 128569344 02:51:18 INFO - 2016-01-12 02:51:18,902 DEBUG : BROWSER_OUTPUT: 02:51:21 INFO - 2016-01-12 02:51:21,357 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52443/tests/kraken/driver.html?testName=audio-beat-detection) 02:51:21 INFO - 2016-01-12 02:51:21,918 DEBUG : BROWSER_OUTPUT: RSS: Main: 161669120 02:51:21 INFO - 2016-01-12 02:51:21,918 DEBUG : BROWSER_OUTPUT: 02:51:24 INFO - 2016-01-12 02:51:24,475 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52443/tests/kraken/driver.html?testName=audio-dft) 02:51:25 INFO - 2016-01-12 02:51:25,041 DEBUG : BROWSER_OUTPUT: RSS: Main: 159686656 02:51:25 INFO - 2016-01-12 02:51:25,041 DEBUG : BROWSER_OUTPUT: 02:51:27 INFO - 2016-01-12 02:51:27,827 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52443/tests/kraken/driver.html?testName=audio-fft) 02:51:28 INFO - 2016-01-12 02:51:28,389 DEBUG : BROWSER_OUTPUT: RSS: Main: 156426240 02:51:28 INFO - 2016-01-12 02:51:28,389 DEBUG : BROWSER_OUTPUT: 02:51:30 INFO - 2016-01-12 02:51:30,354 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52443/tests/kraken/driver.html?testName=audio-oscillator) 02:51:30 INFO - 2016-01-12 02:51:30,921 DEBUG : BROWSER_OUTPUT: RSS: Main: 157298688 02:51:30 INFO - 2016-01-12 02:51:30,921 DEBUG : BROWSER_OUTPUT: 02:51:33 INFO - 2016-01-12 02:51:33,060 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52443/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:51:33 INFO - 2016-01-12 02:51:33,622 DEBUG : BROWSER_OUTPUT: RSS: Main: 156426240 02:51:33 INFO - 2016-01-12 02:51:33,622 DEBUG : BROWSER_OUTPUT: 02:51:36 INFO - 2016-01-12 02:51:36,486 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52443/tests/kraken/driver.html?testName=imaging-darkroom) 02:51:37 INFO - 2016-01-12 02:51:37,059 DEBUG : BROWSER_OUTPUT: RSS: Main: 156229632 02:51:37 INFO - 2016-01-12 02:51:37,060 DEBUG : BROWSER_OUTPUT: 02:51:40 INFO - 2016-01-12 02:51:40,403 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52443/tests/kraken/driver.html?testName=imaging-desaturate) 02:51:40 INFO - 2016-01-12 02:51:40,969 DEBUG : BROWSER_OUTPUT: RSS: Main: 156495872 02:51:40 INFO - 2016-01-12 02:51:40,970 DEBUG : BROWSER_OUTPUT: 02:51:43 INFO - 2016-01-12 02:51:43,653 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52443/tests/kraken/driver.html?testName=json-parse-financial) 02:51:44 INFO - 2016-01-12 02:51:44,223 DEBUG : BROWSER_OUTPUT: RSS: Main: 155623424 02:51:44 INFO - 2016-01-12 02:51:44,223 DEBUG : BROWSER_OUTPUT: 02:51:45 INFO - 2016-01-12 02:51:45,704 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52443/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:51:46 INFO - 2016-01-12 02:51:46,265 DEBUG : BROWSER_OUTPUT: RSS: Main: 160456704 02:51:46 INFO - 2016-01-12 02:51:46,265 DEBUG : BROWSER_OUTPUT: 02:51:48 INFO - 2016-01-12 02:51:48,000 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52443/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:51:48 INFO - 2016-01-12 02:51:48,589 DEBUG : BROWSER_OUTPUT: RSS: Main: 160514048 02:51:48 INFO - 2016-01-12 02:51:48,589 DEBUG : BROWSER_OUTPUT: 02:51:50 INFO - 2016-01-12 02:51:50,554 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52443/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:51:51 INFO - 2016-01-12 02:51:51,122 DEBUG : BROWSER_OUTPUT: RSS: Main: 165588992 02:51:51 INFO - 2016-01-12 02:51:51,122 DEBUG : BROWSER_OUTPUT: 02:51:53 INFO - 2016-01-12 02:51:53,416 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52443/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:51:53 INFO - 2016-01-12 02:51:53,985 DEBUG : BROWSER_OUTPUT: RSS: Main: 168386560 02:51:53 INFO - 2016-01-12 02:51:53,985 DEBUG : BROWSER_OUTPUT: 02:51:56 INFO - 2016-01-12 02:51:56,407 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52443/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:51:56 INFO - 2016-01-12 02:51:56,989 DEBUG : BROWSER_OUTPUT: RSS: Main: 165662720 02:51:56 INFO - 2016-01-12 02:51:56,989 DEBUG : BROWSER_OUTPUT: 02:51:58 INFO - 2016-01-12 02:51:58,586 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52443/tests/kraken/driver.html?testName=ai-astar) 02:51:58 INFO - 2016-01-12 02:51:58,836 DEBUG : BROWSER_OUTPUT: RSS: Main: 226258944 02:51:58 INFO - 2016-01-12 02:51:58,837 DEBUG : BROWSER_OUTPUT: 02:51:58 INFO - 2016-01-12 02:51:58,840 DEBUG : BROWSER_OUTPUT: __start_tp_report 02:51:58 INFO - 2016-01-12 02:51:58,840 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 02:51:58 INFO - 2016-01-12 02:51:58,840 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 02:51:58 INFO - 2016-01-12 02:51:58,840 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 02:51:58 INFO - 2016-01-12 02:51:58,841 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;111;106;111;114;113;100;112;98;111 02:51:58 INFO - 2016-01-12 02:51:58,841 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;125;144;160;127;152;151;123;158;122 02:51:58 INFO - 2016-01-12 02:51:58,841 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;160;161;159;160;160;160;162;161;159 02:51:58 INFO - 2016-01-12 02:51:58,841 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;71;141;71;72;78;71;151;71 02:51:58 INFO - 2016-01-12 02:51:58,841 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;88;89;137;88;88;89;141;88;89;135 02:51:58 INFO - 2016-01-12 02:51:58,841 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;110;109;109;109;109;110;109;110 02:51:58 INFO - 2016-01-12 02:51:58,841 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;145;146;145;145;146;145;146;145;145 02:51:58 INFO - 2016-01-12 02:51:58,841 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;95;95;95;95;95;95;95 02:51:58 INFO - 2016-01-12 02:51:58,841 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;53;56;55;54;55;55;56;64;53;53 02:51:58 INFO - 2016-01-12 02:51:58,842 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;65;64;64;64;64;64;63;64;63;64 02:51:58 INFO - 2016-01-12 02:51:58,842 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;86;79;79;76;79;85;77;78;78 02:51:58 INFO - 2016-01-12 02:51:58,842 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;108;112;122;109;113;110;109;111;111;108 02:51:58 INFO - 2016-01-12 02:51:58,842 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;131;138;137;140;136;137;137;140;147;134 02:51:58 INFO - 2016-01-12 02:51:58,842 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;61;62;62;63;61;61;64;60;62 02:51:58 INFO - 2016-01-12 02:51:58,842 DEBUG : BROWSER_OUTPUT: __end_tp_report 02:51:58 INFO - 2016-01-12 02:51:58,842 DEBUG : BROWSER_OUTPUT: __start_cc_report 02:51:58 INFO - 2016-01-12 02:51:58,842 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,867 02:51:58 INFO - 2016-01-12 02:51:58,842 DEBUG : BROWSER_OUTPUT: __end_cc_report 02:51:58 INFO - 2016-01-12 02:51:58,842 DEBUG : BROWSER_OUTPUT: __startTimestamp1452595918838__endTimestamp 02:51:58 INFO - 2016-01-12 02:51:58,842 DEBUG : BROWSER_OUTPUT: 02:51:58 INFO - 2016-01-12 02:51:58,845 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 02:51:58 INFO - 2016-01-12 02:51:58,846 DEBUG : BROWSER_OUTPUT: Number of tests: 14 02:51:58 INFO - 2016-01-12 02:51:58,846 DEBUG : BROWSER_OUTPUT: 02:51:58 INFO - 2016-01-12 02:51:58,846 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.60 Median:111.50 stddev:5.50 (4.9%) stddev-sans-first:5.81 02:51:58 INFO - 2016-01-12 02:51:58,846 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 106.0 111.0 114.0 113.0 100.0 112.0 98.0 111.0 02:51:58 INFO - 2016-01-12 02:51:58,846 DEBUG : BROWSER_OUTPUT: 02:51:58 INFO - 2016-01-12 02:51:58,846 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.10 Median:150.00 stddev:15.21 (10.1%) stddev-sans-first:15.86 02:51:58 INFO - 2016-01-12 02:51:58,846 DEBUG : BROWSER_OUTPUT: Values: 149.0 125.0 144.0 160.0 127.0 152.0 151.0 123.0 158.0 122.0 02:51:58 INFO - 2016-01-12 02:51:58,846 DEBUG : BROWSER_OUTPUT: 02:51:58 INFO - 2016-01-12 02:51:58,846 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:1.07 (0.7%) stddev-sans-first:0.97 02:51:58 INFO - 2016-01-12 02:51:58,846 DEBUG : BROWSER_OUTPUT: Values: 162.0 160.0 161.0 159.0 160.0 160.0 160.0 162.0 161.0 159.0 02:51:58 INFO - 2016-01-12 02:51:58,846 DEBUG : BROWSER_OUTPUT: 02:51:58 INFO - 2016-01-12 02:51:58,846 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:86.80 Median:71.00 stddev:31.36 (44.2%) stddev-sans-first:32.74 02:51:58 INFO - 2016-01-12 02:51:58,846 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 71.0 141.0 71.0 72.0 78.0 71.0 151.0 71.0 02:51:58 INFO - 2016-01-12 02:51:58,846 DEBUG : BROWSER_OUTPUT: 02:51:58 INFO - 2016-01-12 02:51:58,847 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.20 Median:88.00 stddev:23.83 (27.1%) stddev-sans-first:24.63 02:51:58 INFO - 2016-01-12 02:51:58,847 DEBUG : BROWSER_OUTPUT: Values: 88.0 89.0 137.0 88.0 88.0 89.0 141.0 88.0 89.0 135.0 02:51:58 INFO - 2016-01-12 02:51:58,847 DEBUG : BROWSER_OUTPUT: 02:51:58 INFO - 2016-01-12 02:51:58,847 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 02:51:58 INFO - 2016-01-12 02:51:58,847 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 110.0 109.0 109.0 109.0 109.0 110.0 109.0 110.0 02:51:58 INFO - 2016-01-12 02:51:58,847 DEBUG : BROWSER_OUTPUT: 02:51:58 INFO - 2016-01-12 02:51:58,847 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 02:51:58 INFO - 2016-01-12 02:51:58,847 DEBUG : BROWSER_OUTPUT: Values: 146.0 145.0 146.0 145.0 145.0 146.0 145.0 146.0 145.0 145.0 02:51:58 INFO - 2016-01-12 02:51:58,847 DEBUG : BROWSER_OUTPUT: 02:51:58 INFO - 2016-01-12 02:51:58,847 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 02:51:58 INFO - 2016-01-12 02:51:58,847 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 02:51:58 INFO - 2016-01-12 02:51:58,847 DEBUG : BROWSER_OUTPUT: 02:51:58 INFO - 2016-01-12 02:51:58,847 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.00 stddev:3.24 (5.9%) stddev-sans-first:3.32 02:51:58 INFO - 2016-01-12 02:51:58,847 DEBUG : BROWSER_OUTPUT: Values: 53.0 56.0 55.0 54.0 55.0 55.0 56.0 64.0 53.0 53.0 02:51:58 INFO - 2016-01-12 02:51:58,847 DEBUG : BROWSER_OUTPUT: 02:51:58 INFO - 2016-01-12 02:51:58,847 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:64.00 stddev:0.57 (0.9%) stddev-sans-first:0.44 02:51:58 INFO - 2016-01-12 02:51:58,848 DEBUG : BROWSER_OUTPUT: Values: 65.0 64.0 64.0 64.0 64.0 64.0 63.0 64.0 63.0 64.0 02:51:58 INFO - 2016-01-12 02:51:58,848 DEBUG : BROWSER_OUTPUT: 02:51:58 INFO - 2016-01-12 02:51:58,848 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:79.00 stddev:3.37 (4.3%) stddev-sans-first:3.46 02:51:58 INFO - 2016-01-12 02:51:58,848 DEBUG : BROWSER_OUTPUT: Values: 77.0 86.0 79.0 79.0 76.0 79.0 85.0 77.0 78.0 78.0 02:51:58 INFO - 2016-01-12 02:51:58,848 DEBUG : BROWSER_OUTPUT: 02:51:58 INFO - 2016-01-12 02:51:58,848 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:111.30 Median:111.00 stddev:4.11 (3.7%) stddev-sans-first:4.18 02:51:58 INFO - 2016-01-12 02:51:58,848 DEBUG : BROWSER_OUTPUT: Values: 108.0 112.0 122.0 109.0 113.0 110.0 109.0 111.0 111.0 108.0 02:51:58 INFO - 2016-01-12 02:51:58,848 DEBUG : BROWSER_OUTPUT: 02:51:58 INFO - 2016-01-12 02:51:58,848 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.70 Median:137.50 stddev:4.22 (3.1%) stddev-sans-first:3.71 02:51:58 INFO - 2016-01-12 02:51:58,848 DEBUG : BROWSER_OUTPUT: Values: 131.0 138.0 137.0 140.0 136.0 137.0 137.0 140.0 147.0 134.0 02:51:58 INFO - 2016-01-12 02:51:58,848 DEBUG : BROWSER_OUTPUT: 02:51:58 INFO - 2016-01-12 02:51:58,848 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.00 stddev:1.52 (2.5%) stddev-sans-first:1.20 02:51:58 INFO - 2016-01-12 02:51:58,848 DEBUG : BROWSER_OUTPUT: Values: 65.0 61.0 62.0 62.0 63.0 61.0 61.0 64.0 60.0 62.0 02:51:58 INFO - 2016-01-12 02:51:58,848 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 02:51:58 INFO - 2016-01-12 02:51:58,848 DEBUG : BROWSER_OUTPUT: 02:51:59 INFO - 2016-01-12 02:51:59,522 INFO : Browser exited with error code: 0 02:51:59 INFO - 2016-01-12 02:51:59,576 INFO : Completed test kraken (00:00:57) 02:51:59 INFO - 2016-01-12 02:51:59,576 INFO : Starting test v8_7 02:51:59 INFO - 2016-01-12 02:51:59,576 DEBUG : operating with platform_type : linux_ 02:51:59 INFO - 2016-01-12 02:51:59,576 INFO : Initialising browser for v8_7 test... 02:51:59 INFO - 2016-01-12 02:51:59,583 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpAuKsH8/profile http://localhost:52443/getInfo.html 02:52:03 INFO - 2016-01-12 02:52:03,273 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:52:03 INFO - 2016-01-12 02:52:03,274 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:52:03 INFO - 2016-01-12 02:52:03,274 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 02:52:03 INFO - 2016-01-12 02:52:03,274 DEBUG : BROWSER_OUTPUT: __metrics 02:52:03 INFO - 2016-01-12 02:52:03,463 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] 02:52:04 INFO - 2016-01-12 02:52:04,646 INFO : Browser initialized. 02:52:04 INFO - 2016-01-12 02:52:04,646 INFO : Running cycle 1/1 for v8_7 test... 02:52:04 INFO - 2016-01-12 02:52:04,647 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpAuKsH8/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 02:52:16 INFO - 2016-01-12 02:52:16,185 DEBUG : BROWSER_OUTPUT: RSS: Main: 128389120 02:52:16 INFO - 2016-01-12 02:52:16,185 DEBUG : BROWSER_OUTPUT: 02:52:37 INFO - 2016-01-12 02:52:37,565 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52443/tests/v8_7/run.html (next: http://localhost:52443/tests/v8_7/run.html) 02:52:37 INFO - 2016-01-12 02:52:37,815 DEBUG : BROWSER_OUTPUT: RSS: Main: 418332672 02:52:37 INFO - 2016-01-12 02:52:37,815 DEBUG : BROWSER_OUTPUT: 02:52:37 INFO - 2016-01-12 02:52:37,816 DEBUG : BROWSER_OUTPUT: __start_tp_report 02:52:37 INFO - 2016-01-12 02:52:37,817 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 02:52:37 INFO - 2016-01-12 02:52:37,817 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 02:52:37 INFO - 2016-01-12 02:52:37,817 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 02:52:37 INFO - 2016-01-12 02:52:37,817 DEBUG : BROWSER_OUTPUT: |0;Richards;163.29196603527106 02:52:37 INFO - 2016-01-12 02:52:37,817 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.56114617090228 02:52:37 INFO - 2016-01-12 02:52:37,817 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.2690582959641 02:52:37 INFO - 2016-01-12 02:52:37,817 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5005 02:52:37 INFO - 2016-01-12 02:52:37,818 DEBUG : BROWSER_OUTPUT: |4;RayTrace;950.5703422053232 02:52:37 INFO - 2016-01-12 02:52:37,818 DEBUG : BROWSER_OUTPUT: |5;Earley;557.1030640668523 02:52:37 INFO - 2016-01-12 02:52:37,818 DEBUG : BROWSER_OUTPUT: |6;Boyer;10526.315789473685 02:52:37 INFO - 2016-01-12 02:52:37,818 DEBUG : BROWSER_OUTPUT: |7;RegExp;24190.47619047619 02:52:37 INFO - 2016-01-12 02:52:37,818 DEBUG : BROWSER_OUTPUT: |8;Splay;525.4860746190226 02:52:37 INFO - 2016-01-12 02:52:37,819 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4817.307692307692 02:52:37 INFO - 2016-01-12 02:52:37,819 DEBUG : BROWSER_OUTPUT: __end_tp_report 02:52:37 INFO - 2016-01-12 02:52:37,819 DEBUG : BROWSER_OUTPUT: __startTimestamp1452595957815__endTimestamp 02:52:37 INFO - 2016-01-12 02:52:37,819 DEBUG : BROWSER_OUTPUT: 02:52:37 INFO - 2016-01-12 02:52:37,820 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 02:52:37 INFO - 2016-01-12 02:52:37,820 DEBUG : BROWSER_OUTPUT: Number of tests: 10 02:52:37 INFO - 2016-01-12 02:52:37,820 DEBUG : BROWSER_OUTPUT: 02:52:37 INFO - 2016-01-12 02:52:37,820 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.29 Median:163.29 stddev:0.00 (0.0%) 02:52:37 INFO - 2016-01-12 02:52:37,820 DEBUG : BROWSER_OUTPUT: Values: 163.3 02:52:37 INFO - 2016-01-12 02:52:37,821 DEBUG : BROWSER_OUTPUT: 02:52:37 INFO - 2016-01-12 02:52:37,821 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.56 Median:170.56 stddev:0.00 (0.0%) 02:52:37 INFO - 2016-01-12 02:52:37,821 DEBUG : BROWSER_OUTPUT: Values: 170.6 02:52:37 INFO - 2016-01-12 02:52:37,821 DEBUG : BROWSER_OUTPUT: 02:52:37 INFO - 2016-01-12 02:52:37,821 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.27 Median:280.27 stddev:0.00 (0.0%) 02:52:37 INFO - 2016-01-12 02:52:37,822 DEBUG : BROWSER_OUTPUT: Values: 280.3 02:52:37 INFO - 2016-01-12 02:52:37,822 DEBUG : BROWSER_OUTPUT: 02:52:37 INFO - 2016-01-12 02:52:37,822 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5005.00 Median:5005.00 stddev:0.00 (0.0%) 02:52:37 INFO - 2016-01-12 02:52:37,822 DEBUG : BROWSER_OUTPUT: Values: 5005.0 02:52:37 INFO - 2016-01-12 02:52:37,822 DEBUG : BROWSER_OUTPUT: 02:52:37 INFO - 2016-01-12 02:52:37,822 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:950.57 Median:950.57 stddev:0.00 (0.0%) 02:52:37 INFO - 2016-01-12 02:52:37,823 DEBUG : BROWSER_OUTPUT: Values: 950.6 02:52:37 INFO - 2016-01-12 02:52:37,823 DEBUG : BROWSER_OUTPUT: 02:52:37 INFO - 2016-01-12 02:52:37,823 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:557.10 Median:557.10 stddev:0.00 (0.0%) 02:52:37 INFO - 2016-01-12 02:52:37,823 DEBUG : BROWSER_OUTPUT: Values: 557.1 02:52:37 INFO - 2016-01-12 02:52:37,823 DEBUG : BROWSER_OUTPUT: 02:52:37 INFO - 2016-01-12 02:52:37,823 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10526.32 Median:10526.32 stddev:0.00 (0.0%) 02:52:37 INFO - 2016-01-12 02:52:37,825 DEBUG : BROWSER_OUTPUT: Values: 10526.3 02:52:37 INFO - 2016-01-12 02:52:37,825 DEBUG : BROWSER_OUTPUT: 02:52:37 INFO - 2016-01-12 02:52:37,825 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24190.48 Median:24190.48 stddev:0.00 (0.0%) 02:52:37 INFO - 2016-01-12 02:52:37,825 DEBUG : BROWSER_OUTPUT: Values: 24190.5 02:52:37 INFO - 2016-01-12 02:52:37,826 DEBUG : BROWSER_OUTPUT: 02:52:37 INFO - 2016-01-12 02:52:37,826 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:525.49 Median:525.49 stddev:0.00 (0.0%) 02:52:37 INFO - 2016-01-12 02:52:37,826 DEBUG : BROWSER_OUTPUT: Values: 525.5 02:52:37 INFO - 2016-01-12 02:52:37,826 DEBUG : BROWSER_OUTPUT: 02:52:37 INFO - 2016-01-12 02:52:37,826 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4817.31 Median:4817.31 stddev:0.00 (0.0%) 02:52:37 INFO - 2016-01-12 02:52:37,827 DEBUG : BROWSER_OUTPUT: Values: 4817.3 02:52:37 INFO - 2016-01-12 02:52:37,827 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 02:52:37 INFO - 2016-01-12 02:52:37,827 DEBUG : BROWSER_OUTPUT: 02:52:38 INFO - 2016-01-12 02:52:38,486 INFO : Browser exited with error code: 0 02:52:38 INFO - 2016-01-12 02:52:38,503 INFO : Completed test v8_7 (00:00:38) 02:52:38 INFO - 2016-01-12 02:52:38,846 INFO : Completed test suite (00:20:06) 02:52:38 INFO - 2016-01-12 02:52:38,846 DEBUG : Working with test: dromaeo_css 02:52:38 INFO - 2016-01-12 02:52:38,847 DEBUG : Generating results file: dromaeo_css 02:52:38 INFO - 2016-01-12 02:52:38,848 DEBUG : Working with test: kraken 02:52:38 INFO - 2016-01-12 02:52:38,848 DEBUG : Generating results file: kraken 02:52:38 INFO - 2016-01-12 02:52:38,848 INFO : javascript benchmark 02:52:38 INFO - 2016-01-12 02:52:38,848 DEBUG : Working with test: v8_7 02:52:38 INFO - 2016-01-12 02:52:38,848 DEBUG : Generating results file: v8_7 02:52:38 INFO - 2016-01-12 02:52:38,849 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 02:52:38 INFO - 2016-01-12 02:52:38,893 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 02:52:38 INFO - 2016-01-12 02:52:38,893 DEBUG : process_Request line: dromaeo_css 7175.64 graph.html#tests=[[72,53,35]] 02:52:38 INFO - 2016-01-12 02:52:38,893 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 02:52:38 INFO - 2016-01-12 02:52:38,927 DEBUG : process_Request line: kraken 1459.70 graph.html#tests=[[232,53,35]] 02:52:38 INFO - 2016-01-12 02:52:38,927 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 02:52:38 INFO - 2016-01-12 02:52:38,956 DEBUG : process_Request line: v8_7 23273.58 graph.html#tests=[[230,53,35]] 02:52:38 INFO - 2016-01-12 02:52:38,957 INFO : javascript benchmark 02:52:39 INFO - 2016-01-12 02:52:38,961 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3298.2743796364794, 3374.2913988062505, 3395.575553055868, 3400.5409269748575, 3409.7272218222543, 5554.333479982402, 5571.247881535992, 5594.272744543214, 5598.888022239553, 5615.831525054255, 5592.105263157896, 5598.916016259759, 5607.719614019303, 5612.242347283115, 5622.072358060915, 4964.329815474901, 5111.744412779355, 5138.794448222066, 5191.091558977183, 5205.427402985688, 5132.8973420531565, 5152.665076770017, 5154.948450515484, 5163.457836927125, 5164.664296820713, 4952.479989601094, 5095.3885533736, 5101.489851014906, 5159.638825282232, 5167.224916262572, 5183.585313174937, 5187.844364669066, 5241.895162096775, 5241.895162096775, 5244.685318880879, 5148.871278218037, 5184.170532714786, 5203.427622961472, 5225.451327610622, 5246.475352464761, 5244.81643142492, 5284.154535274376, 5429.56563474921, 5445.047116754552, 5468.234447177399, 5995.52035837132, 6007.279126504804, 6040.728167232485, 6045.516358691294, 6048.727807248684, 6065.8180254592435, 6075.0, 6084.117802918597, 6086.421789929949, 6086.7565297388055, 5403.567714582824, 5484.561235101182, 5514.393416724157, 5608.523275521586, 5613.943860561342, 6054.182685337423, 6064.120702498201, 6075.563133806005, 6097.847553811191, 6111.816645500642, 5901.527877769769, 5932.665150341225, 5942.762289508371, 5956.821295361146, 5969.910451343233, 5842.357340692521, 5853.209816674695, 5978.043513037893, 5996.580239383201, 6030.4572588467245, 5942.524598032147, 5986.401359864066, 5990.430909063632, 5997.640141591519, 6020.69896505173, 5986.850328741729, 6002.789902353484, 6003.7898673546215, 6006.5495087868585, 6015.759369625166, 4450.443694538183, 4462.509123996358, 4534.501204867462, 4547.590716835501, 4550.294704320836, 4118.588141185917, 4127.133302006552, 4143.875683729493, 4155.044339801862, 4155.8337666493, 5462.098753705594, 5729.799457018984, 5737.053386191315, 5739.827805165852, 5739.913901291484, 5904.793332233351, 5949.672767997802, 5958.314793798769, 5965.850853728692, 5973.462388384979, 5723.141528770634, 5734.77060917567, 5737.741801618937, 5743.0, 5745.080787074048, 4968.701877887266, 5196.68819870809, 5256.2904007959205, 5260.763265653055, 5262.947370526323, 6009.699515024231, 6103.542234332443, 6106.095145186544, 6113.402113262514, 6139.171211886338, 6048.485878700184, 6052.636841789506, 6059.030555111161, 6059.787907423219, 6061.696915154136, 5973.790917317873, 6070.423309785563, 6077.817665470043, 6159.538034647329, 6161.661108638888, 5913.793017244376, 5953.910691339547, 5956.672383018976, 5960.0, 5975.850603734942, 6091.390860914051, 6092.238470191226, 6102.389761023862, 6105.419985101408, 6113.694315284129, 5676.602637815313, 5803.7968671096305, 5838.32859221195, 5925.970370148294, 5983.521318294439, 5858.736356863866, 5872.412758724094, 5874.823755287348, 5880.970595146997, 5881.617694849821, 5769.07694768835, 5769.53843692512, 5774.0, 5785.681787501728, 5790.1893734877995, 5718.685472299064, 5731.0, 5834.795782147605, 5860.0, 5874.206982057539, 5784.161296612052, 5805.245318108618, 5814.476697097281, 5818.592698511064, 5824.068149096124], "unit": "ms", "name": "dojo.html", "value": 5534.526514375124}, {"lowerIsBetter": false, "replicates": [596.826920193144, 622.6264241455127, 629.3549112160036, 645.3449748505271, 647.359114476668, 35368.938931832025, 43813.34279985799, 44265.33601995972, 44529.109417811625, 44568.0, 49387.753061234645, 50021.49978500214, 50067.749661251735, 50132.24801627969, 50159.0, 8324.042735085482, 8452.0, 9086.77283067922, 9229.492377919212, 10724.0, 10915.345079295268, 11009.064229540498, 11016.504257308441, 11030.944845275762, 11034.889651103467, 11083.390413527253, 11184.38485883276, 11205.271657342246, 11209.663710088711, 11210.0, 10978.725531861728, 11073.169512286611, 11075.16936229782, 11082.833757493685, 11091.279066860629, 10854.185936054828, 11017.394043327613, 11038.558457661684, 11041.171912106623, 11043.50912626793, 43963.56036439627, 44333.556664433265, 44389.33415998763, 44487.0, 44487.55512444866, 3418.3334249821296, 3760.7555508891833, 4275.4228179195725, 4347.174036932973, 4361.367601697736, 33103.0, 33404.832975835205, 33572.1606959828, 33692.6630733692, 33826.32347353039, 3649.7262705296944, 3668.4497325401403, 3686.391745362012, 3908.2965066288057, 3992.321305378093, 318277.0, 320289.0, 320624.0, 321694.0, 322328.0, 62662.686686566274, 63647.0, 70811.64594176997, 71270.64364678143, 71286.28713712901, 72514.27485725076, 72663.27336726565, 73151.63424182845, 73242.26757732354, 73369.26630733625, 17540.912295438695, 17589.560260993323, 17636.911815440842, 17650.205740741698, 17692.0, 14032.08791428554, 14087.43650253999, 14137.293135343398, 14137.85862141386, 14143.85856141446, 22416.43958901008, 22752.886235568716, 22768.886155569115, 22967.54064918692, 22988.195413160458, 8649.956750216208, 8845.0, 8913.420627659181, 8968.68609598661, 9049.276057915482, 1039.308859608352, 1054.715226888751, 1065.7229120428744, 1076.923076923077, 1138.0, 29929.850350748107, 29978.55032174519, 30731.0, 30839.691603083684, 31008.689913101032, 1072.871255449351, 1109.6892869996414, 1139.3961200563715, 1201.1111777284746, 1210.0984766349127, 13956.720865582834, 15047.924760376347, 15258.16080115582, 15661.608459788597, 16730.83269167317, 459260.0, 465800.0, 466652.0, 467285.0, 468352.0, 59119.0, 59566.40433595609, 59623.403765962656, 59717.701411492664, 59733.402665973656, 1331.8401791785047, 1415.6782876083232, 1441.80535627691, 1462.4223431744188, 1480.5262316058759, 30118.247043824078, 30292.69707302987, 30669.693303066684, 31081.68918310788, 31109.533357000117, 49757.75121124371, 50203.74898125632, 50253.0, 50255.0, 50282.24576631426, 300080.0, 303150.0, 303677.0, 304596.0, 305073.0, 35268.29463410769, 35715.82142089377, 36371.63628363788, 36407.63592364149, 36450.27099458049, 46885.260514938134, 47558.28662570134, 47561.0, 47572.0, 48035.0, 38520.42219366768, 38530.80734596309, 39566.20867582575, 39809.00477488086, 39851.60148398479, 38362.61637383702, 38710.612893870704, 39033.41449878311, 39171.608283916794, 39537.406938895365, 38256.0, 38891.41662875003, 39705.80147099246, 39824.0, 39835.0, 32754.67245327516, 34893.476597850546, 34925.65074349224, 35262.647373525935, 35341.64658353384, 26484.602730959436, 26747.86626066935, 26898.596521051808, 26947.461050778482, 27147.457050858477], "unit": "ms", "name": "ext.html", "value": 20905.97628243273}, {"lowerIsBetter": false, "replicates": [388.1441421665228, 389.87523992322457, 391.5751409720455, 392.72116797074074, 395.44242617908753, 13784.483706792245, 14118.223497707622, 18341.358052468167, 21069.578608427822, 21075.262365817198, 13174.0, 13600.183988960669, 13618.387172577233, 18871.716924246146, 19318.420447386565, 4854.320395144682, 4957.330760347343, 4959.156943319626, 4963.875903102416, 4970.080535100999, 4927.384076990376, 4945.579625731817, 4950.6287028472825, 4953.0341583391255, 4953.628477864157, 3900.0444891001744, 4897.191963326047, 4908.067467181224, 4909.975450122744, 5111.488851114877, 5351.063563876306, 5381.650192737459, 5384.3807962084275, 5395.7032363220005, 5402.756875940572, 4537.410136682243, 4653.697509661861, 4678.602318802906, 4680.7191568506, 4682.274247491644, 15618.375264989387, 15777.447789327432, 15812.209389530593, 15894.76157857633, 15911.124888131148, 2125.84567676577, 2139.465133716571, 2171.598254322955, 2197.04428573571, 2251.4821591034156, 11157.497912593872, 11246.212765106446, 11307.943460282728, 11396.60111896088, 11424.143189260838, 1809.185866360143, 1881.7741870975433, 1935.8741681790639, 1937.5156210947264, 1944.057132290838, 65555.67222163954, 65837.67081164611, 66756.3324366755, 66939.3306066938, 67822.0, 7709.768706938801, 7804.492707973964, 8028.518288902685, 8079.878801817978, 8084.434089613671, 9573.760655983517, 9605.855912161323, 9628.422294662343, 9632.229421646252, 9632.229421646392, 6475.384838440341, 6559.934400656028, 6571.868562628816, 6585.0, 6601.735930562723, 6823.93176068233, 6825.146856642919, 6860.142482189726, 6901.240863505011, 6902.240753517109, 3647.507586475845, 3691.6308369163407, 3695.3163664721574, 3720.4233343831747, 3724.2365315110337, 1273.6433798536425, 1275.7384736129072, 1283.9165454245444, 1286.228263042167, 1323.9404226809816, 489.82856000399573, 524.7612336386935, 545.1577313051346, 550.2022068001414, 579.9855003624943, 12797.424115914806, 12830.16603920742, 13436.126651767603, 13486.325683715775, 13504.0, 518.3235877180241, 536.083297561167, 550.796205403995, 552.1662289942216, 586.416515567005, 6068.21113255274, 6788.015737718003, 6829.282925292872, 7243.60160191184, 7326.890096648554, 571505.0, 575449.0, 590629.0, 590873.0, 596208.0, 83874.0, 85422.14577854142, 85476.0, 85980.57009715037, 86125.56937215399, 626.3360837512251, 628.2743431336813, 633.0851918977125, 634.3719717479662, 634.8285962790021, 12062.51749930028, 12170.634880953585, 12186.817197741864, 12211.633650990485, 12253.264804111783, 37981.430278546395, 38014.42978355272, 38157.0, 38444.0, 38973.805130974164, 53385.0, 53553.0, 53845.19232211442, 54698.453015469335, 54830.0, 7304.123505179413, 7640.80897977555, 7646.655900484603, 7651.890475880855, 7658.923410765821, 12828.29444380568, 13103.0, 13167.144135631153, 13178.341082946006, 13352.399142038827, 11802.52789888395, 11834.0, 11921.403929803648, 12003.0, 12112.273263604213, 11519.769604607693, 11522.942385288356, 11594.420278986187, 11686.18196726221, 12171.02631789437, 10413.635522756667, 10588.523516441932, 12043.337616431181, 12072.275663460221, 12164.148509604243, 7575.621218939141, 7609.0, 7625.580593067434, 7655.578943157957, 7662.501937374052, 11470.369129697612, 11508.539658413905, 11769.470373833026, 11838.763224735629, 11849.881501184878], "unit": "ms", "name": "jquery.html", "value": 7687.45919086111}, {"lowerIsBetter": false, "replicates": [1567.2633862084815, 1587.2301933562221, 1799.5860951981047, 1804.431604044725, 1835.0916296433263, 7227.9277207227915, 7447.218042105582, 8027.719029833957, 8208.09710931797, 8214.794630134242, 7925.524468531878, 7970.36237101032, 8102.18978102191, 8108.0, 8132.26809587137, 2410.3251089694913, 2455.0548039004934, 2455.4966231922504, 2455.950880982379, 2459.692538432696, 2185.6830759539857, 2230.3754948614414, 2406.181898154627, 2472.777450029506, 2492.8130390220804, 2450.742672019439, 2467.592847180213, 2476.1333533263396, 2497.5379554782335, 2501.5122051200024, 2449.265220433863, 2463.4334103156193, 2478.0707234786955, 2478.7521247875247, 2479.90080396783, 2483.2301986384277, 2496.3135137837057, 2503.7621425964503, 2503.8372505787247, 2504.9874750626313, 8193.426460147792, 8278.337732981347, 8281.46792843326, 8287.378446616527, 8314.875276870851, 5093.567046801027, 5161.406438259573, 5170.302009228743, 5170.482951704837, 5172.896542069175, 3210.614726232867, 3247.902562923116, 3309.851056702454, 3334.0, 3344.0803778960735, 1080.6541906589894, 1086.576235268246, 1099.901008909194, 1102.9779404411868, 1141.566204842163, 11350.20548561601, 11392.430378481125, 11402.315861048364, 11402.94298528502, 11408.828867566994, 4521.525239849834, 4584.220682483986, 4594.862154135381, 4625.722456652545, 4675.766211689402, 9698.854517182248, 9705.6603018894, 9725.513724313756, 9727.805443891224, 9753.609855605839, 5128.051310507568, 5128.1794912813775, 5130.538251557377, 5132.153194722903, 5134.05020071288, 4662.836800711959, 4694.413198350207, 4706.058788242365, 4706.764661766898, 4735.147673418749, 3504.123969007748, 4034.515858097047, 4049.6962727794944, 4073.409355643415, 4073.674106071507, 1225.9754804903853, 1291.3285091752236, 1321.3129172830074, 1328.4885319152197, 1334.292824802856, 1383.6125884752284, 1430.9427622895175, 1446.4142022480912, 1451.252604908473, 1457.234951650371, 7885.053793544811, 7932.484388514844, 8137.79655508617, 8141.22658347456, 8193.13972032928, 1204.7229137298295, 1206.8974137198436, 1214.9453274602663, 1225.5587988324196, 1236.2335352081766, 6275.403836635513, 6425.839354016187, 6469.0, 6503.739850406026, 6516.804495865132, 9903.25725570585, 10028.799424011479, 10055.849162262572, 10060.195184385231, 10062.69811905644, 9590.472524011246, 9920.4543750093, 9964.50177491137, 9975.600975961026, 10002.749931251632, 7834.4907581007055, 7883.448158628956, 7904.881426778603, 7905.695560232497, 7915.485493442908, 7632.656530456254, 7637.043499937679, 7663.425243106678, 7744.922550774646, 7769.261920117579, 8721.65113395457, 8809.647614095364, 8831.91168088311, 8837.337199710177, 8944.329175311748, 8835.11648835137, 8986.19124278818, 9015.909840901508, 9032.412893161923, 9063.818723625622, 7337.0828646419195, 7422.591757453392, 7438.702451901863, 7439.107307123179, 7445.627718614156, 7379.446541509516, 7533.886991695019, 7771.145174030965, 8012.118666946748, 8065.83868322642, 7122.821929451805, 7157.1769246537315, 7438.144613369315, 7457.81355466096, 7711.614419279126, 7442.06974128234, 7500.9249907500225, 7543.283388078124, 7579.54522728638, 7612.581308028111, 7324.121105467378, 7606.543607383574, 7631.3131818136635, 7644.235576442311, 7645.808854778675, 6716.597004179765, 6930.272321406382, 6948.374646281859, 6949.722011119498, 6949.7567585134275, 6946.4095551876635, 7088.645567721696, 7229.891551626835, 7239.058922340273, 7245.021922040457], "unit": "ms", "name": "mootools.html", "value": 4848.694632283935}, {"lowerIsBetter": false, "replicates": [355.1122194513715, 357.05202686866363, 358.6771905285243, 363.313337791574, 363.96178401267883, 5957.731902064406, 5994.580379373446, 6014.609050411726, 6029.397060293968, 6085.056816193486, 5861.882762344761, 5953.523718102552, 5970.582059255854, 5992.0712289595185, 5996.070609055603, 4661.207594708908, 4674.7195168289845, 4679.064187162564, 4679.204535229002, 4682.7892744826395, 4555.134524440346, 4593.265077587586, 4603.240465323218, 4665.860024199263, 4670.813167473297, 4624.028953919664, 4626.213543697562, 4647.209974304376, 4647.34937108805, 4657.5575320344515, 4596.172688915994, 4612.3542704021465, 4627.745473998929, 4628.791704373311, 4629.421322334708, 4091.3862920561855, 4102.651274641629, 4103.4050062740735, 4103.466549348595, 4104.486939132608, 5616.550675945915, 5716.714164291769, 5721.170430287627, 5729.88540229193, 5820.35976042635, 5440.047991601454, 5446.046941785172, 5447.809326673548, 5448.536874365684, 5448.945510544883, 6399.26408463028, 6414.102025716404, 6419.51853610981, 6421.165248517709, 6421.93578064218, 4363.410939523187, 4402.449693788277, 4487.439070116236, 4530.184566777979, 4531.365608814768, 6470.676466176672, 6474.090143407706, 6481.125048118491, 6484.448821850148, 6498.057781621638, 4627.768611569408, 4639.559241872017, 4641.280601506704, 4642.605378542794, 4643.164230438486, 4601.0, 4690.695104818176, 4694.295855621615, 4697.13103075932, 4727.952720472751, 5406.8918621627345, 5414.675119492843, 5415.160650099241, 5420.512153906088, 5423.942331245369, 6451.0, 6453.419192272718, 6454.774082907075, 6459.644719540376, 6460.3862633049785, 1718.7250039993792, 1755.1575243882753, 1763.065575245122, 1763.1713094845659, 1764.3648286616808, 1125.8086125358545, 1133.625903451859, 1138.971525711864, 1174.9412529373496, 1192.3263356203718, 6166.321704612489, 6209.161763161916, 6216.036514340284, 6222.875542489125, 6224.439800417984, 6634.601923884582, 6638.336166383323, 6640.601563906181, 6644.534882558174, 6645.800625981228, 6039.8792024159275, 6201.658908759971, 6209.2548894133, 6212.968935155386, 6214.347493513206, 3653.707703383723, 3683.5027271318563, 3684.797336146484, 3685.1155722626377, 3687.2809802088336, 6734.696938637773, 6736.494762892803, 6736.730530778812, 6741.730330786713, 6744.696488657924, 6659.6670166491485, 6772.25505194438, 6776.525643205027, 6777.322267773183, 6780.1524809398825, 6733.494987875929, 6734.057231987624, 6734.56225345351, 6739.326067393222, 6743.1908171019795, 6418.679066046772, 6624.370684784938, 6630.436412904957, 6637.53537252407, 6637.971114477264, 6424.0, 6451.838704032437, 6480.060391243149, 6525.314841941527, 6534.281229064894, 6654.434373078343, 6670.032845237511, 6694.698738556874, 6696.0, 6707.5304728668525, 4601.28680054592, 4665.5567721066445, 4669.649776266861, 4671.462781780003, 4671.883202919818, 6579.671016449254, 6594.0, 6628.27089020217, 6630.90053649186, 6734.831629209309, 6393.488520918222, 6395.360463953567, 6398.424141827258, 6473.708683109177, 6476.4495017924, 6247.375262473716, 6441.420272175526, 6444.355564443518, 6449.6130232186215, 6453.354664533509, 6314.431701146918, 6339.809805705836, 6352.142460767737, 6353.777617783356, 6355.5551111421755, 6463.353664633499, 6467.126937863328, 6473.676316184266, 6477.125588045742, 6478.838029049311, 6160.969195153995, 6248.312685604671, 6315.936840631719, 6322.557420980487, 6332.176817013758], "unit": "ms", "name": "prototype.html", "value": 4874.983876853467}, {"lowerIsBetter": false, "replicates": [891.6032365597309, 899.3704406915161, 901.4095767272431, 917.7017469322471, 918.8529835226365, 14808.481703140393, 15350.846491535081, 15376.692466150671, 15408.84591154088, 15410.152441615706, 13913.095648782823, 13998.230097344642, 14019.649508762286, 14023.719525609507, 14034.578962631113, 770.1912991359079, 779.8752199648058, 896.7085697148436, 902.9864552031725, 925.694520808135, 924.944503329799, 925.6436272035247, 930.2046750028729, 931.4457897550947, 933.1508327422048, 964.7684555706614, 972.1348000279756, 972.7811242470458, 972.8297547929119, 973.0, 804.6178065418909, 818.4598165210962, 953.1612181280464, 961.0581630002566, 968.3560432312504, 713.3401603516726, 715.1204019056589, 715.3954908102697, 715.6207210178613, 717.3722992381666, 13917.58247252584, 14060.92969535156, 14070.085444446078, 14079.084859484203, 14107.083039602496, 13635.795463068062, 13671.86328136716, 13767.862321376759, 13823.239721815296, 13840.515581954684, 10584.100351470135, 10782.029617334478, 10785.029347358775, 11683.41582920859, 12008.63974080779, 3567.678908898212, 3586.497890295361, 3607.1703508193264, 3607.567091948983, 3618.276344731038, 46022.53977460216, 46069.30896036562, 46720.0, 46740.766296168644, 47065.0, 8468.534230617252, 8574.056853746082, 8603.827923441497, 8672.219500245008, 8674.436161649473, 13994.510192143227, 14007.649808754863, 14038.649033774238, 14041.5085472008, 14048.08687435334, 7291.963540182265, 7314.926850731531, 7365.2634736525915, 7406.481546291816, 7551.169371369145, 4522.4573051234065, 4647.674662773574, 4649.3026046093355, 4674.906501869944, 4680.46174689915, 3028.227801910499, 3042.3154790172475, 3068.2329417645587, 3069.892553760608, 3070.4933685942015, 469.73459995102655, 481.5232919409829, 482.2428786804683, 482.84548944337473, 493.1837808427059, 569.2059576890298, 569.6212019007314, 584.9385814489502, 628.3465196195997, 652.5073569455126, 8765.956170219235, 9320.813583728288, 9464.574094165779, 9636.614535418645, 9749.415035097916, 475.46272711835405, 658.6180015590945, 669.7421492725335, 708.1679027143086, 709.8048036789821, 3963.425303331001, 4177.791110444466, 4210.5157906841105, 4213.936790948138, 4219.472565929259, 64873.351266487676, 65992.34007659862, 66006.66996665082, 66126.33873661202, 66151.0, 57477.71261143751, 57812.71093644589, 57935.0, 58731.99999999915, 59638.0, 53220.73389633105, 53266.7336663322, 54067.45932540624, 54643.45356546463, 55033.1745023825, 8343.41596088268, 8496.235338819535, 8583.0, 8594.398392112493, 8657.134286571292, 39611.405828912015, 40000.799995999834, 40401.191976159724, 40483.59516404916, 40587.59412405838, 49314.75342623264, 51637.0, 51703.0, 51824.481755181965, 52521.0, 7675.5010924289745, 7704.460687752028, 7708.306252437307, 7732.961335193288, 7801.804954876174, 12229.449674764835, 12509.186902851285, 12592.055595830532, 12597.244165350108, 13061.934690326487, 10808.135349171891, 10859.945700271765, 11041.723956901142, 11308.151888608223, 11439.256448330832, 11258.943705281421, 11315.434228288717, 11696.122790790554, 11789.941050294694, 11887.28676279426, 10911.890881091087, 11181.0, 11366.886331136582, 11367.772644547229, 11700.47347869365, 9941.453220072965, 10390.376577405077, 10512.211584131373, 10746.516406761559, 10846.728831779268, 9313.39462934907, 9595.376300540442, 10280.588776449158, 10573.207009474167, 10589.364638121737], "unit": "ms", "name": "yui.html", "value": 6492.222690885009}], "name": "dromaeo_css", "value": 7175.638510640608}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 111.0, 106.0, 111.0, 114.0, 113.0, 100.0, 112.0, 98.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 108.6}, {"lowerIsBetter": true, "replicates": [149.0, 125.0, 144.0, 160.0, 127.0, 152.0, 151.0, 123.0, 158.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 141.1}, {"lowerIsBetter": true, "replicates": [162.0, 160.0, 161.0, 159.0, 160.0, 160.0, 160.0, 162.0, 161.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 160.4}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 71.0, 141.0, 71.0, 72.0, 78.0, 71.0, 151.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 86.8}, {"lowerIsBetter": true, "replicates": [88.0, 89.0, 137.0, 88.0, 88.0, 89.0, 141.0, 88.0, 89.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 103.2}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.1}, {"lowerIsBetter": true, "replicates": [53.0, 56.0, 55.0, 54.0, 55.0, 55.0, 56.0, 64.0, 53.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 55.4}, {"lowerIsBetter": true, "replicates": [65.0, 64.0, 64.0, 64.0, 64.0, 64.0, 63.0, 64.0, 63.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.9}, {"lowerIsBetter": true, "replicates": [77.0, 86.0, 79.0, 79.0, 76.0, 79.0, 85.0, 77.0, 78.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.4}, {"lowerIsBetter": true, "replicates": [108.0, 112.0, 122.0, 109.0, 113.0, 110.0, 109.0, 111.0, 111.0, 108.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 111.3}, {"lowerIsBetter": true, "replicates": [131.0, 138.0, 137.0, 140.0, 136.0, 137.0, 137.0, 140.0, 147.0, 134.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 137.7}, {"lowerIsBetter": true, "replicates": [65.0, 61.0, 62.0, 62.0, 63.0, 61.0, 61.0, 64.0, 60.0, 62.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 62.1}], "name": "kraken", "value": 1459.7}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.29196603527106], "unit": "score", "name": "Richards", "value": 216.18944800000006}, {"lowerIsBetter": false, "replicates": [170.56114617090228], "unit": "score", "name": "DeltaBlue", "value": 387.649834}, {"lowerIsBetter": false, "replicates": [280.2690582959641], "unit": "score", "name": "Encrypt", "value": 949.7338080000005}, {"lowerIsBetter": false, "replicates": [5005.0], "unit": "score", "name": "Decrypt", "value": 53.18301698301701}, {"lowerIsBetter": false, "replicates": [950.5703422053232], "unit": "score", "name": "RayTrace", "value": 778.4684279999997}, {"lowerIsBetter": false, "replicates": [557.1030640668523], "unit": "score", "name": "Earley", "value": 1196.3010849999996}, {"lowerIsBetter": false, "replicates": [10526.315789473685], "unit": "score", "name": "Boyer", "value": 63.313984999999995}, {"lowerIsBetter": false, "replicates": [24190.47619047619], "unit": "score", "name": "RegExp", "value": 37.65882874015749}, {"lowerIsBetter": false, "replicates": [525.4860746190226], "unit": "score", "name": "Splay", "value": 155.077373}, {"lowerIsBetter": false, "replicates": [4817.307692307692], "unit": "score", "name": "NavierStokes", "value": 308.055888223553}], "name": "v8_7", "value": 23273.579762262518}]} 02:52:39 INFO - RETURN: dromaeo_css: 7175.64 02:52:39 INFO - RETURN: kraken: 1459.70 02:52:39 INFO - RETURN: v8_7: 23273.58 02:52:39 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23273.58"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1459.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7175.64"}}} 02:52:39 INFO - Return code: 0 02:52:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 02:52:39 INFO - # TBPL SUCCESS # 02:52:39 INFO - Running post-action listener: _resource_record_post_action 02:52:39 INFO - Running post-run listener: _resource_record_post_run 02:52:40 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 315392; Write bytes: 275689472; Read time: 52; Write time: 162772 02:52:40 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 13598720; Read time: 0; Write time: 1500 02:52:40 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 315392; Write bytes: 262090752; Read time: 52; Write time: 161272 02:52:40 INFO - Running post-run listener: _upload_blobber_files 02:52:40 INFO - Blob upload gear active. 02:52:40 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 02:52:40 INFO - Copying logs to upload dir... 02:52:40 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1272.638545 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-01-12 02:52:39.130069) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-12 02:52:39.133667) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009889 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452586492/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 02:52:39.185213) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 02:52:39.185534) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1452594195.246507-104026725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004593 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 02:52:39.234843) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-12 02:52:39.235216) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-12 02:52:39.235511) ========= ========= Total master_lag: 0.25 =========