builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-008 starttime: 1450267786.61 results: success (0) buildid: 20151214205110 builduid: 489f3942ee5c444ea53c5ff4fc03e011 revision: d1a4b331d80b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.606919) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.607373) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.607655) ========= 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=7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006456 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.649857) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.650206) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.677580) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.677971) ========= 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=7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004645 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.734584) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.734869) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.735259) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.735543) ========= 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=7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-16 04:09:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2015-12-16 04:09:47 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.173274 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.928888) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.932220) ========= 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=7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017757 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.985987) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.986354) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d1a4b331d80b --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 d1a4b331d80b --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=7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-16 04:09:47,208 Setting DEBUG logging. 2015-12-16 04:09:47,208 attempt 1/10 2015-12-16 04:09:47,208 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d1a4b331d80b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-16 04:09:47,689 unpacking tar archive at: mozilla-beta-d1a4b331d80b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.793312 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:47.791866) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:47.792148) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:47.800472) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:47.800720) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:47.801137) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2015-12-16 04:09:47.801421) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --system-bits 64 --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', '--system-bits', '64', '--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=7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:09:48 INFO - MultiFileLogger online at 20151216 04:09:48 in /builds/slave/test-pgo 04:09:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 04:09:48 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 04:09:48 INFO - {'append_to_log': False, 04:09:48 INFO - 'base_work_dir': '/builds/slave/test-pgo', 04:09:48 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:09:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 04:09:48 INFO - 'branch': 'Mozilla-Beta', 04:09:48 INFO - 'buildbot_json_path': 'buildprops.json', 04:09:48 INFO - 'config_files': ('talos/linux_config.py',), 04:09:48 INFO - 'default_actions': ('clobber', 04:09:48 INFO - 'read-buildbot-config', 04:09:48 INFO - 'download-and-extract', 04:09:48 INFO - 'populate-webroot', 04:09:48 INFO - 'create-virtualenv', 04:09:48 INFO - 'install', 04:09:48 INFO - 'run-tests'), 04:09:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:09:48 INFO - 'download_minidump_stackwalk': True, 04:09:48 INFO - 'download_symbols': 'ondemand', 04:09:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:09:48 INFO - 'tooltool.py': '/tools/tooltool.py', 04:09:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:09:48 INFO - '/tools/misc-python/virtualenv.py')}, 04:09:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:09:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:09:48 INFO - 'installer_path': 'installer.exe', 04:09:48 INFO - 'log_level': 'info', 04:09:48 INFO - 'log_name': 'talos', 04:09:48 INFO - 'log_to_console': True, 04:09:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:09:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:09:48 INFO - 'opt_config_files': (), 04:09:48 INFO - 'pip_index': False, 04:09:48 INFO - 'sps_profile': False, 04:09:48 INFO - 'sps_profile_interval': 0, 04:09:48 INFO - 'suite': 'dromaeojs', 04:09:48 INFO - 'system_bits': '64', 04:09:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:09:48 INFO - 'title': 'talos-linux64-ix-008', 04:09:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:09:48 INFO - 'use_talos_json': True, 04:09:48 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 04:09:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:09:48 INFO - 'work_dir': 'build'} 04:09:48 INFO - ##### 04:09:48 INFO - ##### Running clobber step. 04:09:48 INFO - ##### 04:09:48 INFO - Running pre-action listener: _resource_record_pre_action 04:09:48 INFO - Running main action method: clobber 04:09:48 INFO - rmtree: /builds/slave/test-pgo/build 04:09:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 04:09:48 INFO - Running post-action listener: _resource_record_post_action 04:09:48 INFO - ##### 04:09:48 INFO - ##### Running read-buildbot-config step. 04:09:48 INFO - ##### 04:09:48 INFO - Running pre-action listener: _resource_record_pre_action 04:09:48 INFO - Running main action method: read_buildbot_config 04:09:48 INFO - Using buildbot properties: 04:09:48 INFO - { 04:09:48 INFO - "properties": { 04:09:48 INFO - "buildnumber": 74, 04:09:48 INFO - "product": "firefox", 04:09:48 INFO - "script_repo_revision": "production", 04:09:48 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 04:09:48 INFO - "repository": "", 04:09:48 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 04:09:48 INFO - "buildid": "20151214205110", 04:09:48 INFO - "slavename": "talos-linux64-ix-008", 04:09:48 INFO - "pgo_build": "True", 04:09:48 INFO - "basedir": "/builds/slave/test-pgo", 04:09:48 INFO - "project": "", 04:09:48 INFO - "platform": "ubuntu64_hw", 04:09:48 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:09:48 INFO - "slavebuilddir": "test", 04:09:48 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 04:09:48 INFO - "branch": "mozilla-beta", 04:09:48 INFO - "repo_path": "releases/mozilla-beta", 04:09:48 INFO - "stage_platform": "linux64-pgo", 04:09:48 INFO - "builduid": "489f3942ee5c444ea53c5ff4fc03e011", 04:09:48 INFO - "revision": "d1a4b331d80b" 04:09:48 INFO - }, 04:09:48 INFO - "sourcestamp": { 04:09:48 INFO - "repository": "", 04:09:48 INFO - "hasPatch": false, 04:09:48 INFO - "project": "", 04:09:48 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 04:09:48 INFO - "changes": [ 04:09:48 INFO - { 04:09:48 INFO - "category": null, 04:09:48 INFO - "files": [ 04:09:48 INFO - { 04:09:48 INFO - "url": null, 04:09:48 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2" 04:09:48 INFO - } 04:09:48 INFO - ], 04:09:48 INFO - "repository": "", 04:09:48 INFO - "rev": "d1a4b331d80b", 04:09:48 INFO - "who": "sendchange", 04:09:48 INFO - "when": 1450163443, 04:09:48 INFO - "number": 7361158, 04:09:48 INFO - "comments": "Bug 1232466 - Hack around use of re.sub(..., flags=). r=me a=bustage", 04:09:48 INFO - "project": "", 04:09:48 INFO - "at": "Mon 14 Dec 2015 23:10:43", 04:09:48 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 04:09:48 INFO - "revlink": "", 04:09:48 INFO - "properties": [ 04:09:48 INFO - [ 04:09:48 INFO - "buildid", 04:09:48 INFO - "20151214205110", 04:09:48 INFO - "Change" 04:09:48 INFO - ], 04:09:48 INFO - [ 04:09:48 INFO - "builduid", 04:09:48 INFO - "489f3942ee5c444ea53c5ff4fc03e011", 04:09:48 INFO - "Change" 04:09:48 INFO - ], 04:09:48 INFO - [ 04:09:48 INFO - "pgo_build", 04:09:48 INFO - "True", 04:09:48 INFO - "Change" 04:09:48 INFO - ] 04:09:48 INFO - ], 04:09:48 INFO - "revision": "d1a4b331d80b" 04:09:48 INFO - } 04:09:48 INFO - ], 04:09:48 INFO - "revision": "d1a4b331d80b" 04:09:48 INFO - } 04:09:48 INFO - } 04:09:48 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2. 04:09:48 INFO - Running post-action listener: _resource_record_post_action 04:09:48 INFO - ##### 04:09:48 INFO - ##### Running download-and-extract step. 04:09:48 INFO - ##### 04:09:48 INFO - Running pre-action listener: _resource_record_pre_action 04:09:48 INFO - Running main action method: download_and_extract 04:09:48 INFO - mkdir: /builds/slave/test-pgo/build/tests 04:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:48 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/test_packages.json 04:09:48 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 04:09:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 04:09:48 INFO - Downloaded 1270 bytes. 04:09:48 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 04:09:48 INFO - Using the following test package requirements: 04:09:48 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 04:09:48 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:09:48 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 04:09:48 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:09:48 INFO - u'jsshell-linux-x86_64.zip'], 04:09:48 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:09:48 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 04:09:48 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 04:09:48 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:09:48 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 04:09:48 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:09:48 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 04:09:48 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:09:48 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 04:09:48 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 04:09:48 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:09:48 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 04:09:48 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:48 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.common.tests.zip 04:09:48 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/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 04:09:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/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 04:09:58 INFO - Downloaded 22571596 bytes. 04:09:58 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 04:09:58 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 04:09:58 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 04:09:59 INFO - Return code: 0 04:09:59 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 04:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:59 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.common.tests.zip 04:09:59 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/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 04:09:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/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 04:09:59 INFO - Downloaded 22571596 bytes. 04:09:59 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 04:09:59 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 04:09:59 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 04:10:00 INFO - Return code: 0 04:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:00 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 04:10:00 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/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 04:10:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/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 04:10:04 INFO - Downloaded 11131970 bytes. 04:10:04 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 04:10:04 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 04:10:04 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 04:10:04 INFO - Return code: 0 04:10:04 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')]} 04:10:04 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2 04:10:04 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/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 04:10:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/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 04:10:13 INFO - Downloaded 56638018 bytes. 04:10:13 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2 04:10:13 INFO - mkdir: /builds/slave/test-pgo/properties 04:10:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 04:10:13 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 04:10:13 INFO - Contents: 04:10:13 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2 04:10:13 INFO - Running post-action listener: _resource_record_post_action 04:10:13 INFO - Running post-action listener: set_extra_try_arguments 04:10:13 INFO - ##### 04:10:13 INFO - ##### Running populate-webroot step. 04:10:13 INFO - ##### 04:10:13 INFO - Running pre-action listener: _resource_record_pre_action 04:10:13 INFO - Running main action method: populate_webroot 04:10:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:10:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:10:13 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 04:10:13 INFO - '../fennec_ids.txt'], 04:10:13 INFO - 'tests': ['tcheck2']}, 04:10:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:10:13 INFO - '--tppagecycles', 04:10:13 INFO - '7'], 04:10:13 INFO - 'tests': ['tsvgm']}}, 04:10:13 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:10:13 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:10:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:10:13 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:10:13 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:10:13 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:10:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:10:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:13 INFO - 'talos_options': ['--e10s'], 04:10:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:10:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:13 INFO - 'tests': ['damp', 'tps']}, 04:10:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:13 INFO - 'talos_options': ['--e10s'], 04:10:13 INFO - 'tests': ['damp', 'tps']}, 04:10:13 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:10:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:10:13 INFO - 'other': {'tests': ['a11yr', 04:10:13 INFO - 'ts_paint', 04:10:13 INFO - 'tpaint', 04:10:13 INFO - 'sessionrestore', 04:10:13 INFO - 'sessionrestore_no_auto_restore']}, 04:10:13 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:10:13 INFO - 'tests': ['a11yr', 04:10:13 INFO - 'ts_paint', 04:10:13 INFO - 'tpaint', 04:10:13 INFO - 'sessionrestore', 04:10:13 INFO - 'sessionrestore_no_auto_restore']}, 04:10:13 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:10:13 INFO - 'tests': ['a11yr', 04:10:13 INFO - 'ts_paint', 04:10:13 INFO - 'tpaint', 04:10:13 INFO - 'sessionrestore', 04:10:13 INFO - 'sessionrestore_no_auto_restore']}, 04:10:13 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:10:13 INFO - 'tests': ['a11yr', 04:10:13 INFO - 'ts_paint', 04:10:13 INFO - 'tpaint', 04:10:13 INFO - 'sessionrestore', 04:10:13 INFO - 'sessionrestore_no_auto_restore']}, 04:10:13 INFO - 'other_l64': {'tests': ['a11yr', 04:10:13 INFO - 'ts_paint', 04:10:13 INFO - 'tpaint', 04:10:13 INFO - 'sessionrestore', 04:10:13 INFO - 'sessionrestore_no_auto_restore']}, 04:10:13 INFO - 'other_nol64': {'tests': ['a11yr', 04:10:13 INFO - 'ts_paint', 04:10:13 INFO - 'tpaint', 04:10:13 INFO - 'sessionrestore', 04:10:13 INFO - 'sessionrestore_no_auto_restore']}, 04:10:13 INFO - 'svgr': {'tests': ['tsvgx', 04:10:13 INFO - 'tsvgr_opacity', 04:10:13 INFO - 'tart', 04:10:13 INFO - 'tscrollx', 04:10:13 INFO - 'cart']}, 04:10:13 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:10:13 INFO - 'tests': ['tsvgx', 04:10:13 INFO - 'tsvgr_opacity', 04:10:13 INFO - 'tart', 04:10:13 INFO - 'tscrollx', 04:10:13 INFO - 'cart']}, 04:10:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:13 INFO - 'tests': ['tp5o']}, 04:10:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:13 INFO - 'talos_options': ['--e10s'], 04:10:13 INFO - 'tests': ['tp5o']}, 04:10:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:13 INFO - 'talos_options': ['--xperf_path', 04:10:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:10:13 INFO - 'tests': ['tp5n']}, 04:10:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:13 INFO - 'talos_options': ['--e10s', 04:10:13 INFO - '--xperf_path', 04:10:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:10:13 INFO - 'tests': ['tp5n']}}, 04:10:13 INFO - 'talos.zip': {'path': '', 04:10:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:10:13 INFO - Running post-action listener: _resource_record_post_action 04:10:13 INFO - ##### 04:10:13 INFO - ##### Running create-virtualenv step. 04:10:13 INFO - ##### 04:10:13 INFO - Running pre-action listener: _resource_record_pre_action 04:10:13 INFO - Running main action method: create_virtualenv 04:10:13 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 04:10:13 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 04:10:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 04:10:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:10:13 INFO - Using real prefix '/usr' 04:10:13 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 04:10:14 INFO - Installing distribute.............................................................................................................................................................................................done. 04:10:15 INFO - Installing pip.................done. 04:10:15 INFO - Return code: 0 04:10:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 04:10:15 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')]} 04:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:15 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')]} 04:10:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:15 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 0x7fc4c9a30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2327600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b6350>, '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 0x23b7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b76e0>, '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': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', '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 04:10:15 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 04:10:15 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 04:10:15 INFO - Using env: {'DISPLAY': ':0', 04:10:16 INFO - 'HOME': '/home/cltbld', 04:10:16 INFO - 'LANG': 'en_US.UTF-8', 04:10:16 INFO - 'LANGUAGE': 'en_US:en', 04:10:16 INFO - 'LOGNAME': 'cltbld', 04:10:16 INFO - 'MAIL': '/var/mail/cltbld', 04:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:16 INFO - 'MOZ_NO_REMOTE': '1', 04:10:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:16 INFO - 'NO_EM_RESTART': '1', 04:10:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:16 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:16 INFO - 'SHELL': '/bin/bash', 04:10:16 INFO - 'SHLVL': '1', 04:10:16 INFO - 'TERM': 'linux', 04:10:16 INFO - 'TMOUT': '86400', 04:10:16 INFO - 'USER': 'cltbld', 04:10:16 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', 04:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:16 INFO - '_': '/tools/buildbot/bin/python'} 04:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:16 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 04:10:16 INFO - Cleaning up... 04:10:16 INFO - Return code: 0 04:10:16 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 04:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:16 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7fc4c9a30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2327600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b6350>, '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 0x23b7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b76e0>, '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': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', '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 04:10:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 04:10:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --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 04:10:16 INFO - Using env: {'DISPLAY': ':0', 04:10:16 INFO - 'HOME': '/home/cltbld', 04:10:16 INFO - 'LANG': 'en_US.UTF-8', 04:10:16 INFO - 'LANGUAGE': 'en_US:en', 04:10:16 INFO - 'LOGNAME': 'cltbld', 04:10:16 INFO - 'MAIL': '/var/mail/cltbld', 04:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:16 INFO - 'MOZ_NO_REMOTE': '1', 04:10:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:16 INFO - 'NO_EM_RESTART': '1', 04:10:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:16 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:16 INFO - 'SHELL': '/bin/bash', 04:10:16 INFO - 'SHLVL': '1', 04:10:16 INFO - 'TERM': 'linux', 04:10:16 INFO - 'TMOUT': '86400', 04:10:16 INFO - 'USER': 'cltbld', 04:10:16 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', 04:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:16 INFO - '_': '/tools/buildbot/bin/python'} 04:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:16 INFO - Downloading/unpacking psutil>=0.7.1 04:10: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 04:10: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 04:10: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 04:10: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 04:10: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 04:10: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 04:10:18 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 04:10:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 04:10:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 04:10:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:10:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:10:18 INFO - Installing collected packages: psutil 04:10:18 INFO - Running setup.py install for psutil 04:10:18 INFO - building 'psutil._psutil_linux' extension 04:10:18 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 04:10:18 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 04:10:18 INFO - building 'psutil._psutil_posix' extension 04:10:18 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 04:10:18 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 04:10:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:10:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:10:18 INFO - Successfully installed psutil 04:10:18 INFO - Cleaning up... 04:10:18 INFO - Return code: 0 04:10:18 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 04:10:18 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')]} 04:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:18 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')]} 04:10:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:18 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 0x7fc4c9a30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2327600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b6350>, '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 0x23b7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b76e0>, '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': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', '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 04:10:18 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 04:10:18 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 04:10:18 INFO - Using env: {'DISPLAY': ':0', 04:10:18 INFO - 'HOME': '/home/cltbld', 04:10:18 INFO - 'LANG': 'en_US.UTF-8', 04:10:18 INFO - 'LANGUAGE': 'en_US:en', 04:10:18 INFO - 'LOGNAME': 'cltbld', 04:10:18 INFO - 'MAIL': '/var/mail/cltbld', 04:10:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:18 INFO - 'MOZ_NO_REMOTE': '1', 04:10:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:18 INFO - 'NO_EM_RESTART': '1', 04:10:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:18 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:18 INFO - 'SHELL': '/bin/bash', 04:10:18 INFO - 'SHLVL': '1', 04:10:18 INFO - 'TERM': 'linux', 04:10:18 INFO - 'TMOUT': '86400', 04:10:18 INFO - 'USER': 'cltbld', 04:10:18 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', 04:10:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:18 INFO - '_': '/tools/buildbot/bin/python'} 04:10:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:18 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:20 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:10:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 04:10:20 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:10:20 INFO - Installing collected packages: mozsystemmonitor 04:10:20 INFO - Running setup.py install for mozsystemmonitor 04:10:20 INFO - Successfully installed mozsystemmonitor 04:10:20 INFO - Cleaning up... 04:10:20 INFO - Return code: 0 04:10:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 04:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:20 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc4c9a30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2327600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b6350>, '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 0x23b7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b76e0>, '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': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', '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 04:10:20 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 04:10:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:10:20 INFO - Using env: {'DISPLAY': ':0', 04:10:20 INFO - 'HOME': '/home/cltbld', 04:10:20 INFO - 'LANG': 'en_US.UTF-8', 04:10:20 INFO - 'LANGUAGE': 'en_US:en', 04:10:20 INFO - 'LOGNAME': 'cltbld', 04:10:20 INFO - 'MAIL': '/var/mail/cltbld', 04:10:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:20 INFO - 'MOZ_NO_REMOTE': '1', 04:10:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:20 INFO - 'NO_EM_RESTART': '1', 04:10:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:20 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:20 INFO - 'SHELL': '/bin/bash', 04:10:20 INFO - 'SHLVL': '1', 04:10:20 INFO - 'TERM': 'linux', 04:10:20 INFO - 'TMOUT': '86400', 04:10:20 INFO - 'USER': 'cltbld', 04:10:20 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', 04:10:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:20 INFO - '_': '/tools/buildbot/bin/python'} 04:10:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:21 INFO - Downloading/unpacking blobuploader==1.2.4 04:10:21 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 04:10:21 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 04:10:21 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 04:10:21 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 04:10:21 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 04:10:21 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 04:10:22 INFO - Downloading blobuploader-1.2.4.tar.gz 04:10:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 04:10:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:10:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 04:10:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 04:10:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:23 INFO - Downloading docopt-0.6.1.tar.gz 04:10:23 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 04:10:23 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 04:10:23 INFO - Installing collected packages: blobuploader, requests, docopt 04:10:23 INFO - Running setup.py install for blobuploader 04:10:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:10:23 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 04:10:23 INFO - Running setup.py install for requests 04:10:23 INFO - Running setup.py install for docopt 04:10:23 INFO - Successfully installed blobuploader requests docopt 04:10:23 INFO - Cleaning up... 04:10:24 INFO - Return code: 0 04:10:24 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 04:10:24 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')]} 04:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:24 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')]} 04:10:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:24 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 0x7fc4c9a30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2327600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b6350>, '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 0x23b7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b76e0>, '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': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', '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 04:10:24 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 04:10:24 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 04:10:24 INFO - Using env: {'DISPLAY': ':0', 04:10:24 INFO - 'HOME': '/home/cltbld', 04:10:24 INFO - 'LANG': 'en_US.UTF-8', 04:10:24 INFO - 'LANGUAGE': 'en_US:en', 04:10:24 INFO - 'LOGNAME': 'cltbld', 04:10:24 INFO - 'MAIL': '/var/mail/cltbld', 04:10:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:24 INFO - 'MOZ_NO_REMOTE': '1', 04:10:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:24 INFO - 'NO_EM_RESTART': '1', 04:10:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:24 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:24 INFO - 'SHELL': '/bin/bash', 04:10:24 INFO - 'SHLVL': '1', 04:10:24 INFO - 'TERM': 'linux', 04:10:24 INFO - 'TMOUT': '86400', 04:10:24 INFO - 'USER': 'cltbld', 04:10:24 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', 04:10:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:24 INFO - '_': '/tools/buildbot/bin/python'} 04:10:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:10:24 INFO - Running setup.py (path:/tmp/pip-bUhrOG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:10:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:10:24 INFO - Running setup.py (path:/tmp/pip-j6XLKr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:10:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:10:24 INFO - Running setup.py (path:/tmp/pip-PQ154x-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:10:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:10:24 INFO - Running setup.py (path:/tmp/pip-jmtW2w-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:10:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 04:10:24 INFO - Running setup.py (path:/tmp/pip-npRUqr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 04:10:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:10:24 INFO - Running setup.py (path:/tmp/pip-BNEa0G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:10:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:10:24 INFO - Running setup.py (path:/tmp/pip-ezLweI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:10:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:10:24 INFO - Running setup.py (path:/tmp/pip-ySOrfz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:10:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 04:10:24 INFO - Running setup.py (path:/tmp/pip-RnhJ2T-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 04:10:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 04:10:24 INFO - Running setup.py (path:/tmp/pip-r8_ZFj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 04:10:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:10:24 INFO - Running setup.py (path:/tmp/pip-7A4Yyu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:10:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:10:25 INFO - Running setup.py (path:/tmp/pip-1PCRYh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:10:25 INFO - Running setup.py (path:/tmp/pip-WtSZGB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:10:25 INFO - Running setup.py (path:/tmp/pip-M_vwsi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:10:25 INFO - Running setup.py (path:/tmp/pip-3uhYMv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 04:10:25 INFO - Running setup.py (path:/tmp/pip-bAAyl9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 04:10:25 INFO - Running setup.py (path:/tmp/pip-NWbMf7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 04:10:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:10:25 INFO - Running setup.py install for manifestparser 04:10:25 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 04:10:25 INFO - Running setup.py install for mozcrash 04:10:25 INFO - Running setup.py install for mozdebug 04:10:25 INFO - Running setup.py install for mozdevice 04:10:25 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 04:10:25 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 04:10:25 INFO - Running setup.py install for mozfile 04:10:25 INFO - Running setup.py install for mozhttpd 04:10:26 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Running setup.py install for mozinfo 04:10:26 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Running setup.py install for mozInstall 04:10:26 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Running setup.py install for mozleak 04:10:26 INFO - Running setup.py install for mozlog 04:10:26 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Running setup.py install for moznetwork 04:10:26 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Running setup.py install for mozprocess 04:10:26 INFO - Running setup.py install for mozprofile 04:10:26 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Running setup.py install for mozrunner 04:10:26 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Running setup.py install for mozscreenshot 04:10:27 INFO - Running setup.py install for moztest 04:10:27 INFO - Running setup.py install for mozversion 04:10:27 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 04:10:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:10:27 INFO - Cleaning up... 04:10:27 INFO - Return code: 0 04:10:27 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 04:10:27 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')]} 04:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:27 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')]} 04:10:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:27 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 0x7fc4c9a30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2327600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b6350>, '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 0x23b7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b76e0>, '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': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', '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 04:10:27 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 04:10:27 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 04:10:27 INFO - Using env: {'DISPLAY': ':0', 04:10:27 INFO - 'HOME': '/home/cltbld', 04:10:27 INFO - 'LANG': 'en_US.UTF-8', 04:10:27 INFO - 'LANGUAGE': 'en_US:en', 04:10:27 INFO - 'LOGNAME': 'cltbld', 04:10:27 INFO - 'MAIL': '/var/mail/cltbld', 04:10:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:27 INFO - 'MOZ_NO_REMOTE': '1', 04:10:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:27 INFO - 'NO_EM_RESTART': '1', 04:10:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:27 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:27 INFO - 'SHELL': '/bin/bash', 04:10:27 INFO - 'SHLVL': '1', 04:10:27 INFO - 'TERM': 'linux', 04:10:27 INFO - 'TMOUT': '86400', 04:10:27 INFO - 'USER': 'cltbld', 04:10:27 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', 04:10:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:27 INFO - '_': '/tools/buildbot/bin/python'} 04:10:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:10:27 INFO - Running setup.py (path:/tmp/pip-yGm50C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:10:27 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)) 04:10:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:10:27 INFO - Running setup.py (path:/tmp/pip-3iaEi3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:10:27 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)) 04:10:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:10:27 INFO - Running setup.py (path:/tmp/pip-67lLot-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:10:27 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)) 04:10:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:10:27 INFO - Running setup.py (path:/tmp/pip-TP3qCt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:10:27 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)) 04:10:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 04:10:27 INFO - Running setup.py (path:/tmp/pip-5Ynl7I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 04:10:27 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)) 04:10:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:10:27 INFO - Running setup.py (path:/tmp/pip-VNV7dw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:10:27 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)) 04:10:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:10:27 INFO - Running setup.py (path:/tmp/pip-jNo43i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:10:27 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)) 04:10:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:10:27 INFO - Running setup.py (path:/tmp/pip-zRLgMl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 04:10:28 INFO - Running setup.py (path:/tmp/pip-fKU3zy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 04:10:28 INFO - Running setup.py (path:/tmp/pip-LDB_bC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:10:28 INFO - Running setup.py (path:/tmp/pip-qNLajJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:10:28 INFO - Running setup.py (path:/tmp/pip-R9JD_6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:10:28 INFO - Running setup.py (path:/tmp/pip-VtuMdu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:10:28 INFO - Running setup.py (path:/tmp/pip-73qHa8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:10:28 INFO - Running setup.py (path:/tmp/pip-ebdDln-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 04:10:28 INFO - Running setup.py (path:/tmp/pip-hF9slo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 04:10:28 INFO - Running setup.py (path:/tmp/pip-AYos5d-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 04:10:28 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)) 04:10:28 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)) 04:10:28 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)) 04:10:28 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)) 04:10:28 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)) 04:10:28 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)) 04:10:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 04:10:28 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 04:10:28 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 04:10:28 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 04:10:28 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 04:10:28 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 04:10:28 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 04:10:30 INFO - Downloading blessings-1.5.1.tar.gz 04:10:30 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 04:10:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 04:10:30 INFO - Installing collected packages: blessings 04:10:30 INFO - Running setup.py install for blessings 04:10:30 INFO - Successfully installed blessings 04:10:30 INFO - Cleaning up... 04:10:30 INFO - Return code: 0 04:10:30 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 04:10:30 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 04:10:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 04:10:30 INFO - Reading from file tmpfile_stdout 04:10:30 INFO - Current package versions: 04:10:30 INFO - argparse == 1.2.1 04:10:30 INFO - blessings == 1.5.1 04:10:30 INFO - blobuploader == 1.2.4 04:10:30 INFO - docopt == 0.6.1 04:10:30 INFO - manifestparser == 1.1 04:10:30 INFO - mozInstall == 1.12 04:10:30 INFO - mozcrash == 0.16 04:10:30 INFO - mozdebug == 0.1 04:10:30 INFO - mozdevice == 0.46 04:10:30 INFO - mozfile == 1.2 04:10:30 INFO - mozhttpd == 0.7 04:10:30 INFO - mozinfo == 0.8 04:10:30 INFO - mozleak == 0.1 04:10:30 INFO - mozlog == 3.0 04:10:30 INFO - moznetwork == 0.27 04:10:30 INFO - mozprocess == 0.22 04:10:30 INFO - mozprofile == 0.27 04:10:30 INFO - mozrunner == 6.11 04:10:30 INFO - mozscreenshot == 0.1 04:10:30 INFO - mozsystemmonitor == 0.0 04:10:30 INFO - moztest == 0.7 04:10:30 INFO - mozversion == 1.4 04:10:30 INFO - psutil == 3.1.1 04:10:30 INFO - requests == 1.2.3 04:10:30 INFO - wsgiref == 0.1.2 04:10:30 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 04:10:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:30 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 0x7fc4c9a30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2327600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b6350>, '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 0x23b7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b76e0>, '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': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', '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 04:10:30 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 04:10:30 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 04:10:30 INFO - Using env: {'DISPLAY': ':0', 04:10:30 INFO - 'HOME': '/home/cltbld', 04:10:30 INFO - 'LANG': 'en_US.UTF-8', 04:10:30 INFO - 'LANGUAGE': 'en_US:en', 04:10:30 INFO - 'LOGNAME': 'cltbld', 04:10:30 INFO - 'MAIL': '/var/mail/cltbld', 04:10:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:30 INFO - 'MOZ_NO_REMOTE': '1', 04:10:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:30 INFO - 'NO_EM_RESTART': '1', 04:10:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:30 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:30 INFO - 'SHELL': '/bin/bash', 04:10:30 INFO - 'SHLVL': '1', 04:10:30 INFO - 'TERM': 'linux', 04:10:30 INFO - 'TMOUT': '86400', 04:10:30 INFO - 'USER': 'cltbld', 04:10:30 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', 04:10:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:30 INFO - '_': '/tools/buildbot/bin/python'} 04:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 INFO - Cleaning up... 04:10:31 INFO - Return code: 0 04:10:31 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 04:10: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')]} 04:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10: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')]} 04:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:31 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 0x7fc4c9a30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2327600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b6350>, '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 0x23b7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b76e0>, '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': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', '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 04:10:31 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 04:10:31 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 04:10:31 INFO - Using env: {'DISPLAY': ':0', 04:10:31 INFO - 'HOME': '/home/cltbld', 04:10:31 INFO - 'LANG': 'en_US.UTF-8', 04:10:31 INFO - 'LANGUAGE': 'en_US:en', 04:10:31 INFO - 'LOGNAME': 'cltbld', 04:10:31 INFO - 'MAIL': '/var/mail/cltbld', 04:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:31 INFO - 'MOZ_NO_REMOTE': '1', 04:10:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:31 INFO - 'NO_EM_RESTART': '1', 04:10:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:31 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:31 INFO - 'SHELL': '/bin/bash', 04:10:31 INFO - 'SHLVL': '1', 04:10:31 INFO - 'TERM': 'linux', 04:10:31 INFO - 'TMOUT': '86400', 04:10:31 INFO - 'USER': 'cltbld', 04:10:31 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', 04:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:31 INFO - '_': '/tools/buildbot/bin/python'} 04:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:31 INFO - Downloading/unpacking jsonschema 04:10:31 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 04:10:31 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 04:10:31 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 04:10:31 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 04:10:31 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 04:10:31 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 04:10:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:10:32 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 04:10:32 INFO - Downloading/unpacking functools32 (from jsonschema) 04:10:32 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 04:10:32 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 04:10:32 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 04:10:32 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 04:10:32 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 04:10:32 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 04:10:33 INFO - Downloading functools32-3.2.3-2.tar.gz 04:10:33 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 04:10:33 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 04:10:33 INFO - warning: no files found matching '*.txt' 04:10:33 INFO - no previously-included directories found matching 'build' 04:10:33 INFO - no previously-included directories found matching 'dist' 04:10:33 INFO - no previously-included directories found matching '.git*' 04:10:33 INFO - Installing collected packages: jsonschema, functools32 04:10:33 INFO - Running setup.py install for functools32 04:10:33 INFO - warning: no files found matching '*.txt' 04:10:33 INFO - no previously-included directories found matching 'build' 04:10:33 INFO - no previously-included directories found matching 'dist' 04:10:33 INFO - no previously-included directories found matching '.git*' 04:10:33 INFO - Successfully installed jsonschema functools32 04:10:33 INFO - Cleaning up... 04:10:33 INFO - Return code: 0 04:10:33 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 04:10:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:33 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 0x7fc4c9a30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2327600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b6350>, '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 0x23b7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b76e0>, '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': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', '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 04:10:33 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 04:10:33 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 04:10:33 INFO - Using env: {'DISPLAY': ':0', 04:10:33 INFO - 'HOME': '/home/cltbld', 04:10:33 INFO - 'LANG': 'en_US.UTF-8', 04:10:33 INFO - 'LANGUAGE': 'en_US:en', 04:10:33 INFO - 'LOGNAME': 'cltbld', 04:10:33 INFO - 'MAIL': '/var/mail/cltbld', 04:10:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:33 INFO - 'MOZ_NO_REMOTE': '1', 04:10:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:33 INFO - 'NO_EM_RESTART': '1', 04:10:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:33 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:33 INFO - 'SHELL': '/bin/bash', 04:10:33 INFO - 'SHLVL': '1', 04:10:33 INFO - 'TERM': 'linux', 04:10:33 INFO - 'TMOUT': '86400', 04:10:33 INFO - 'USER': 'cltbld', 04:10:33 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', 04:10:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:33 INFO - '_': '/tools/buildbot/bin/python'} 04:10:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:33 INFO - Downloading/unpacking flake8 04:10:33 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 04:10:33 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 04:10:33 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 04:10:33 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 04:10:33 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 04:10:33 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 04:10:35 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:10:35 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 04:10:35 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:10:35 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 04:10:35 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 04:10:35 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 04:10:35 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 04:10:35 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 04:10:35 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 04:10:35 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:10:35 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 04:10:35 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:10:35 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 04:10:35 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 04:10:35 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 04:10:35 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 04:10:35 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 04:10:35 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 04:10:35 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 04:10:35 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:10:35 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 04:10:35 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 04:10:35 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 04:10:35 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 04:10:35 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 04:10:35 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 04:10:35 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 04:10:35 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 04:10:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:10:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:10:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:10:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:10:36 INFO - no previously-included directories found matching 'docs/_build' 04:10:36 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:10:36 INFO - Running setup.py install for pep8 04:10:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:10:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:10:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:10:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:10:36 INFO - no previously-included directories found matching 'docs/_build' 04:10:36 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 04:10:36 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:10:36 INFO - Cleaning up... 04:10:36 INFO - Return code: 0 04:10:36 INFO - Running post-action listener: _resource_record_post_action 04:10:36 INFO - Running post-action listener: _start_resource_monitoring 04:10:36 INFO - Starting resource monitoring. 04:10:36 INFO - ##### 04:10:36 INFO - ##### Running install step. 04:10:36 INFO - ##### 04:10:36 INFO - Running pre-action listener: _resource_record_pre_action 04:10:36 INFO - Running main action method: install 04:10:36 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 04:10:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 04:10:36 INFO - Reading from file tmpfile_stdout 04:10:36 INFO - Detecting whether we're running mozinstall >=1.0... 04:10:36 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 04:10:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 04:10:36 INFO - Reading from file tmpfile_stdout 04:10:36 INFO - Output received: 04:10:36 INFO - Usage: mozinstall [options] installer 04:10:36 INFO - Options: 04:10:36 INFO - -h, --help show this help message and exit 04:10:36 INFO - -d DEST, --destination=DEST 04:10:36 INFO - Directory to install application into. [default: 04:10:36 INFO - "/builds/slave/test-pgo"] 04:10:36 INFO - --app=APP Application being installed. [default: firefox] 04:10:36 INFO - mkdir: /builds/slave/test-pgo/build/application 04:10:36 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'] 04:10:36 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 04:10:50 INFO - Reading from file tmpfile_stdout 04:10:50 INFO - Output received: 04:10:50 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 04:10:50 INFO - Running post-action listener: _resource_record_post_action 04:10:50 INFO - ##### 04:10:50 INFO - ##### Running run-tests step. 04:10:50 INFO - ##### 04:10:50 INFO - Running pre-action listener: _resource_record_pre_action 04:10:50 INFO - Running main action method: run_tests 04:10:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 04:10:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 04:10:50 INFO - Python 2.7.3 04:10:50 INFO - Return code: 0 04:10:50 INFO - grabbing minidump binary from tooltool 04:10:50 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')]} 04:10:50 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 0x23b6350>, '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 0x23b7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b76e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 04:10:50 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 04:10:50 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 04:10:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:10:50 INFO - Return code: 0 04:10:50 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 04:10:50 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 04:10:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 04:10:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 04:10:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 04:10:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 04:10:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 04:10:51 INFO - Return code: 0 04:10:51 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/1450155070/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 04:10:51 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/1450155070/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-008 --webServer localhost 04:10:51 INFO - Using env: {'DISPLAY': ':0', 04:10:51 INFO - 'HOME': '/home/cltbld', 04:10:51 INFO - 'LANG': 'en_US.UTF-8', 04:10:51 INFO - 'LANGUAGE': 'en_US:en', 04:10:51 INFO - 'LOGNAME': 'cltbld', 04:10:51 INFO - 'MAIL': '/var/mail/cltbld', 04:10:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 04:10:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 04:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:51 INFO - 'MOZ_NO_REMOTE': '1', 04:10:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 04:10:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:51 INFO - 'NO_EM_RESTART': '1', 04:10:51 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:51 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:51 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 04:10:51 INFO - 'SHELL': '/bin/bash', 04:10:51 INFO - 'SHLVL': '1', 04:10:51 INFO - 'TERM': 'linux', 04:10:51 INFO - 'TMOUT': '86400', 04:10:51 INFO - 'USER': 'cltbld', 04:10:51 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548', 04:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:51 INFO - '_': '/tools/buildbot/bin/python'} 04:10:51 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/1450155070/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost'] with output_timeout 3600 04:10:51 INFO - mozversion INFO | application_buildid: 20151214205110 04:10:51 INFO - mozversion INFO | application_changeset: d1a4b331d80bc4daea15a7df4057205483b1f276 04:10:51 INFO - mozversion INFO | application_display_name: Nightly 04:10:51 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:10:51 INFO - mozversion INFO | application_name: Firefox 04:10:51 INFO - mozversion INFO | application_remotingname: firefox 04:10:51 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:10:51 INFO - mozversion INFO | application_vendor: Mozilla 04:10:51 INFO - mozversion INFO | application_version: 44.0 04:10:51 INFO - mozversion INFO | platform_buildid: 20151214205110 04:10:51 INFO - mozversion INFO | platform_changeset: d1a4b331d80bc4daea15a7df4057205483b1f276 04:10:51 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:10:51 INFO - mozversion INFO | platform_version: 44.0 04:10:51 INFO - 2015-12-16 04:10:51,953 DEBUG : using testdate: 1450267851 04:10:51 INFO - 2015-12-16 04:10:51,953 DEBUG : actual date: 1450267851 04:10:52 INFO - 2015-12-16 04:10:52,080 INFO : starting webserver on 'localhost:55935' 04:10:52 INFO - 2015-12-16 04:10:52,081 INFO : Starting test suite talos-linux64-ix-008 04:10:52 INFO - 2015-12-16 04:10:52,081 INFO : Starting test dromaeo_css 04:10:52 INFO - 2015-12-16 04:10:52,081 DEBUG : operating with platform_type : linux_ 04:10:52 INFO - 2015-12-16 04:10:52,082 INFO : Initialising browser for dromaeo_css test... 04:10:52 INFO - 2015-12-16 04:10:52,094 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpWvKlxg/profile http://localhost:55935/getInfo.html 04:10:55 INFO - 2015-12-16 04:10:55,952 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:10:55 INFO - 2015-12-16 04:10:55,952 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:10:55 INFO - 2015-12-16 04:10:55,953 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:10:55 INFO - 2015-12-16 04:10:55,953 DEBUG : BROWSER_OUTPUT: __metrics 04:10:56 INFO - 2015-12-16 04:10:56,029 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] 04:10:57 INFO - 2015-12-16 04:10:57,213 INFO : Browser initialized. 04:10:57 INFO - 2015-12-16 04:10:57,213 INFO : Running cycle 1/1 for dromaeo_css test... 04:10:57 INFO - 2015-12-16 04:10:57,214 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpWvKlxg/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:11:08 INFO - 2015-12-16 04:11:08,858 DEBUG : BROWSER_OUTPUT: RSS: Main: 129282048 04:11:08 INFO - 2015-12-16 04:11:08,858 DEBUG : BROWSER_OUTPUT: 04:11:59 INFO - 2015-12-16 04:11:59,730 DEBUG : BROWSER_OUTPUT: 1450267919729 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] 04:14:08 INFO - 2015-12-16 04:14:08,079 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55935/tests/dromaeo/cssquery-ext.html) 04:14:09 INFO - 2015-12-16 04:14:09,164 DEBUG : BROWSER_OUTPUT: RSS: Main: 239251456 04:14:09 INFO - 2015-12-16 04:14:09,164 DEBUG : BROWSER_OUTPUT: 04:17:11 INFO - 2015-12-16 04:17:11,245 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/dromaeo/cssquery-ext.html (next: http://localhost:55935/tests/dromaeo/cssquery-jquery.html) 04:17:11 INFO - 2015-12-16 04:17:11,807 DEBUG : BROWSER_OUTPUT: RSS: Main: 213225472 04:17:11 INFO - 2015-12-16 04:17:11,807 DEBUG : BROWSER_OUTPUT: 04:20:13 INFO - 2015-12-16 04:20:13,526 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55935/tests/dromaeo/cssquery-mootools.html) 04:20:14 INFO - 2015-12-16 04:20:14,093 DEBUG : BROWSER_OUTPUT: RSS: Main: 213962752 04:20:14 INFO - 2015-12-16 04:20:14,093 DEBUG : BROWSER_OUTPUT: 04:23:15 INFO - 2015-12-16 04:23:15,802 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55935/tests/dromaeo/cssquery-prototype.html) 04:23:16 INFO - 2015-12-16 04:23:16,356 DEBUG : BROWSER_OUTPUT: RSS: Main: 194142208 04:23:16 INFO - 2015-12-16 04:23:16,356 DEBUG : BROWSER_OUTPUT: 04:26:18 INFO - 2015-12-16 04:26:18,635 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55935/tests/dromaeo/cssquery-yui.html) 04:26:19 INFO - 2015-12-16 04:26:19,197 DEBUG : BROWSER_OUTPUT: RSS: Main: 196358144 04:26:19 INFO - 2015-12-16 04:26:19,197 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,151 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/dromaeo/cssquery-yui.html (next: http://localhost:55935/tests/dromaeo/cssquery-dojo.html) 04:29:21 INFO - 2015-12-16 04:29:21,401 DEBUG : BROWSER_OUTPUT: RSS: Main: 228302848 04:29:21 INFO - 2015-12-16 04:29:21,402 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,403 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:29:21 INFO - 2015-12-16 04:29:21,403 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:29:21 INFO - 2015-12-16 04:29:21,403 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:29:21 INFO - 2015-12-16 04:29:21,403 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:29:21 INFO - 2015-12-16 04:29:21,407 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3373.4939759036142;3398.8980330590093;3423.5035919791617;3443.9655603443975;3465.688088072073;5733.512651424634;5740;5745.798897038604;5752.856178595537;5764.827055188346;5755.856103597413;5766.106253530709;5768.913466297999;5805.390434004432;5822.243108395914;5080.1617733074;5218.39988401334;5245.291885595453;5312.6281160318795;5335.759890804923;5258.579313654917;5262.236975638531;5285.180796976475;5285.709285989269;5286.365636123671;5014.122528557488;5194.013137503881;5206.297149884755;5247.1866860636665;5259.053370393328;5189.42916279209;5297.046531624306;5312.867178320573;5315;5323.946760532383;5263.4473380295285;5266;5274.287971123926;5282.577393808486;5322.787088516455;5364.9731751341;5453.345598528163;5457.836264912019;5564.360098588633;5628.577856660726;6203.038529028008;6240.469560087398;6268.435840774307;6268.561200715952;6292.087647291117;6308.369163083701;6310.021946598285;6325.272593651743;6329.968350158266;6330.145430366933;5624.606277560572;5724.370319264877;5748.051571490699;5891.882162356729;5927.407259274081;6358.968205158944;6365.331640177715;6367.331430199855;6368.395002474757;6374.26695929974;6008.21893153896;6124.724387402578;6170.938290617126;6176.474999625082;6179.567430279837;6192.938070619326;6204.689765511706;6334.968325158345;6335.651539165298;6336.588121772163;6250.499960003189;6250.781222657185;6260.123582698426;6265.310815810257;6266.404691554295;5912.822615321547;6060.969695151584;6232;6247.75008999644;6249.812505624838;4704.435467743824;4706.1528924793865;4707.31743897133;4710.29345598163;4710.599599034052;4108.548059713429;4198.202341555095;4226.1336426032585;4272.209641216385;4282.828686852553;5886.087656413263;5895.675737834375;5931.436513531207;5941.346451890288;5952.255968003999;6214.906776398358;6233.688315584293;6240.219972503437;6245.718942647592;6247.843803904939;5675.4892059714;5734.569907256972;5823.097419899922;5850.23946886902;5854.18041474194;5372.274742909735;5414.593905457106;5421.674699518042;5425.348958125051;5425.565954723613;6169.0129579268;6456.806295811133;6457.935420645734;6461.870762584722;6467.708953097122;6092.360302168386;6112.015337453936;6217.253929528486;6255.280642726145;6258.718357673824;6405.551611387345;6509.576877502947;6517.934820651923;6523.18460192476;6530.8367290818105;6022.45797878193;6123.8162855114415;6181.721822518088;6239.656818874824;6248.500119990299;6064.666443345658;6152.077188421773;6152.661603611844;6154.9384506154365;6157.9384206157365;6042.0967395270345;6078.422549857756;6083.878322433615;6087.817365479043;6088.969555152196;5944.167816505606;6064.151018857276;6065.969670151621;6066.3023752269055;6092.086187071797;6025.8192254232445;6029.8191054268445;6036.456718895321;6037.154798328325;6047.848803779764;6083.66539840313;6141.570090093651;6146.969265153645;6147.0779383092895;6148.569600127948;5694.914576281442;5792.623479473821;5951.22634057587;5954;6109.480694141048 04:29:21 INFO - 2015-12-16 04:29:21,407 DEBUG : BROWSER_OUTPUT: |1;ext.html;590.3476658292586;635.2377147423093;640.4140211706289;655.3512023097135;665.9267480577136;34586.8270658647;45095.54904450954;45528.999999999956;45544.772276138654;45560.54439455605;48539.75730121353;49174.262386064234;49223;49223.26165107526;49225.753871230685;8229.588520573978;8329.916700833004;9195.402298850553;9365.719028429157;10646.840297395545;10972.177086718488;11050.723731906686;11084.279521831095;11091.223614346998;11093.889061109407;11133.888661113408;11174.776504469906;11227.045701115416;11243.494042768096;11250.3812290324;11089.778204435946;11105.111591072695;11181.72045698856;11189.936955989167;11196.160287978393;10829.620963266249;10995.395253261067;11024.393658348788;11026.558937642565;11050.226484146113;44116.11767764659;44218.77890610559;44320;44549.55450445486;44716.329255060875;3596.604373518911;3646.346194014239;3702.278055779097;3749.418840079792;4343.6959412841115;33461.49807752886;33612;33639.49540756891;33715.83142084274;33909.15227119317;3954.5357492216567;3961.6632586230203;3986.6412022918075;3990.6408423241755;4077.510322497598;315485;316613;317048;317168;317753;58952;60299.39700602938;68126;68570;68583.65708171527;73350;73855.63072184604;74408.62795685987;74559.25440745632;74967.62516187385;16426.260818263207;16580.668386632442;16606;16606.169691515377;16610.58473538147;12342.259464432163;12351.505939762488;12356.196847204903;12365.814512782315;12379.6905077372;22141.889290553765;22292.331230063122;22347.55304893893;22370.552588948456;22416.775832241794;7870.881936770953;7997.520148791091;8131.349492040623;8164.387670924704;8208.835823283502;973.5473005052685;1016.2632091733582;1022.1209759606733;1037.0873631204493;1141.14414189358;30746.69253307483;31392.215194620316;31482.527762083588;35586.466203006974;36124.81937590295;1139.7207684117445;1152.9423528823525;1171.0982543441503;1228.072805032195;1255.5731051442554;14778.926105369404;17010.149492525325;17127.74308385375;17246.051467169174;17336.30654773795;443772;446636;447170;449060;450578;62876;62906.37093629005;62995;63441.365586344466;63472;1554.8833837462237;1570.7408277634063;1571;1580.3599542185434;1587.49200255917;32384.190395240308;32941.50587741138;35280.64719352876;35762;35919.28161436809;56807.43192568021;57387;57491.137632934704;57546.7122664384;57573;361100;363982;364873;365077;367529;31923.68076319207;34914.9525514235;37400.43899341457;37474.437883432314;37654.811725941196;42694.57305426906;43067.78466107775;43607.56392436162;43894;44105.55894441014;45983;47372.05255894932;48206;48436.757816210695;48769;39519.407208891316;41750.79124604358;42306.15387692167;42330.36504452497;46558.53441465542;40396.19207615773;40917;41094;41479.7926010368;41492.1701565961;35160.29679406449;36319.455208172425;36588;36764.16179191083;37484.25031499409;28320.43359132847;28506.57240141358;28532;28668.856655716587;28716.42567148687 04:29:21 INFO - 2015-12-16 04:29:21,411 DEBUG : BROWSER_OUTPUT: |2;jquery.html;376.5895174260058;383.21823480100613;385.09694765774265;385.610533361305;392.1979551816535;15715.921420392911;17882.195301211443;18087.638247235063;19352.806471935277;19355.322563710255;16965.236564354604;17178.226979785908;17417.477475675718;17932.37236696716;18093.457196284133;5678.233438485813;5842.415758424145;5855.912161317584;5859.0625499920025;5862.4430679085635;4336.241157797389;4444.133393988173;5768.826935191951;6160.106784516242;6328.367163283681;6074.149619053336;6162.784302549389;6237.906431403532;6261.3425590313045;6265.216847894013;6201.379862013785;6227.19046523949;6233.2208473940755;6237.906431403532;6238.251409830804;2868.641419822522;5313.9637770634745;5497.147942068986;5506.559475242011;5510.173473978895;21893.890530547244;22051.66922496164;22208.777912220834;22611.77388226113;22805;2367.4791545859885;2405.0980882169188;2459.446624509489;2482.739312372193;2498.6376975338476;13984;14094.365753541013;14203.78694319586;14298.857011429856;14677.633059173499;1826.7899191593003;1894.355918987551;1985.176151896961;2003.929862454807;2016.052455345985;62843.68578157126;63952.523323367386;64118;65131.348686513;65486;10359.741006474747;10798.298110622783;11118.166137539716;11158.944205278922;11224.66326010221;14406.639834004234;14413.639659008399;14432.855671443362;14435.92782036083;14485.348159332856;8214.301784348278;8266.338692904674;8456.788580285542;8512.148785121439;8518.23335899772;8182.7545173644885;8196.221358970888;8231.34149268057;8270.545120018456;8276.8344633107;6496.155499784998;6598.571092879043;6603.735850565923;6609.933900661028;6611.404973552403;1733.9219641085667;1798.2896755781655;2001.6196922584663;2081.39639504542;2082.4481512399225;477.68711493971506;485.66003797342;496.6697146397605;508.905852417304;512.7333786431035;11002.994384905529;12589;13006.284654344103;13077.084604077623;13253.07228493996;519.8492437193172;530.6948504609865;544.4528249109699;548.9533389661923;549.4231057389725;6981.860362792716;7175.425965922714;7208.711651533985;7236.782896513113;7258.455615828834;621955;624912;641039;642232;660639;80312.19687803047;81268;82714;83788;84095.579522102;579.8289504596239;592.6681058607194;597.5100417657566;597.9282486101696;598.308953159102;10956;11579.826302605636;11634.883651163718;11950.820737688768;11951.282923024646;35876.28247435089;36958.63041369552;37246;39173.41239881461;40522.39216411815;51989.480105199975;52313;52931.735341323045;53173.46826531685;53823.73088134534;7180;7235.131784185932;7304.050473438419;7330.633468326456;7358.374538164316;10336.37981721069;12744.808827867404;12872.549460769204;12979.805302920275;13132.015098867432;10958.561657533928;10977.3962432067;11095.056920161876;11117.11063114965;11139.05318047975;10742.409167495864;10802.297850639683;10883.401412922365;10896.455177241265;10997.780044399227;11053.613123540637;11152.16358773079;11323;11410.600628977945;11453.942730286628;7225.602591857499;7228.241034691282;7313.158986716383;7346.485745997729;7352.338289553752;11045.668629941114;11182.944085279847;11355.091592672401;11383.715407114558;11409.54361825551 04:29:21 INFO - 2015-12-16 04:29:21,412 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1570.450342380167;1579.541932839476;1828.5520047588343;1862.8509719222463;1873.5035215667847;7365.7790266292095;7465.365443937272;8335.333173346133;8560.571971401429;8561.529115898631;8013.679452821894;8106.594670266492;8207.179282071806;8222.588870556463;8222.753317400473;2418.9999999999955;2458.91393801217;2460.188137914493;2461.064795377754;2462.5198086373166;2106.6524023536176;2146.731658542682;2422.1280339077916;2422.358075110097;2509.4604659998095;2397.3646983549374;2410.6143017117265;2439.231642032754;2440.9023639054503;2441.6703744994375;2411.1560953666253;2437.146998550502;2437.622168563875;2439.5486834935596;2440.6095024796123;2384.9046038158453;2417.274817554744;2421.2010036688025;2422.0796097482844;2426.041713523166;8303.501789892625;8346.958265208636;8371.288440482565;8372.83254334916;8373.246407823324;5122.103631864409;5184.092783762826;5197.220416937452;5199.922001169948;5201.40183878855;3251.723603493706;3298.109510432194;3390.0507857799857;3405.2848901730417;3418.3676019936393;1177.7879981603307;1183.8520184976878;1217.2696382170627;1218.074263559692;1226.9877301227052;11273.943630281796;11305.773884522347;11311.264767790068;11332.660020199408;11333.773324533464;4540.137373898949;4548.931766023512;4707.176244157259;4744.335792988984;4750.287456881495;9745.684332615148;9752.658656946973;9769.413835169913;9770.560324785401;9772.657956971472;5202.2196670499725;5206.088934436459;5210.869728256824;5213.035588416155;5213.7914483421;4663.790129444184;4681.204195286809;4690.64820138491;4691.319758634979;4691.413573303337;3593.2813437312643;4121.9381709274385;4140.275451795924;4206.537280899099;4254.3831144483875;1256.1395444120624;1367.4325155060537;1374.2785037855006;1390.7635701930494;1396.3855903402673;1148.74153315505;1162.8837116288473;1254.8996080313552;1465.377214683755;1484.250453520965;7945.48354356965;8058.516489010678;8077.474964127313;8079.555624440594;8087.433879628487;1240.944157512914;1243.92478686957;1246.1775228349245;1271.8219449277108;1293.424426130363;6274.121622972787;6285.057241413824;6339.207599050119;6345.841353966188;6346.174997250328;9931.602735890629;10052.497375131214;10062.446565438824;10091.243156763125;10093.343932644502;9875.031117749564;9875.851862222073;9961.203103751683;9980.101790838859;9997.500124993721;7811.843763124706;7877.212278772077;7877.921220787834;7889.6449659766595;7894.052713674396;7358.153812311439;7391.8152046199275;7394.334509894135;7476.25237476248;7592.202818703957;8582.957085214533;8891.155340242667;9087.500187489752;9094.727158185266;9128.178463938277;9065.138811812867;9246.398984066014;9253.120953509406;9364.859527107235;9446.38598491096;7316.524425912298;7319.817004574928;7401.666924988281;7415;7424.109106907205;7452.590107544137;7587.013688220496;8122.593870306343;8264;8277.130901255523;7642.235776422313;7730.188330225245;7734.7679569613;7753.92246077532;7893.36853051766;7655.885161722467;7705.345045670956;7721.806954826175;7782.610869456617;7799.06411230656;7784.143744188022;7787.766367008999;7827.647755851114;7844.764657060297;7865.41009424284;7156;7171.677274522554;7190.101237345332;7191.532550384209;7202.279772022755;7028.8594228114125;7052.188998265266;7066.7173313066905;7109.2179860216365;7152.570845749272 04:29:21 INFO - 2015-12-16 04:29:21,416 DEBUG : BROWSER_OUTPUT: |4;prototype.html;349.5089399393852;355.9092431429986;356.99464508032383;369.2117329501514;373.1548043681061;5952.196453478779;5966.254218222723;5992.34084250732;6014.36849130841;6021.969890150549;5886.852828679285;5892.3518412974645;5894.115882617599;5896.204012458317;5909.734061967211;4606.355110284563;4656.930146047804;4663.976680116594;4707.835225767099;4746.810127594892;4721.126591580569;4726.7400292983875;4732.810687572493;4737.076270127326;4737.881552961169;4674.392328997243;4678.251479763238;4678.391809064834;4682.578567928886;4691.530846915315;4409.669274804386;4452.3989261449615;4592.288195329746;4650.906981860344;4661.86014419568;4122.91754164918;4123.8556650517085;4136.607022332873;4139.710220284582;4150.896227594304;5463.562914966793;5596.888062238773;5619.044762390404;5622.522085622727;5647.915281270784;5214.60890433219;5308.840734777924;5357.062514060063;5360.91958620621;5364.088105022117;6241.96879015602;6245.937540624581;6253.374662533755;6254.155688981975;6257;4509.323601459774;4513.345564893105;4514.345419914094;4514.413126293593;4516.616087632555;6310.211223597051;6469.77355792545;6478.416942475153;6489;6533.183352080942;4704.317873908332;4705.623550115983;4706.1999460091865;4707.176244157259;4708.576228139484;4759.881002974884;4765.4996225395835;4768.523147685204;4770;4771.045790841846;5325.041492531382;5417.079096553595;5418.891622167614;5420.810271640474;5425.186222066722;6383.585066970632;6409.262934762469;6409.743610255631;6412.070249813844;6417.101605775195;1721.8278172182931;1745.7294119411258;1746.737989301615;1747.6504699060238;1748;1186.4364426897328;1187.3113594115393;1188;1204.6386084174887;1205.2045649871043;6201.503879689614;6207.81376558704;6208.1308616793685;6209.9379006210265;6212.596181248295;6510.9023364649565;6581.078648989145;6582.473402127914;6585.012248162718;6585.967070164618;6064.241969753781;6131.264248290234;6150.384961503813;6195.628262304276;6196.256449226122;3614.548181477315;3640.7815531068222;3641.1443310821937;3642.18050938542;3650.2152037312107;6694.732210711517;6704.530682852153;6732.3267673232285;6747.797566073025;6754.797356079325;6504.479641628658;6569.343065693392;6715.6977935993;6721.462283017347;6837.965810170917;6804.897926531205;6810.795676129724;6838.179418469816;6848.589084654936;6858.074159988438;6613.768518101843;6621.106150669791;6641.568298060611;6641.933580664131;6650.3682150195655;6440.871182576228;6556.114924485133;6561.179852518435;6563.442107420923;6569.113169722217;6808.421284190701;6842.213145488134;6842.623655698785;6848.863022739617;6852.246252912275;4701.435827700698;4712.575868171881;4714.764261786965;4718.811247550197;4719.032598317268;6485.44873685742;6599.142111525471;6607.174103237096;6607.537472376887;6614.404703576702;6414.550981431255;6430.196225471816;6432.742690292336;6432.9678351607945;6434.453071488976;6411.134496842867;6419.101325814297;6425.8714825704155;6426.100345951477;6439.967800160969;6250;6356.4279214870885;6357.618542887441;6358.77744278948;6365.777197798055;6473.287938326875;6476.902846457401;6479.384458476437;6480.773172938924;6495.480361570969;6189.752409903554;6220.533459990609;6265.122882796503;6272.372762723874;6280.937190628218 04:29:21 INFO - 2015-12-16 04:29:21,416 DEBUG : BROWSER_OUTPUT: |5;yui.html;935.5182081228164;947.5309721687764;953.2612225525219;953.3517208298355;973.6981535723926;14212.786808197872;14342.784858227134;14358.425662973468;14373.784393234097;14391.06458080224;13340.332983350818;13379.39792709328;13543.119697219698;13545.390457429414;13568.321583920813;755.207032615754;762.9771106866802;928.4104593583089;929.5445231836402;937.3719607862748;932.664240873285;933.2440723014346;936.6393938333722;940.4686352210979;940.9764755881089;979.1872745621117;984.601236499219;987.3828856964398;987.4272921705393;987.4420952162005;795.0697683710073;804.5735760047185;964.320154291223;969.0406497567428;975.3708857786759;702.3678689179728;719.0652152202168;719.3777382564097;719.7804669575777;720.1718024272077;14451.349689263903;14485.927570362186;14558.781618275734;14572.70854582913;14577.562673119823;13237.933810330984;13725.862741372559;13969;14068.437262509487;14119.294035298297;10624.734381640521;10652.786944261148;10738.140948724084;10769.138468922545;10809.135269178447;3837.2901013312626;3850.4224366344993;3888.6305800948867;3893.469244990779;3938.2911076006308;52646.21030684543;52691;53678.73160634211;55638.44361556373;55691;11133.888661113448;11237.3257604544;11315.83026254607;11420.88579114215;11440.94279528597;20637.896810515853;20662;20664.276750313667;20673.17307307691;20675.276365327143;7581.3176814087;7697.345725613273;7948.523088614586;8359.749207523784;8466;4884.853454396374;4887.36464259644;5010.173321401928;5021.723805190676;5026.773795179226;2866.498362786504;2889.2054684961804;2900.115464783219;2906.447774922758;2908.0548821632633;456.76933148760327;464.98837529061365;468.6344651172023;471.5920728569831;477.50578151612916;709.443087176564;753.4575105923726;774.9883751743729;784.1726978038224;787.8227398835216;9818.852717209247;9990.400575965465;10058.698239052694;10074.798504029879;10095.646652367132;707.844274259662;731.2175971710325;735.6285076036568;753.1376573823052;755.3881356101575;4662.277347011219;4682.672212945129;4722.598579120813;4724.385829842098;4760.095581839509;62583;69782.3021769786;69876.301236988;69934.3006569938;70139;53647.46352536503;53775.73112134414;54395;54572;54829;54254.186187207226;54282;54520.90958180814;54532;55183.72408137934;9944.254180936603;10121.949390253296;10126.341787783771;10149.84775228387;10342.172626189737;39661.405078924414;40638.39042414425;40884.79557602193;40886;41969.58030419657;50979;51423.48576514187;51944.74027629838;52382;52620.73689631528;8843.557822108998;8970;9091.727248182566;9385.953070234606;9402.482863442576;12628.621141365773;13828.170309781446;13864.584062478141;14171.307451584165;14654.706905862036;12399.814002790146;12476.313802740937;12888.291143986795;13106.281738574296;13142.34288285601;12730.872691272969;13126.868731312565;13307.334633268492;13317;13352.799708004582;12930.482780688668;13309.933450332686;13339.599812005656;13355;13407.396667149807;10217.948910255402;10781.730456738644;11247.325160490396;11298.209125361145;11413.942930285295;10284.382937023802;10570.735731606772;10829.729256768329;10836.891631083903;11092 04:29:21 INFO - 2015-12-16 04:29:21,416 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:29:21 INFO - 2015-12-16 04:29:21,416 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:29:21 INFO - 2015-12-16 04:29:21,416 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,824 04:29:21 INFO - 2015-12-16 04:29:21,416 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:29:21 INFO - 2015-12-16 04:29:21,416 DEBUG : BROWSER_OUTPUT: __startTimestamp1450268961402__endTimestamp 04:29:21 INFO - 2015-12-16 04:29:21,417 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,417 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:29:21 INFO - 2015-12-16 04:29:21,417 DEBUG : BROWSER_OUTPUT: Number of tests: 6 04:29:21 INFO - 2015-12-16 04:29:21,417 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,418 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5761.00 Median:6022.46 stddev:663.01 (11.0%) stddev-sans-first:638.04 04:29:21 INFO - 2015-12-16 04:29:21,418 DEBUG : BROWSER_OUTPUT: Values: 3373.5 3398.9 3423.5 3444.0 3465.7 5733.5 5740.0 5745.8 5752.9 5764.8 5755.9 5766.1 5768.9 5805.4 5822.2 5080.2 5218.4 5245.3 5312.6 5335.8 5258.6 5262.2 5285.2 5285.7 5286.4 5014.1 5194.0 5206.3 5247.2 5259.1 5189.4 5297.0 5312.9 5315.0 5323.9 5263.4 5266.0 5274.3 5282.6 5322.8 5365.0 5453.3 5457.8 5564.4 5628.6 6203.0 6240.5 6268.4 6268.6 6292.1 6308.4 6310.0 6325.3 6330.0 6330.1 5624.6 5724.4 5748.1 5891.9 5927.4 6359.0 6365.3 6367.3 6368.4 6374.3 6008.2 6124.7 6170.9 6176.5 6179.6 6192.9 6204.7 6335.0 6335.7 6336.6 6250.5 6250.8 6260.1 6265.3 6266.4 5912.8 6061.0 6232.0 6247.8 6249.8 4704.4 4706.2 4707.3 4710.3 4710.6 4108.5 4198.2 4226.1 4272.2 4282.8 5886.1 5895.7 5931.4 5941.3 5952.3 6214.9 6233.7 6240.2 6245.7 6247.8 5675.5 5734.6 5823.1 5850.2 5854.2 5372.3 5414.6 5421.7 5425.3 5425.6 6169.0 6456.8 6457.9 6461.9 6467.7 6092.4 6112.0 6217.3 6255.3 6258.7 6405.6 6509.6 6517.9 6523.2 6530.8 6022.5 6123.8 6181.7 6239.7 6248.5 6064.7 6152.1 6152.7 6154.9 6157.9 6042.1 6078.4 6083.9 6087.8 6089.0 5944.2 6064.2 6066.0 6066.3 6092.1 6025.8 6029.8 6036.5 6037.2 6047.8 6083.7 6141.6 6147.0 6147.1 6148.6 5694.9 5792.6 5951.2 5954.0 6109.5 04:29:21 INFO - 2015-12-16 04:29:21,418 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,419 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:56772.48 Median:200832.50 stddev:99877.49 (49.7%) stddev-sans-first:100068.60 04:29:21 INFO - 2015-12-16 04:29:21,420 DEBUG : BROWSER_OUTPUT: Values: 590.3 635.2 640.4 655.4 665.9 34586.8 45095.5 45529.0 45544.8 45560.5 48539.8 49174.3 49223.0 49223.3 49225.8 8229.6 8329.9 9195.4 9365.7 10646.8 10972.2 11050.7 11084.3 11091.2 11093.9 11133.9 11174.8 11227.0 11243.5 11250.4 11089.8 11105.1 11181.7 11189.9 11196.2 10829.6 10995.4 11024.4 11026.6 11050.2 44116.1 44218.8 44320.0 44549.6 44716.3 3596.6 3646.3 3702.3 3749.4 4343.7 33461.5 33612.0 33639.5 33715.8 33909.2 3954.5 3961.7 3986.6 3990.6 4077.5 315485.0 316613.0 317048.0 317168.0 317753.0 58952.0 60299.4 68126.0 68570.0 68583.7 73350.0 73855.6 74408.6 74559.3 74967.6 16426.3 16580.7 16606.0 16606.2 16610.6 12342.3 12351.5 12356.2 12365.8 12379.7 22141.9 22292.3 22347.6 22370.6 22416.8 7870.9 7997.5 8131.3 8164.4 8208.8 973.5 1016.3 1022.1 1037.1 1141.1 30746.7 31392.2 31482.5 35586.5 36124.8 1139.7 1152.9 1171.1 1228.1 1255.6 14778.9 17010.1 17127.7 17246.1 17336.3 443772.0 446636.0 447170.0 449060.0 450578.0 62876.0 62906.4 62995.0 63441.4 63472.0 1554.9 1570.7 1571.0 1580.4 1587.5 32384.2 32941.5 35280.6 35762.0 35919.3 56807.4 57387.0 57491.1 57546.7 57573.0 361100.0 363982.0 364873.0 365077.0 367529.0 31923.7 34915.0 37400.4 37474.4 37654.8 42694.6 43067.8 43607.6 43894.0 44105.6 45983.0 47372.1 48206.0 48436.8 48769.0 39519.4 41750.8 42306.2 42330.4 46558.5 40396.2 40917.0 41094.0 41479.8 41492.2 35160.3 36319.5 36588.0 36764.2 37484.3 28320.4 28506.6 28532.0 28668.9 28716.4 04:29:21 INFO - 2015-12-16 04:29:21,420 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,420 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31561.33 Median:22429.32 stddev:104356.95 (465.3%) stddev-sans-first:104623.43 04:29:21 INFO - 2015-12-16 04:29:21,420 DEBUG : BROWSER_OUTPUT: Values: 376.6 383.2 385.1 385.6 392.2 15715.9 17882.2 18087.6 19352.8 19355.3 16965.2 17178.2 17417.5 17932.4 18093.5 5678.2 5842.4 5855.9 5859.1 5862.4 4336.2 4444.1 5768.8 6160.1 6328.4 6074.1 6162.8 6237.9 6261.3 6265.2 6201.4 6227.2 6233.2 6237.9 6238.3 2868.6 5314.0 5497.1 5506.6 5510.2 21893.9 22051.7 22208.8 22611.8 22805.0 2367.5 2405.1 2459.4 2482.7 2498.6 13984.0 14094.4 14203.8 14298.9 14677.6 1826.8 1894.4 1985.2 2003.9 2016.1 62843.7 63952.5 64118.0 65131.3 65486.0 10359.7 10798.3 11118.2 11158.9 11224.7 14406.6 14413.6 14432.9 14435.9 14485.3 8214.3 8266.3 8456.8 8512.1 8518.2 8182.8 8196.2 8231.3 8270.5 8276.8 6496.2 6598.6 6603.7 6609.9 6611.4 1733.9 1798.3 2001.6 2081.4 2082.4 477.7 485.7 496.7 508.9 512.7 11003.0 12589.0 13006.3 13077.1 13253.1 519.8 530.7 544.5 549.0 549.4 6981.9 7175.4 7208.7 7236.8 7258.5 621955.0 624912.0 641039.0 642232.0 660639.0 80312.2 81268.0 82714.0 83788.0 84095.6 579.8 592.7 597.5 597.9 598.3 10956.0 11579.8 11634.9 11950.8 11951.3 35876.3 36958.6 37246.0 39173.4 40522.4 51989.5 52313.0 52931.7 53173.5 53823.7 7180.0 7235.1 7304.1 7330.6 7358.4 10336.4 12744.8 12872.5 12979.8 13132.0 10958.6 10977.4 11095.1 11117.1 11139.1 10742.4 10802.3 10883.4 10896.5 10997.8 11053.6 11152.2 11323.0 11410.6 11453.9 7225.6 7228.2 7313.2 7346.5 7352.3 11045.7 11182.9 11355.1 11383.7 11409.5 04:29:21 INFO - 2015-12-16 04:29:21,420 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,420 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5902.36 Median:6312.13 stddev:3004.64 (47.6%) stddev-sans-first:2995.42 04:29:21 INFO - 2015-12-16 04:29:21,422 DEBUG : BROWSER_OUTPUT: Values: 1570.5 1579.5 1828.6 1862.9 1873.5 7365.8 7465.4 8335.3 8560.6 8561.5 8013.7 8106.6 8207.2 8222.6 8222.8 2419.0 2458.9 2460.2 2461.1 2462.5 2106.7 2146.7 2422.1 2422.4 2509.5 2397.4 2410.6 2439.2 2440.9 2441.7 2411.2 2437.1 2437.6 2439.5 2440.6 2384.9 2417.3 2421.2 2422.1 2426.0 8303.5 8347.0 8371.3 8372.8 8373.2 5122.1 5184.1 5197.2 5199.9 5201.4 3251.7 3298.1 3390.1 3405.3 3418.4 1177.8 1183.9 1217.3 1218.1 1227.0 11273.9 11305.8 11311.3 11332.7 11333.8 4540.1 4548.9 4707.2 4744.3 4750.3 9745.7 9752.7 9769.4 9770.6 9772.7 5202.2 5206.1 5210.9 5213.0 5213.8 4663.8 4681.2 4690.6 4691.3 4691.4 3593.3 4121.9 4140.3 4206.5 4254.4 1256.1 1367.4 1374.3 1390.8 1396.4 1148.7 1162.9 1254.9 1465.4 1484.3 7945.5 8058.5 8077.5 8079.6 8087.4 1240.9 1243.9 1246.2 1271.8 1293.4 6274.1 6285.1 6339.2 6345.8 6346.2 9931.6 10052.5 10062.4 10091.2 10093.3 9875.0 9875.9 9961.2 9980.1 9997.5 7811.8 7877.2 7877.9 7889.6 7894.1 7358.2 7391.8 7394.3 7476.3 7592.2 8583.0 8891.2 9087.5 9094.7 9128.2 9065.1 9246.4 9253.1 9364.9 9446.4 7316.5 7319.8 7401.7 7415.0 7424.1 7452.6 7587.0 8122.6 8264.0 8277.1 7642.2 7730.2 7734.8 7753.9 7893.4 7655.9 7705.3 7721.8 7782.6 7799.1 7784.1 7787.8 7827.6 7844.8 7865.4 7156.0 7171.7 7190.1 7191.5 7202.3 7028.9 7052.2 7066.7 7109.2 7152.6 04:29:21 INFO - 2015-12-16 04:29:21,422 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,422 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5371.88 Median:6097.75 stddev:1569.49 (25.7%) stddev-sans-first:1527.95 04:29:21 INFO - 2015-12-16 04:29:21,422 DEBUG : BROWSER_OUTPUT: Values: 349.5 355.9 357.0 369.2 373.2 5952.2 5966.3 5992.3 6014.4 6022.0 5886.9 5892.4 5894.1 5896.2 5909.7 4606.4 4656.9 4664.0 4707.8 4746.8 4721.1 4726.7 4732.8 4737.1 4737.9 4674.4 4678.3 4678.4 4682.6 4691.5 4409.7 4452.4 4592.3 4650.9 4661.9 4122.9 4123.9 4136.6 4139.7 4150.9 5463.6 5596.9 5619.0 5622.5 5647.9 5214.6 5308.8 5357.1 5360.9 5364.1 6242.0 6245.9 6253.4 6254.2 6257.0 4509.3 4513.3 4514.3 4514.4 4516.6 6310.2 6469.8 6478.4 6489.0 6533.2 4704.3 4705.6 4706.2 4707.2 4708.6 4759.9 4765.5 4768.5 4770.0 4771.0 5325.0 5417.1 5418.9 5420.8 5425.2 6383.6 6409.3 6409.7 6412.1 6417.1 1721.8 1745.7 1746.7 1747.7 1748.0 1186.4 1187.3 1188.0 1204.6 1205.2 6201.5 6207.8 6208.1 6209.9 6212.6 6510.9 6581.1 6582.5 6585.0 6586.0 6064.2 6131.3 6150.4 6195.6 6196.3 3614.5 3640.8 3641.1 3642.2 3650.2 6694.7 6704.5 6732.3 6747.8 6754.8 6504.5 6569.3 6715.7 6721.5 6838.0 6804.9 6810.8 6838.2 6848.6 6858.1 6613.8 6621.1 6641.6 6641.9 6650.4 6440.9 6556.1 6561.2 6563.4 6569.1 6808.4 6842.2 6842.6 6848.9 6852.2 4701.4 4712.6 4714.8 4718.8 4719.0 6485.4 6599.1 6607.2 6607.5 6614.4 6414.6 6430.2 6432.7 6433.0 6434.5 6411.1 6419.1 6425.9 6426.1 6440.0 6250.0 6356.4 6357.6 6358.8 6365.8 6473.3 6476.9 6479.4 6480.8 6495.5 6189.8 6220.5 6265.1 6272.4 6280.9 04:29:21 INFO - 2015-12-16 04:29:21,422 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,422 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15519.84 Median:2575.65 stddev:18349.00 (712.4%) stddev-sans-first:18367.78 04:29:21 INFO - 2015-12-16 04:29:21,422 DEBUG : BROWSER_OUTPUT: Values: 935.5 947.5 953.3 953.4 973.7 14212.8 14342.8 14358.4 14373.8 14391.1 13340.3 13379.4 13543.1 13545.4 13568.3 755.2 763.0 928.4 929.5 937.4 932.7 933.2 936.6 940.5 941.0 979.2 984.6 987.4 987.4 987.4 795.1 804.6 964.3 969.0 975.4 702.4 719.1 719.4 719.8 720.2 14451.3 14485.9 14558.8 14572.7 14577.6 13237.9 13725.9 13969.0 14068.4 14119.3 10624.7 10652.8 10738.1 10769.1 10809.1 3837.3 3850.4 3888.6 3893.5 3938.3 52646.2 52691.0 53678.7 55638.4 55691.0 11133.9 11237.3 11315.8 11420.9 11440.9 20637.9 20662.0 20664.3 20673.2 20675.3 7581.3 7697.3 7948.5 8359.7 8466.0 4884.9 4887.4 5010.2 5021.7 5026.8 2866.5 2889.2 2900.1 2906.4 2908.1 456.8 465.0 468.6 471.6 477.5 709.4 753.5 775.0 784.2 787.8 9818.9 9990.4 10058.7 10074.8 10095.6 707.8 731.2 735.6 753.1 755.4 4662.3 4682.7 4722.6 4724.4 4760.1 62583.0 69782.3 69876.3 69934.3 70139.0 53647.5 53775.7 54395.0 54572.0 54829.0 54254.2 54282.0 54520.9 54532.0 55183.7 9944.3 10121.9 10126.3 10149.8 10342.2 39661.4 40638.4 40884.8 40886.0 41969.6 50979.0 51423.5 51944.7 52382.0 52620.7 8843.6 8970.0 9091.7 9386.0 9402.5 12628.6 13828.2 13864.6 14171.3 14654.7 12399.8 12476.3 12888.3 13106.3 13142.3 12730.9 13126.9 13307.3 13317.0 13352.8 12930.5 13309.9 13339.6 13355.0 13407.4 10217.9 10781.7 11247.3 11298.2 11413.9 10284.4 10570.7 10829.7 10836.9 11092.0 04:29:21 INFO - 2015-12-16 04:29:21,422 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:29:21 INFO - 2015-12-16 04:29:21,422 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,988 INFO : Browser exited with error code: 0 04:29:22 INFO - 2015-12-16 04:29:22,004 INFO : Completed test dromaeo_css (00:18:29) 04:29:22 INFO - 2015-12-16 04:29:22,004 INFO : Starting test kraken 04:29:22 INFO - 2015-12-16 04:29:22,004 DEBUG : operating with platform_type : linux_ 04:29:22 INFO - 2015-12-16 04:29:22,004 INFO : Initialising browser for kraken test... 04:29:22 INFO - 2015-12-16 04:29:22,010 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpiOX2s6/profile http://localhost:55935/getInfo.html 04:29:25 INFO - 2015-12-16 04:29:25,617 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:29:25 INFO - 2015-12-16 04:29:25,617 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:29:25 INFO - 2015-12-16 04:29:25,617 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:29:25 INFO - 2015-12-16 04:29:25,617 DEBUG : BROWSER_OUTPUT: __metrics 04:29:25 INFO - 2015-12-16 04:29:25,678 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] 04:29:26 INFO - 2015-12-16 04:29:26,881 INFO : Browser initialized. 04:29:26 INFO - 2015-12-16 04:29:26,881 INFO : Running cycle 1/1 for kraken test... 04:29:26 INFO - 2015-12-16 04:29:26,881 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpiOX2s6/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:29:38 INFO - 2015-12-16 04:29:38,538 DEBUG : BROWSER_OUTPUT: RSS: Main: 131239936 04:29:38 INFO - 2015-12-16 04:29:38,538 DEBUG : BROWSER_OUTPUT: 04:29:40 INFO - 2015-12-16 04:29:40,958 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55935/tests/kraken/driver.html?testName=audio-beat-detection) 04:29:41 INFO - 2015-12-16 04:29:41,525 DEBUG : BROWSER_OUTPUT: RSS: Main: 164093952 04:29:41 INFO - 2015-12-16 04:29:41,525 DEBUG : BROWSER_OUTPUT: 04:29:44 INFO - 2015-12-16 04:29:44,073 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55935/tests/kraken/driver.html?testName=audio-dft) 04:29:44 INFO - 2015-12-16 04:29:44,636 DEBUG : BROWSER_OUTPUT: RSS: Main: 161583104 04:29:44 INFO - 2015-12-16 04:29:44,636 DEBUG : BROWSER_OUTPUT: 04:29:47 INFO - 2015-12-16 04:29:47,402 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55935/tests/kraken/driver.html?testName=audio-fft) 04:29:47 INFO - 2015-12-16 04:29:47,963 DEBUG : BROWSER_OUTPUT: RSS: Main: 158310400 04:29:47 INFO - 2015-12-16 04:29:47,963 DEBUG : BROWSER_OUTPUT: 04:29:49 INFO - 2015-12-16 04:29:49,917 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55935/tests/kraken/driver.html?testName=audio-oscillator) 04:29:50 INFO - 2015-12-16 04:29:50,478 DEBUG : BROWSER_OUTPUT: RSS: Main: 157061120 04:29:50 INFO - 2015-12-16 04:29:50,478 DEBUG : BROWSER_OUTPUT: 04:29:52 INFO - 2015-12-16 04:29:52,578 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55935/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:29:53 INFO - 2015-12-16 04:29:53,140 DEBUG : BROWSER_OUTPUT: RSS: Main: 156778496 04:29:53 INFO - 2015-12-16 04:29:53,140 DEBUG : BROWSER_OUTPUT: 04:29:56 INFO - 2015-12-16 04:29:56,002 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55935/tests/kraken/driver.html?testName=imaging-darkroom) 04:29:56 INFO - 2015-12-16 04:29:56,564 DEBUG : BROWSER_OUTPUT: RSS: Main: 157229056 04:29:56 INFO - 2015-12-16 04:29:56,564 DEBUG : BROWSER_OUTPUT: 04:29:59 INFO - 2015-12-16 04:29:59,886 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55935/tests/kraken/driver.html?testName=imaging-desaturate) 04:30:00 INFO - 2015-12-16 04:30:00,452 DEBUG : BROWSER_OUTPUT: RSS: Main: 157532160 04:30:00 INFO - 2015-12-16 04:30:00,452 DEBUG : BROWSER_OUTPUT: 04:30:03 INFO - 2015-12-16 04:30:03,213 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55935/tests/kraken/driver.html?testName=json-parse-financial) 04:30:03 INFO - 2015-12-16 04:30:03,779 DEBUG : BROWSER_OUTPUT: RSS: Main: 155586560 04:30:03 INFO - 2015-12-16 04:30:03,779 DEBUG : BROWSER_OUTPUT: 04:30:05 INFO - 2015-12-16 04:30:05,286 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55935/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:30:05 INFO - 2015-12-16 04:30:05,852 DEBUG : BROWSER_OUTPUT: RSS: Main: 162459648 04:30:05 INFO - 2015-12-16 04:30:05,852 DEBUG : BROWSER_OUTPUT: 04:30:07 INFO - 2015-12-16 04:30:07,552 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55935/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:30:08 INFO - 2015-12-16 04:30:08,142 DEBUG : BROWSER_OUTPUT: RSS: Main: 161157120 04:30:08 INFO - 2015-12-16 04:30:08,142 DEBUG : BROWSER_OUTPUT: 04:30:10 INFO - 2015-12-16 04:30:10,134 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55935/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:30:10 INFO - 2015-12-16 04:30:10,700 DEBUG : BROWSER_OUTPUT: RSS: Main: 166154240 04:30:10 INFO - 2015-12-16 04:30:10,700 DEBUG : BROWSER_OUTPUT: 04:30:13 INFO - 2015-12-16 04:30:13,015 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55935/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:30:13 INFO - 2015-12-16 04:30:13,588 DEBUG : BROWSER_OUTPUT: RSS: Main: 168198144 04:30:13 INFO - 2015-12-16 04:30:13,589 DEBUG : BROWSER_OUTPUT: 04:30:16 INFO - 2015-12-16 04:30:16,023 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55935/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:30:16 INFO - 2015-12-16 04:30:16,591 DEBUG : BROWSER_OUTPUT: RSS: Main: 166522880 04:30:16 INFO - 2015-12-16 04:30:16,591 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,168 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55935/tests/kraken/driver.html?testName=ai-astar) 04:30:18 INFO - 2015-12-16 04:30:18,418 DEBUG : BROWSER_OUTPUT: RSS: Main: 225746944 04:30:18 INFO - 2015-12-16 04:30:18,418 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,419 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:30:18 INFO - 2015-12-16 04:30:18,420 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:30:18 INFO - 2015-12-16 04:30:18,420 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:30:18 INFO - 2015-12-16 04:30:18,420 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:30:18 INFO - 2015-12-16 04:30:18,420 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;109;104;109;109;109;100;108;100;109 04:30:18 INFO - 2015-12-16 04:30:18,421 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;158;121;143;165;125;148;143;122;158;122 04:30:18 INFO - 2015-12-16 04:30:18,421 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;159;159;161;161;161;160;162;160;159 04:30:18 INFO - 2015-12-16 04:30:18,421 DEBUG : BROWSER_OUTPUT: |3;audio-fft;82;71;70;152;71;70;71;72;155;71 04:30:18 INFO - 2015-12-16 04:30:18,421 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;89;138;88;89;89;131;89;89;139 04:30:18 INFO - 2015-12-16 04:30:18,421 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;110;109;109;109 04:30:18 INFO - 2015-12-16 04:30:18,422 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;144;145;144;144;145;145;145;145;145 04:30:18 INFO - 2015-12-16 04:30:18,422 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;95;96;95;97;95 04:30:18 INFO - 2015-12-16 04:30:18,422 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;53;57;55;55;57;55;55;55;52;52 04:30:18 INFO - 2015-12-16 04:30:18,422 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;63;63;63;63;63;62;63;63;63 04:30:18 INFO - 2015-12-16 04:30:18,423 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;87;77;86;77;78;78;77;77;86;80 04:30:18 INFO - 2015-12-16 04:30:18,423 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;116;115;108;107;119;113;114;115;106;116 04:30:18 INFO - 2015-12-16 04:30:18,423 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;139;142;138;133;135;148;135;134;126 04:30:18 INFO - 2015-12-16 04:30:18,423 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;62;62;61;64;59;61;60;60;60 04:30:18 INFO - 2015-12-16 04:30:18,423 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:30:18 INFO - 2015-12-16 04:30:18,423 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:30:18 INFO - 2015-12-16 04:30:18,423 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,867 04:30:18 INFO - 2015-12-16 04:30:18,424 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:30:18 INFO - 2015-12-16 04:30:18,424 DEBUG : BROWSER_OUTPUT: __startTimestamp1450269018419__endTimestamp 04:30:18 INFO - 2015-12-16 04:30:18,424 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,427 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:30:18 INFO - 2015-12-16 04:30:18,428 DEBUG : BROWSER_OUTPUT: Number of tests: 14 04:30:18 INFO - 2015-12-16 04:30:18,428 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,428 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.50 Median:109.00 stddev:3.75 (3.4%) stddev-sans-first:3.94 04:30:18 INFO - 2015-12-16 04:30:18,428 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 104.0 109.0 109.0 109.0 100.0 108.0 100.0 109.0 04:30:18 INFO - 2015-12-16 04:30:18,429 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,429 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.50 Median:145.50 stddev:16.95 (11.7%) stddev-sans-first:16.76 04:30:18 INFO - 2015-12-16 04:30:18,429 DEBUG : BROWSER_OUTPUT: Values: 158.0 121.0 143.0 165.0 125.0 148.0 143.0 122.0 158.0 122.0 04:30:18 INFO - 2015-12-16 04:30:18,429 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,429 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.20 Median:160.50 stddev:1.03 (0.6%) stddev-sans-first:1.09 04:30:18 INFO - 2015-12-16 04:30:18,429 DEBUG : BROWSER_OUTPUT: Values: 160.0 159.0 159.0 161.0 161.0 161.0 160.0 162.0 160.0 159.0 04:30:18 INFO - 2015-12-16 04:30:18,430 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,430 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.50 Median:71.00 stddev:34.45 (48.5%) stddev-sans-first:36.45 04:30:18 INFO - 2015-12-16 04:30:18,430 DEBUG : BROWSER_OUTPUT: Values: 82.0 71.0 70.0 152.0 71.0 70.0 71.0 72.0 155.0 71.0 04:30:18 INFO - 2015-12-16 04:30:18,430 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,430 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.00 Median:89.00 stddev:22.87 (25.7%) stddev-sans-first:23.69 04:30:18 INFO - 2015-12-16 04:30:18,430 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 138.0 88.0 89.0 89.0 131.0 89.0 89.0 139.0 04:30:18 INFO - 2015-12-16 04:30:18,430 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,431 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:30:18 INFO - 2015-12-16 04:30:18,431 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 04:30:18 INFO - 2015-12-16 04:30:18,431 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,431 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:144.80 Median:145.00 stddev:0.63 (0.4%) stddev-sans-first:0.50 04:30:18 INFO - 2015-12-16 04:30:18,431 DEBUG : BROWSER_OUTPUT: Values: 146.0 144.0 145.0 144.0 144.0 145.0 145.0 145.0 145.0 145.0 04:30:18 INFO - 2015-12-16 04:30:18,431 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,431 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 04:30:18 INFO - 2015-12-16 04:30:18,432 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 97.0 95.0 04:30:18 INFO - 2015-12-16 04:30:18,432 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,432 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:1.78 (3.2%) stddev-sans-first:1.79 04:30:18 INFO - 2015-12-16 04:30:18,432 DEBUG : BROWSER_OUTPUT: Values: 53.0 57.0 55.0 55.0 57.0 55.0 55.0 55.0 52.0 52.0 04:30:18 INFO - 2015-12-16 04:30:18,432 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,432 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:62.90 Median:63.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 04:30:18 INFO - 2015-12-16 04:30:18,432 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 63.0 63.0 63.0 63.0 62.0 63.0 63.0 63.0 04:30:18 INFO - 2015-12-16 04:30:18,432 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,433 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:79.00 stddev:4.27 (5.4%) stddev-sans-first:3.78 04:30:18 INFO - 2015-12-16 04:30:18,433 DEBUG : BROWSER_OUTPUT: Values: 87.0 77.0 86.0 77.0 78.0 78.0 77.0 77.0 86.0 80.0 04:30:18 INFO - 2015-12-16 04:30:18,433 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,433 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:112.90 Median:115.00 stddev:4.38 (3.8%) stddev-sans-first:4.50 04:30:18 INFO - 2015-12-16 04:30:18,433 DEBUG : BROWSER_OUTPUT: Values: 116.0 115.0 108.0 107.0 119.0 113.0 114.0 115.0 106.0 116.0 04:30:18 INFO - 2015-12-16 04:30:18,433 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,433 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:136.50 Median:136.50 stddev:5.84 (4.3%) stddev-sans-first:6.16 04:30:18 INFO - 2015-12-16 04:30:18,433 DEBUG : BROWSER_OUTPUT: Values: 135.0 139.0 142.0 138.0 133.0 135.0 148.0 135.0 134.0 126.0 04:30:18 INFO - 2015-12-16 04:30:18,433 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,433 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.30 Median:61.50 stddev:1.70 (2.8%) stddev-sans-first:1.50 04:30:18 INFO - 2015-12-16 04:30:18,433 DEBUG : BROWSER_OUTPUT: Values: 64.0 62.0 62.0 61.0 64.0 59.0 61.0 60.0 60.0 60.0 04:30:18 INFO - 2015-12-16 04:30:18,433 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:30:18 INFO - 2015-12-16 04:30:18,433 DEBUG : BROWSER_OUTPUT: 04:30:19 INFO - 2015-12-16 04:30:19,096 INFO : Browser exited with error code: 0 04:30:19 INFO - 2015-12-16 04:30:19,144 INFO : Completed test kraken (00:00:57) 04:30:19 INFO - 2015-12-16 04:30:19,144 INFO : Starting test v8_7 04:30:19 INFO - 2015-12-16 04:30:19,144 DEBUG : operating with platform_type : linux_ 04:30:19 INFO - 2015-12-16 04:30:19,144 INFO : Initialising browser for v8_7 test... 04:30:19 INFO - 2015-12-16 04:30:19,150 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTU28v0/profile http://localhost:55935/getInfo.html 04:30:22 INFO - 2015-12-16 04:30:22,871 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:30:22 INFO - 2015-12-16 04:30:22,871 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:30:22 INFO - 2015-12-16 04:30:22,871 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:30:22 INFO - 2015-12-16 04:30:22,871 DEBUG : BROWSER_OUTPUT: __metrics 04:30:23 INFO - 2015-12-16 04:30:23,000 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] 04:30:24 INFO - 2015-12-16 04:30:24,071 INFO : Browser initialized. 04:30:24 INFO - 2015-12-16 04:30:24,072 INFO : Running cycle 1/1 for v8_7 test... 04:30:24 INFO - 2015-12-16 04:30:24,072 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTU28v0/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:30:35 INFO - 2015-12-16 04:30:35,713 DEBUG : BROWSER_OUTPUT: RSS: Main: 129269760 04:30:35 INFO - 2015-12-16 04:30:35,714 DEBUG : BROWSER_OUTPUT: 04:30:57 INFO - 2015-12-16 04:30:57,144 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55935/tests/v8_7/run.html (next: http://localhost:55935/tests/v8_7/run.html) 04:30:57 INFO - 2015-12-16 04:30:57,394 DEBUG : BROWSER_OUTPUT: RSS: Main: 427610112 04:30:57 INFO - 2015-12-16 04:30:57,395 DEBUG : BROWSER_OUTPUT: 04:30:57 INFO - 2015-12-16 04:30:57,396 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:30:57 INFO - 2015-12-16 04:30:57,396 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:30:57 INFO - 2015-12-16 04:30:57,396 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:30:57 INFO - 2015-12-16 04:30:57,396 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:30:57 INFO - 2015-12-16 04:30:57,396 DEBUG : BROWSER_OUTPUT: |0;Richards;163.88069485414618 04:30:57 INFO - 2015-12-16 04:30:57,397 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.7067258449983 04:30:57 INFO - 2015-12-16 04:30:57,397 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.19052956010086 04:30:57 INFO - 2015-12-16 04:30:57,397 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5005 04:30:57 INFO - 2015-12-16 04:30:57,397 DEBUG : BROWSER_OUTPUT: |4;RayTrace;950.5703422053232 04:30:57 INFO - 2015-12-16 04:30:57,397 DEBUG : BROWSER_OUTPUT: |5;Earley;554.3237250554324 04:30:57 INFO - 2015-12-16 04:30:57,397 DEBUG : BROWSER_OUTPUT: |6;Boyer;10437.5 04:30:57 INFO - 2015-12-16 04:30:57,397 DEBUG : BROWSER_OUTPUT: |7;RegExp;23604.6511627907 04:30:57 INFO - 2015-12-16 04:30:57,398 DEBUG : BROWSER_OUTPUT: |8;Splay;544.91341991342 04:30:57 INFO - 2015-12-16 04:30:57,398 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4817.307692307692 04:30:57 INFO - 2015-12-16 04:30:57,398 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:30:57 INFO - 2015-12-16 04:30:57,398 DEBUG : BROWSER_OUTPUT: __startTimestamp1450269057395__endTimestamp 04:30:57 INFO - 2015-12-16 04:30:57,398 DEBUG : BROWSER_OUTPUT: 04:30:57 INFO - 2015-12-16 04:30:57,398 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:30:57 INFO - 2015-12-16 04:30:57,399 DEBUG : BROWSER_OUTPUT: Number of tests: 10 04:30:57 INFO - 2015-12-16 04:30:57,399 DEBUG : BROWSER_OUTPUT: 04:30:57 INFO - 2015-12-16 04:30:57,399 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.88 Median:163.88 stddev:0.00 (0.0%) 04:30:57 INFO - 2015-12-16 04:30:57,399 DEBUG : BROWSER_OUTPUT: Values: 163.9 04:30:57 INFO - 2015-12-16 04:30:57,399 DEBUG : BROWSER_OUTPUT: 04:30:57 INFO - 2015-12-16 04:30:57,400 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.71 Median:170.71 stddev:0.00 (0.0%) 04:30:57 INFO - 2015-12-16 04:30:57,400 DEBUG : BROWSER_OUTPUT: Values: 170.7 04:30:57 INFO - 2015-12-16 04:30:57,400 DEBUG : BROWSER_OUTPUT: 04:30:57 INFO - 2015-12-16 04:30:57,401 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.19 Median:280.19 stddev:0.00 (0.0%) 04:30:57 INFO - 2015-12-16 04:30:57,401 DEBUG : BROWSER_OUTPUT: Values: 280.2 04:30:57 INFO - 2015-12-16 04:30:57,401 DEBUG : BROWSER_OUTPUT: 04:30:57 INFO - 2015-12-16 04:30:57,402 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5005.00 Median:5005.00 stddev:0.00 (0.0%) 04:30:57 INFO - 2015-12-16 04:30:57,402 DEBUG : BROWSER_OUTPUT: Values: 5005.0 04:30:57 INFO - 2015-12-16 04:30:57,402 DEBUG : BROWSER_OUTPUT: 04:30:57 INFO - 2015-12-16 04:30:57,402 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:950.57 Median:950.57 stddev:0.00 (0.0%) 04:30:57 INFO - 2015-12-16 04:30:57,402 DEBUG : BROWSER_OUTPUT: Values: 950.6 04:30:57 INFO - 2015-12-16 04:30:57,402 DEBUG : BROWSER_OUTPUT: 04:30:57 INFO - 2015-12-16 04:30:57,402 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:554.32 Median:554.32 stddev:0.00 (0.0%) 04:30:57 INFO - 2015-12-16 04:30:57,403 DEBUG : BROWSER_OUTPUT: Values: 554.3 04:30:57 INFO - 2015-12-16 04:30:57,403 DEBUG : BROWSER_OUTPUT: 04:30:57 INFO - 2015-12-16 04:30:57,403 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10437.50 Median:10437.50 stddev:0.00 (0.0%) 04:30:57 INFO - 2015-12-16 04:30:57,403 DEBUG : BROWSER_OUTPUT: Values: 10437.5 04:30:57 INFO - 2015-12-16 04:30:57,403 DEBUG : BROWSER_OUTPUT: 04:30:57 INFO - 2015-12-16 04:30:57,403 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23604.65 Median:23604.65 stddev:0.00 (0.0%) 04:30:57 INFO - 2015-12-16 04:30:57,403 DEBUG : BROWSER_OUTPUT: Values: 23604.7 04:30:57 INFO - 2015-12-16 04:30:57,404 DEBUG : BROWSER_OUTPUT: 04:30:57 INFO - 2015-12-16 04:30:57,404 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:544.91 Median:544.91 stddev:0.00 (0.0%) 04:30:57 INFO - 2015-12-16 04:30:57,404 DEBUG : BROWSER_OUTPUT: Values: 544.9 04:30:57 INFO - 2015-12-16 04:30:57,404 DEBUG : BROWSER_OUTPUT: 04:30:57 INFO - 2015-12-16 04:30:57,404 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4817.31 Median:4817.31 stddev:0.00 (0.0%) 04:30:57 INFO - 2015-12-16 04:30:57,404 DEBUG : BROWSER_OUTPUT: Values: 4817.3 04:30:57 INFO - 2015-12-16 04:30:57,404 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:30:57 INFO - 2015-12-16 04:30:57,405 DEBUG : BROWSER_OUTPUT: 04:30:58 INFO - 2015-12-16 04:30:58,012 INFO : Browser exited with error code: 0 04:30:58 INFO - 2015-12-16 04:30:58,030 INFO : Completed test v8_7 (00:00:38) 04:30:58 INFO - 2015-12-16 04:30:58,375 INFO : Completed test suite (00:20:06) 04:30:58 INFO - 2015-12-16 04:30:58,375 DEBUG : Working with test: dromaeo_css 04:30:58 INFO - 2015-12-16 04:30:58,376 DEBUG : Generating results file: dromaeo_css 04:30:58 INFO - 2015-12-16 04:30:58,377 DEBUG : Working with test: kraken 04:30:58 INFO - 2015-12-16 04:30:58,377 DEBUG : Generating results file: kraken 04:30:58 INFO - 2015-12-16 04:30:58,377 INFO : javascript benchmark 04:30:58 INFO - 2015-12-16 04:30:58,377 DEBUG : Working with test: v8_7 04:30:58 INFO - 2015-12-16 04:30:58,377 DEBUG : Generating results file: v8_7 04:30:58 INFO - 2015-12-16 04:30:58,378 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:30:58 INFO - 2015-12-16 04:30:58,435 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 04:30:58 INFO - 2015-12-16 04:30:58,435 DEBUG : process_Request line: dromaeo_css 7439.60 graph.html#tests=[[72,53,35]] 04:30:58 INFO - 2015-12-16 04:30:58,435 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:30:58 INFO - 2015-12-16 04:30:58,471 DEBUG : process_Request line: kraken 1456.40 graph.html#tests=[[232,53,35]] 04:30:58 INFO - 2015-12-16 04:30:58,471 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:30:58 INFO - 2015-12-16 04:30:58,506 DEBUG : process_Request line: v8_7 23266.59 graph.html#tests=[[230,53,35]] 04:30:58 INFO - 2015-12-16 04:30:58,508 INFO : javascript benchmark 04:30:58 INFO - 2015-12-16 04:30:58,511 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3373.4939759036142, 3398.8980330590093, 3423.5035919791617, 3443.9655603443975, 3465.688088072073, 5733.512651424634, 5740.0, 5745.798897038604, 5752.856178595537, 5764.827055188346, 5755.856103597413, 5766.106253530709, 5768.913466297999, 5805.390434004432, 5822.243108395914, 5080.1617733074, 5218.39988401334, 5245.291885595453, 5312.6281160318795, 5335.759890804923, 5258.579313654917, 5262.236975638531, 5285.180796976475, 5285.709285989269, 5286.365636123671, 5014.122528557488, 5194.013137503881, 5206.297149884755, 5247.1866860636665, 5259.053370393328, 5189.42916279209, 5297.046531624306, 5312.867178320573, 5315.0, 5323.946760532383, 5263.4473380295285, 5266.0, 5274.287971123926, 5282.577393808486, 5322.787088516455, 5364.9731751341, 5453.345598528163, 5457.836264912019, 5564.360098588633, 5628.577856660726, 6203.038529028008, 6240.469560087398, 6268.435840774307, 6268.561200715952, 6292.087647291117, 6308.369163083701, 6310.021946598285, 6325.272593651743, 6329.968350158266, 6330.145430366933, 5624.606277560572, 5724.370319264877, 5748.051571490699, 5891.882162356729, 5927.407259274081, 6358.968205158944, 6365.331640177715, 6367.331430199855, 6368.395002474757, 6374.26695929974, 6008.21893153896, 6124.724387402578, 6170.938290617126, 6176.474999625082, 6179.567430279837, 6192.938070619326, 6204.689765511706, 6334.968325158345, 6335.651539165298, 6336.588121772163, 6250.499960003189, 6250.781222657185, 6260.123582698426, 6265.310815810257, 6266.404691554295, 5912.822615321547, 6060.969695151584, 6232.0, 6247.75008999644, 6249.812505624838, 4704.435467743824, 4706.1528924793865, 4707.31743897133, 4710.29345598163, 4710.599599034052, 4108.548059713429, 4198.202341555095, 4226.1336426032585, 4272.209641216385, 4282.828686852553, 5886.087656413263, 5895.675737834375, 5931.436513531207, 5941.346451890288, 5952.255968003999, 6214.906776398358, 6233.688315584293, 6240.219972503437, 6245.718942647592, 6247.843803904939, 5675.4892059714, 5734.569907256972, 5823.097419899922, 5850.23946886902, 5854.18041474194, 5372.274742909735, 5414.593905457106, 5421.674699518042, 5425.348958125051, 5425.565954723613, 6169.0129579268, 6456.806295811133, 6457.935420645734, 6461.870762584722, 6467.708953097122, 6092.360302168386, 6112.015337453936, 6217.253929528486, 6255.280642726145, 6258.718357673824, 6405.551611387345, 6509.576877502947, 6517.934820651923, 6523.18460192476, 6530.8367290818105, 6022.45797878193, 6123.8162855114415, 6181.721822518088, 6239.656818874824, 6248.500119990299, 6064.666443345658, 6152.077188421773, 6152.661603611844, 6154.9384506154365, 6157.9384206157365, 6042.0967395270345, 6078.422549857756, 6083.878322433615, 6087.817365479043, 6088.969555152196, 5944.167816505606, 6064.151018857276, 6065.969670151621, 6066.3023752269055, 6092.086187071797, 6025.8192254232445, 6029.8191054268445, 6036.456718895321, 6037.154798328325, 6047.848803779764, 6083.66539840313, 6141.570090093651, 6146.969265153645, 6147.0779383092895, 6148.569600127948, 5694.914576281442, 5792.623479473821, 5951.22634057587, 5954.0, 6109.480694141048], "unit": "ms", "name": "dojo.html", "value": 5716.448783042989}, {"lowerIsBetter": false, "replicates": [590.3476658292586, 635.2377147423093, 640.4140211706289, 655.3512023097135, 665.9267480577136, 34586.8270658647, 45095.54904450954, 45528.999999999956, 45544.772276138654, 45560.54439455605, 48539.75730121353, 49174.262386064234, 49223.0, 49223.26165107526, 49225.753871230685, 8229.588520573978, 8329.916700833004, 9195.402298850553, 9365.719028429157, 10646.840297395545, 10972.177086718488, 11050.723731906686, 11084.279521831095, 11091.223614346998, 11093.889061109407, 11133.888661113408, 11174.776504469906, 11227.045701115416, 11243.494042768096, 11250.3812290324, 11089.778204435946, 11105.111591072695, 11181.72045698856, 11189.936955989167, 11196.160287978393, 10829.620963266249, 10995.395253261067, 11024.393658348788, 11026.558937642565, 11050.226484146113, 44116.11767764659, 44218.77890610559, 44320.0, 44549.55450445486, 44716.329255060875, 3596.604373518911, 3646.346194014239, 3702.278055779097, 3749.418840079792, 4343.6959412841115, 33461.49807752886, 33612.0, 33639.49540756891, 33715.83142084274, 33909.15227119317, 3954.5357492216567, 3961.6632586230203, 3986.6412022918075, 3990.6408423241755, 4077.510322497598, 315485.0, 316613.0, 317048.0, 317168.0, 317753.0, 58952.0, 60299.39700602938, 68126.0, 68570.0, 68583.65708171527, 73350.0, 73855.63072184604, 74408.62795685987, 74559.25440745632, 74967.62516187385, 16426.260818263207, 16580.668386632442, 16606.0, 16606.169691515377, 16610.58473538147, 12342.259464432163, 12351.505939762488, 12356.196847204903, 12365.814512782315, 12379.6905077372, 22141.889290553765, 22292.331230063122, 22347.55304893893, 22370.552588948456, 22416.775832241794, 7870.881936770953, 7997.520148791091, 8131.349492040623, 8164.387670924704, 8208.835823283502, 973.5473005052685, 1016.2632091733582, 1022.1209759606733, 1037.0873631204493, 1141.14414189358, 30746.69253307483, 31392.215194620316, 31482.527762083588, 35586.466203006974, 36124.81937590295, 1139.7207684117445, 1152.9423528823525, 1171.0982543441503, 1228.072805032195, 1255.5731051442554, 14778.926105369404, 17010.149492525325, 17127.74308385375, 17246.051467169174, 17336.30654773795, 443772.0, 446636.0, 447170.0, 449060.0, 450578.0, 62876.0, 62906.37093629005, 62995.0, 63441.365586344466, 63472.0, 1554.8833837462237, 1570.7408277634063, 1571.0, 1580.3599542185434, 1587.49200255917, 32384.190395240308, 32941.50587741138, 35280.64719352876, 35762.0, 35919.28161436809, 56807.43192568021, 57387.0, 57491.137632934704, 57546.7122664384, 57573.0, 361100.0, 363982.0, 364873.0, 365077.0, 367529.0, 31923.68076319207, 34914.9525514235, 37400.43899341457, 37474.437883432314, 37654.811725941196, 42694.57305426906, 43067.78466107775, 43607.56392436162, 43894.0, 44105.55894441014, 45983.0, 47372.05255894932, 48206.0, 48436.757816210695, 48769.0, 39519.407208891316, 41750.79124604358, 42306.15387692167, 42330.36504452497, 46558.53441465542, 40396.19207615773, 40917.0, 41094.0, 41479.7926010368, 41492.1701565961, 35160.29679406449, 36319.455208172425, 36588.0, 36764.16179191083, 37484.25031499409, 28320.43359132847, 28506.57240141358, 28532.0, 28668.856655716587, 28716.42567148687], "unit": "ms", "name": "ext.html", "value": 21343.533570385447}, {"lowerIsBetter": false, "replicates": [376.5895174260058, 383.21823480100613, 385.09694765774265, 385.610533361305, 392.1979551816535, 15715.921420392911, 17882.195301211443, 18087.638247235063, 19352.806471935277, 19355.322563710255, 16965.236564354604, 17178.226979785908, 17417.477475675718, 17932.37236696716, 18093.457196284133, 5678.233438485813, 5842.415758424145, 5855.912161317584, 5859.0625499920025, 5862.4430679085635, 4336.241157797389, 4444.133393988173, 5768.826935191951, 6160.106784516242, 6328.367163283681, 6074.149619053336, 6162.784302549389, 6237.906431403532, 6261.3425590313045, 6265.216847894013, 6201.379862013785, 6227.19046523949, 6233.2208473940755, 6237.906431403532, 6238.251409830804, 2868.641419822522, 5313.9637770634745, 5497.147942068986, 5506.559475242011, 5510.173473978895, 21893.890530547244, 22051.66922496164, 22208.777912220834, 22611.77388226113, 22805.0, 2367.4791545859885, 2405.0980882169188, 2459.446624509489, 2482.739312372193, 2498.6376975338476, 13984.0, 14094.365753541013, 14203.78694319586, 14298.857011429856, 14677.633059173499, 1826.7899191593003, 1894.355918987551, 1985.176151896961, 2003.929862454807, 2016.052455345985, 62843.68578157126, 63952.523323367386, 64118.0, 65131.348686513, 65486.0, 10359.741006474747, 10798.298110622783, 11118.166137539716, 11158.944205278922, 11224.66326010221, 14406.639834004234, 14413.639659008399, 14432.855671443362, 14435.92782036083, 14485.348159332856, 8214.301784348278, 8266.338692904674, 8456.788580285542, 8512.148785121439, 8518.23335899772, 8182.7545173644885, 8196.221358970888, 8231.34149268057, 8270.545120018456, 8276.8344633107, 6496.155499784998, 6598.571092879043, 6603.735850565923, 6609.933900661028, 6611.404973552403, 1733.9219641085667, 1798.2896755781655, 2001.6196922584663, 2081.39639504542, 2082.4481512399225, 477.68711493971506, 485.66003797342, 496.6697146397605, 508.905852417304, 512.7333786431035, 11002.994384905529, 12589.0, 13006.284654344103, 13077.084604077623, 13253.07228493996, 519.8492437193172, 530.6948504609865, 544.4528249109699, 548.9533389661923, 549.4231057389725, 6981.860362792716, 7175.425965922714, 7208.711651533985, 7236.782896513113, 7258.455615828834, 621955.0, 624912.0, 641039.0, 642232.0, 660639.0, 80312.19687803047, 81268.0, 82714.0, 83788.0, 84095.579522102, 579.8289504596239, 592.6681058607194, 597.5100417657566, 597.9282486101696, 598.308953159102, 10956.0, 11579.826302605636, 11634.883651163718, 11950.820737688768, 11951.282923024646, 35876.28247435089, 36958.63041369552, 37246.0, 39173.41239881461, 40522.39216411815, 51989.480105199975, 52313.0, 52931.735341323045, 53173.46826531685, 53823.73088134534, 7180.0, 7235.131784185932, 7304.050473438419, 7330.633468326456, 7358.374538164316, 10336.37981721069, 12744.808827867404, 12872.549460769204, 12979.805302920275, 13132.015098867432, 10958.561657533928, 10977.3962432067, 11095.056920161876, 11117.11063114965, 11139.05318047975, 10742.409167495864, 10802.297850639683, 10883.401412922365, 10896.455177241265, 10997.780044399227, 11053.613123540637, 11152.16358773079, 11323.0, 11410.600628977945, 11453.942730286628, 7225.602591857499, 7228.241034691282, 7313.158986716383, 7346.485745997729, 7352.338289553752, 11045.668629941114, 11182.944085279847, 11355.091592672401, 11383.715407114558, 11409.54361825551], "unit": "ms", "name": "jquery.html", "value": 8384.895561719826}, {"lowerIsBetter": false, "replicates": [1570.450342380167, 1579.541932839476, 1828.5520047588343, 1862.8509719222463, 1873.5035215667847, 7365.7790266292095, 7465.365443937272, 8335.333173346133, 8560.571971401429, 8561.529115898631, 8013.679452821894, 8106.594670266492, 8207.179282071806, 8222.588870556463, 8222.753317400473, 2418.9999999999955, 2458.91393801217, 2460.188137914493, 2461.064795377754, 2462.5198086373166, 2106.6524023536176, 2146.731658542682, 2422.1280339077916, 2422.358075110097, 2509.4604659998095, 2397.3646983549374, 2410.6143017117265, 2439.231642032754, 2440.9023639054503, 2441.6703744994375, 2411.1560953666253, 2437.146998550502, 2437.622168563875, 2439.5486834935596, 2440.6095024796123, 2384.9046038158453, 2417.274817554744, 2421.2010036688025, 2422.0796097482844, 2426.041713523166, 8303.501789892625, 8346.958265208636, 8371.288440482565, 8372.83254334916, 8373.246407823324, 5122.103631864409, 5184.092783762826, 5197.220416937452, 5199.922001169948, 5201.40183878855, 3251.723603493706, 3298.109510432194, 3390.0507857799857, 3405.2848901730417, 3418.3676019936393, 1177.7879981603307, 1183.8520184976878, 1217.2696382170627, 1218.074263559692, 1226.9877301227052, 11273.943630281796, 11305.773884522347, 11311.264767790068, 11332.660020199408, 11333.773324533464, 4540.137373898949, 4548.931766023512, 4707.176244157259, 4744.335792988984, 4750.287456881495, 9745.684332615148, 9752.658656946973, 9769.413835169913, 9770.560324785401, 9772.657956971472, 5202.2196670499725, 5206.088934436459, 5210.869728256824, 5213.035588416155, 5213.7914483421, 4663.790129444184, 4681.204195286809, 4690.64820138491, 4691.319758634979, 4691.413573303337, 3593.2813437312643, 4121.9381709274385, 4140.275451795924, 4206.537280899099, 4254.3831144483875, 1256.1395444120624, 1367.4325155060537, 1374.2785037855006, 1390.7635701930494, 1396.3855903402673, 1148.74153315505, 1162.8837116288473, 1254.8996080313552, 1465.377214683755, 1484.250453520965, 7945.48354356965, 8058.516489010678, 8077.474964127313, 8079.555624440594, 8087.433879628487, 1240.944157512914, 1243.92478686957, 1246.1775228349245, 1271.8219449277108, 1293.424426130363, 6274.121622972787, 6285.057241413824, 6339.207599050119, 6345.841353966188, 6346.174997250328, 9931.602735890629, 10052.497375131214, 10062.446565438824, 10091.243156763125, 10093.343932644502, 9875.031117749564, 9875.851862222073, 9961.203103751683, 9980.101790838859, 9997.500124993721, 7811.843763124706, 7877.212278772077, 7877.921220787834, 7889.6449659766595, 7894.052713674396, 7358.153812311439, 7391.8152046199275, 7394.334509894135, 7476.25237476248, 7592.202818703957, 8582.957085214533, 8891.155340242667, 9087.500187489752, 9094.727158185266, 9128.178463938277, 9065.138811812867, 9246.398984066014, 9253.120953509406, 9364.859527107235, 9446.38598491096, 7316.524425912298, 7319.817004574928, 7401.666924988281, 7415.0, 7424.109106907205, 7452.590107544137, 7587.013688220496, 8122.593870306343, 8264.0, 8277.130901255523, 7642.235776422313, 7730.188330225245, 7734.7679569613, 7753.92246077532, 7893.36853051766, 7655.885161722467, 7705.345045670956, 7721.806954826175, 7782.610869456617, 7799.06411230656, 7784.143744188022, 7787.766367008999, 7827.647755851114, 7844.764657060297, 7865.41009424284, 7156.0, 7171.677274522554, 7190.101237345332, 7191.532550384209, 7202.279772022755, 7028.8594228114125, 7052.188998265266, 7066.7173313066905, 7109.2179860216365, 7152.570845749272], "unit": "ms", "name": "mootools.html", "value": 4886.775802325401}, {"lowerIsBetter": false, "replicates": [349.5089399393852, 355.9092431429986, 356.99464508032383, 369.2117329501514, 373.1548043681061, 5952.196453478779, 5966.254218222723, 5992.34084250732, 6014.36849130841, 6021.969890150549, 5886.852828679285, 5892.3518412974645, 5894.115882617599, 5896.204012458317, 5909.734061967211, 4606.355110284563, 4656.930146047804, 4663.976680116594, 4707.835225767099, 4746.810127594892, 4721.126591580569, 4726.7400292983875, 4732.810687572493, 4737.076270127326, 4737.881552961169, 4674.392328997243, 4678.251479763238, 4678.391809064834, 4682.578567928886, 4691.530846915315, 4409.669274804386, 4452.3989261449615, 4592.288195329746, 4650.906981860344, 4661.86014419568, 4122.91754164918, 4123.8556650517085, 4136.607022332873, 4139.710220284582, 4150.896227594304, 5463.562914966793, 5596.888062238773, 5619.044762390404, 5622.522085622727, 5647.915281270784, 5214.60890433219, 5308.840734777924, 5357.062514060063, 5360.91958620621, 5364.088105022117, 6241.96879015602, 6245.937540624581, 6253.374662533755, 6254.155688981975, 6257.0, 4509.323601459774, 4513.345564893105, 4514.345419914094, 4514.413126293593, 4516.616087632555, 6310.211223597051, 6469.77355792545, 6478.416942475153, 6489.0, 6533.183352080942, 4704.317873908332, 4705.623550115983, 4706.1999460091865, 4707.176244157259, 4708.576228139484, 4759.881002974884, 4765.4996225395835, 4768.523147685204, 4770.0, 4771.045790841846, 5325.041492531382, 5417.079096553595, 5418.891622167614, 5420.810271640474, 5425.186222066722, 6383.585066970632, 6409.262934762469, 6409.743610255631, 6412.070249813844, 6417.101605775195, 1721.8278172182931, 1745.7294119411258, 1746.737989301615, 1747.6504699060238, 1748.0, 1186.4364426897328, 1187.3113594115393, 1188.0, 1204.6386084174887, 1205.2045649871043, 6201.503879689614, 6207.81376558704, 6208.1308616793685, 6209.9379006210265, 6212.596181248295, 6510.9023364649565, 6581.078648989145, 6582.473402127914, 6585.012248162718, 6585.967070164618, 6064.241969753781, 6131.264248290234, 6150.384961503813, 6195.628262304276, 6196.256449226122, 3614.548181477315, 3640.7815531068222, 3641.1443310821937, 3642.18050938542, 3650.2152037312107, 6694.732210711517, 6704.530682852153, 6732.3267673232285, 6747.797566073025, 6754.797356079325, 6504.479641628658, 6569.343065693392, 6715.6977935993, 6721.462283017347, 6837.965810170917, 6804.897926531205, 6810.795676129724, 6838.179418469816, 6848.589084654936, 6858.074159988438, 6613.768518101843, 6621.106150669791, 6641.568298060611, 6641.933580664131, 6650.3682150195655, 6440.871182576228, 6556.114924485133, 6561.179852518435, 6563.442107420923, 6569.113169722217, 6808.421284190701, 6842.213145488134, 6842.623655698785, 6848.863022739617, 6852.246252912275, 4701.435827700698, 4712.575868171881, 4714.764261786965, 4718.811247550197, 4719.032598317268, 6485.44873685742, 6599.142111525471, 6607.174103237096, 6607.537472376887, 6614.404703576702, 6414.550981431255, 6430.196225471816, 6432.742690292336, 6432.9678351607945, 6434.453071488976, 6411.134496842867, 6419.101325814297, 6425.8714825704155, 6426.100345951477, 6439.967800160969, 6250.0, 6356.4279214870885, 6357.618542887441, 6358.77744278948, 6365.777197798055, 6473.287938326875, 6476.902846457401, 6479.384458476437, 6480.773172938924, 6495.480361570969, 6189.752409903554, 6220.533459990609, 6265.122882796503, 6272.372762723874, 6280.937190628218], "unit": "ms", "name": "prototype.html", "value": 4877.2034519685585}, {"lowerIsBetter": false, "replicates": [935.5182081228164, 947.5309721687764, 953.2612225525219, 953.3517208298355, 973.6981535723926, 14212.786808197872, 14342.784858227134, 14358.425662973468, 14373.784393234097, 14391.06458080224, 13340.332983350818, 13379.39792709328, 13543.119697219698, 13545.390457429414, 13568.321583920813, 755.207032615754, 762.9771106866802, 928.4104593583089, 929.5445231836402, 937.3719607862748, 932.664240873285, 933.2440723014346, 936.6393938333722, 940.4686352210979, 940.9764755881089, 979.1872745621117, 984.601236499219, 987.3828856964398, 987.4272921705393, 987.4420952162005, 795.0697683710073, 804.5735760047185, 964.320154291223, 969.0406497567428, 975.3708857786759, 702.3678689179728, 719.0652152202168, 719.3777382564097, 719.7804669575777, 720.1718024272077, 14451.349689263903, 14485.927570362186, 14558.781618275734, 14572.70854582913, 14577.562673119823, 13237.933810330984, 13725.862741372559, 13969.0, 14068.437262509487, 14119.294035298297, 10624.734381640521, 10652.786944261148, 10738.140948724084, 10769.138468922545, 10809.135269178447, 3837.2901013312626, 3850.4224366344993, 3888.6305800948867, 3893.469244990779, 3938.2911076006308, 52646.21030684543, 52691.0, 53678.73160634211, 55638.44361556373, 55691.0, 11133.888661113448, 11237.3257604544, 11315.83026254607, 11420.88579114215, 11440.94279528597, 20637.896810515853, 20662.0, 20664.276750313667, 20673.17307307691, 20675.276365327143, 7581.3176814087, 7697.345725613273, 7948.523088614586, 8359.749207523784, 8466.0, 4884.853454396374, 4887.36464259644, 5010.173321401928, 5021.723805190676, 5026.773795179226, 2866.498362786504, 2889.2054684961804, 2900.115464783219, 2906.447774922758, 2908.0548821632633, 456.76933148760327, 464.98837529061365, 468.6344651172023, 471.5920728569831, 477.50578151612916, 709.443087176564, 753.4575105923726, 774.9883751743729, 784.1726978038224, 787.8227398835216, 9818.852717209247, 9990.400575965465, 10058.698239052694, 10074.798504029879, 10095.646652367132, 707.844274259662, 731.2175971710325, 735.6285076036568, 753.1376573823052, 755.3881356101575, 4662.277347011219, 4682.672212945129, 4722.598579120813, 4724.385829842098, 4760.095581839509, 62583.0, 69782.3021769786, 69876.301236988, 69934.3006569938, 70139.0, 53647.46352536503, 53775.73112134414, 54395.0, 54572.0, 54829.0, 54254.186187207226, 54282.0, 54520.90958180814, 54532.0, 55183.72408137934, 9944.254180936603, 10121.949390253296, 10126.341787783771, 10149.84775228387, 10342.172626189737, 39661.405078924414, 40638.39042414425, 40884.79557602193, 40886.0, 41969.58030419657, 50979.0, 51423.48576514187, 51944.74027629838, 52382.0, 52620.73689631528, 8843.557822108998, 8970.0, 9091.727248182566, 9385.953070234606, 9402.482863442576, 12628.621141365773, 13828.170309781446, 13864.584062478141, 14171.307451584165, 14654.706905862036, 12399.814002790146, 12476.313802740937, 12888.291143986795, 13106.281738574296, 13142.34288285601, 12730.872691272969, 13126.868731312565, 13307.334633268492, 13317.0, 13352.799708004582, 12930.482780688668, 13309.933450332686, 13339.599812005656, 13355.0, 13407.396667149807, 10217.948910255402, 10781.730456738644, 11247.325160490396, 11298.209125361145, 11413.942930285295, 10284.382937023802, 10570.735731606772, 10829.729256768329, 10836.891631083903, 11092.0], "unit": "ms", "name": "yui.html", "value": 6953.060738647317}], "name": "dromaeo_css", "value": 7439.600912699932}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 109.0, 104.0, 109.0, 109.0, 109.0, 100.0, 108.0, 100.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 106.5}, {"lowerIsBetter": true, "replicates": [158.0, 121.0, 143.0, 165.0, 125.0, 148.0, 143.0, 122.0, 158.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.5}, {"lowerIsBetter": true, "replicates": [160.0, 159.0, 159.0, 161.0, 161.0, 161.0, 160.0, 162.0, 160.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 160.2}, {"lowerIsBetter": true, "replicates": [82.0, 71.0, 70.0, 152.0, 71.0, 70.0, 71.0, 72.0, 155.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 88.5}, {"lowerIsBetter": true, "replicates": [89.0, 89.0, 138.0, 88.0, 89.0, 89.0, 131.0, 89.0, 89.0, 139.0], "unit": "ms", "name": "audio-oscillator", "value": 103.0}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [146.0, 144.0, 145.0, 144.0, 144.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 144.8}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 97.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [53.0, 57.0, 55.0, 55.0, 57.0, 55.0, 55.0, 55.0, 52.0, 52.0], "unit": "ms", "name": "json-parse-financial", "value": 54.6}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 62.0, 63.0, 63.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 62.9}, {"lowerIsBetter": true, "replicates": [87.0, 77.0, 86.0, 77.0, 78.0, 78.0, 77.0, 77.0, 86.0, 80.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 80.3}, {"lowerIsBetter": true, "replicates": [116.0, 115.0, 108.0, 107.0, 119.0, 113.0, 114.0, 115.0, 106.0, 116.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 112.9}, {"lowerIsBetter": true, "replicates": [135.0, 139.0, 142.0, 138.0, 133.0, 135.0, 148.0, 135.0, 134.0, 126.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 136.5}, {"lowerIsBetter": true, "replicates": [64.0, 62.0, 62.0, 61.0, 64.0, 59.0, 61.0, 60.0, 60.0, 60.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.3}], "name": "kraken", "value": 1456.4}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.88069485414618], "unit": "score", "name": "Richards", "value": 215.4128039999999}, {"lowerIsBetter": false, "replicates": [170.7067258449983], "unit": "score", "name": "DeltaBlue", "value": 387.31924399999986}, {"lowerIsBetter": false, "replicates": [280.19052956010086], "unit": "score", "name": "Encrypt", "value": 949.9999890000003}, {"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": [554.3237250554324], "unit": "score", "name": "Earley", "value": 1202.2992520000003}, {"lowerIsBetter": false, "replicates": [10437.5], "unit": "score", "name": "Boyer", "value": 63.85274251497008}, {"lowerIsBetter": false, "replicates": [23604.6511627907], "unit": "score", "name": "RegExp", "value": 38.593453201970455}, {"lowerIsBetter": false, "replicates": [544.91341991342], "unit": "score", "name": "Splay", "value": 149.54852830188676}, {"lowerIsBetter": false, "replicates": [4817.307692307692], "unit": "score", "name": "NavierStokes", "value": 308.055888223553}], "name": "v8_7", "value": 23266.587943149927}]} 04:30:58 INFO - RETURN: dromaeo_css: 7439.60 04:30:58 INFO - RETURN: kraken: 1456.40 04:30:58 INFO - RETURN: v8_7: 23266.59 04:30:58 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23266.59"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1456.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7439.60"}}} 04:30:58 INFO - Return code: 0 04:30:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 04:30:58 INFO - # TBPL SUCCESS # 04:30:58 INFO - Running post-action listener: _resource_record_post_action 04:30:58 INFO - Running post-run listener: _resource_record_post_run 04:30:59 INFO - Total resource usage - Wall time: 1222s; CPU: 12.0%; Read bytes: 81920; Write bytes: 358658048; Read time: 368; Write time: 333988 04:30:59 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 76386304; Read time: 0; Write time: 93296 04:30:59 INFO - run-tests - Wall time: 1208s; CPU: 12.0%; Read bytes: 81920; Write bytes: 262205440; Read time: 368; Write time: 237076 04:30:59 INFO - Running post-run listener: _upload_blobber_files 04:30:59 INFO - Blob upload gear active. 04:30:59 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 04:30:59 INFO - Copying logs to upload dir... 04:30:59 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1271.786871 ========= master_lag: 0.16 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2015-12-16 04:30:59.748079) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 04:30:59.752478) ========= 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=7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009869 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.01 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 04:30:59.774907) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:30:59.778232) ========= 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=7d909545e45784438f4e8e36000001b3-1450267181.733139-872235548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004495 ========= master_lag: 0.27 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:31:00.053253) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 04:31:00.053554) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 04:31:00.053895) ========= ========= Total master_lag: 0.60 =========