builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-021 starttime: 1450270169.04 results: success (0) buildid: 20151215222209 builduid: c7d83ec7ec38466f80723d81083dda01 revision: b2d75585bfc4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.037119) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.037672) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.037986) ========= 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=ccb4611811c763f2fad71465000001de-1450270168.481139-315131454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006152 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.060743) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.061085) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.074338) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.074747) ========= 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=ccb4611811c763f2fad71465000001de-1450270168.481139-315131454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004616 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.124330) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.124671) ========= 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:49:29.125054) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.125334) ========= 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=ccb4611811c763f2fad71465000001de-1450270168.481139-315131454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-16 04:49:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 14.0M=0.001s 2015-12-16 04:49:29 (14.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.171642 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.309684) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.310031) ========= 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=ccb4611811c763f2fad71465000001de-1450270168.481139-315131454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020551 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.360446) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.360824) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b2d75585bfc4 --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 b2d75585bfc4 --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=ccb4611811c763f2fad71465000001de-1450270168.481139-315131454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-16 04:49:29,559 Setting DEBUG logging. 2015-12-16 04:49:29,560 attempt 1/10 2015-12-16 04:49:29,560 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b2d75585bfc4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-16 04:49:29,811 unpacking tar archive at: mozilla-beta-b2d75585bfc4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.549961 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.924151) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.924534) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.932600) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 04:49:29.932974) ========= 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:49:29.933518) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2015-12-16 04:49:29.933807) ========= /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=ccb4611811c763f2fad71465000001de-1450270168.481139-315131454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:49:30 INFO - MultiFileLogger online at 20151216 04:49:30 in /builds/slave/test-pgo 04:49:30 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:49:30 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 04:49:30 INFO - {'append_to_log': False, 04:49:30 INFO - 'base_work_dir': '/builds/slave/test-pgo', 04:49:30 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:49:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 04:49:30 INFO - 'branch': 'Mozilla-Beta', 04:49:30 INFO - 'buildbot_json_path': 'buildprops.json', 04:49:30 INFO - 'config_files': ('talos/linux_config.py',), 04:49:30 INFO - 'default_actions': ('clobber', 04:49:30 INFO - 'read-buildbot-config', 04:49:30 INFO - 'download-and-extract', 04:49:30 INFO - 'populate-webroot', 04:49:30 INFO - 'create-virtualenv', 04:49:30 INFO - 'install', 04:49:30 INFO - 'run-tests'), 04:49:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:49:30 INFO - 'download_minidump_stackwalk': True, 04:49:30 INFO - 'download_symbols': 'ondemand', 04:49:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:49:30 INFO - 'tooltool.py': '/tools/tooltool.py', 04:49:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:49:30 INFO - '/tools/misc-python/virtualenv.py')}, 04:49:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:49:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:49:30 INFO - 'installer_path': 'installer.exe', 04:49:30 INFO - 'log_level': 'info', 04:49:30 INFO - 'log_name': 'talos', 04:49:30 INFO - 'log_to_console': True, 04:49:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:49:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:49:30 INFO - 'opt_config_files': (), 04:49:30 INFO - 'pip_index': False, 04:49:30 INFO - 'sps_profile': False, 04:49:30 INFO - 'sps_profile_interval': 0, 04:49:30 INFO - 'suite': 'dromaeojs', 04:49:30 INFO - 'system_bits': '64', 04:49:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:49:30 INFO - 'title': 'talos-linux64-ix-021', 04:49:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:49:30 INFO - 'use_talos_json': True, 04:49:30 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 04:49:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:49:30 INFO - 'work_dir': 'build'} 04:49:30 INFO - ##### 04:49:30 INFO - ##### Running clobber step. 04:49:30 INFO - ##### 04:49:30 INFO - Running pre-action listener: _resource_record_pre_action 04:49:30 INFO - Running main action method: clobber 04:49:30 INFO - rmtree: /builds/slave/test-pgo/build 04:49:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 04:49:30 INFO - Running post-action listener: _resource_record_post_action 04:49:30 INFO - ##### 04:49:30 INFO - ##### Running read-buildbot-config step. 04:49:30 INFO - ##### 04:49:30 INFO - Running pre-action listener: _resource_record_pre_action 04:49:30 INFO - Running main action method: read_buildbot_config 04:49:30 INFO - Using buildbot properties: 04:49:30 INFO - { 04:49:30 INFO - "properties": { 04:49:30 INFO - "buildnumber": 42, 04:49:30 INFO - "product": "firefox", 04:49:30 INFO - "script_repo_revision": "production", 04:49:30 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 04:49:30 INFO - "repository": "", 04:49:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 04:49:30 INFO - "buildid": "20151215222209", 04:49:30 INFO - "slavename": "talos-linux64-ix-021", 04:49:30 INFO - "pgo_build": "True", 04:49:30 INFO - "basedir": "/builds/slave/test-pgo", 04:49:30 INFO - "project": "", 04:49:30 INFO - "platform": "ubuntu64_hw", 04:49:30 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:49:30 INFO - "slavebuilddir": "test", 04:49:30 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 04:49:30 INFO - "branch": "mozilla-beta", 04:49:30 INFO - "repo_path": "releases/mozilla-beta", 04:49:30 INFO - "stage_platform": "linux64-pgo", 04:49:30 INFO - "builduid": "c7d83ec7ec38466f80723d81083dda01", 04:49:30 INFO - "revision": "b2d75585bfc4" 04:49:30 INFO - }, 04:49:30 INFO - "sourcestamp": { 04:49:30 INFO - "repository": "", 04:49:30 INFO - "hasPatch": false, 04:49:30 INFO - "project": "", 04:49:30 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 04:49:30 INFO - "changes": [ 04:49:30 INFO - { 04:49:30 INFO - "category": null, 04:49:30 INFO - "files": [ 04:49:30 INFO - { 04:49:30 INFO - "url": null, 04:49:30 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/firefox-44.0.en-US.linux-x86_64.tar.bz2" 04:49:30 INFO - } 04:49:30 INFO - ], 04:49:30 INFO - "repository": "", 04:49:30 INFO - "rev": "b2d75585bfc4", 04:49:30 INFO - "who": "sendchange", 04:49:30 INFO - "when": 1450255076, 04:49:30 INFO - "number": 7365134, 04:49:30 INFO - "comments": "Bug 1220010: Temporarily disable asyncInit_ r=me, a=bustage", 04:49:30 INFO - "project": "", 04:49:30 INFO - "at": "Wed 16 Dec 2015 00:37:56", 04:49:30 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 04:49:30 INFO - "revlink": "", 04:49:30 INFO - "properties": [ 04:49:30 INFO - [ 04:49:30 INFO - "buildid", 04:49:30 INFO - "20151215222209", 04:49:30 INFO - "Change" 04:49:30 INFO - ], 04:49:30 INFO - [ 04:49:30 INFO - "builduid", 04:49:30 INFO - "c7d83ec7ec38466f80723d81083dda01", 04:49:30 INFO - "Change" 04:49:30 INFO - ], 04:49:30 INFO - [ 04:49:30 INFO - "pgo_build", 04:49:30 INFO - "True", 04:49:30 INFO - "Change" 04:49:30 INFO - ] 04:49:30 INFO - ], 04:49:30 INFO - "revision": "b2d75585bfc4" 04:49:30 INFO - } 04:49:30 INFO - ], 04:49:30 INFO - "revision": "b2d75585bfc4" 04:49:30 INFO - } 04:49:30 INFO - } 04:49:30 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/firefox-44.0.en-US.linux-x86_64.tar.bz2. 04:49:30 INFO - Running post-action listener: _resource_record_post_action 04:49:30 INFO - ##### 04:49:30 INFO - ##### Running download-and-extract step. 04:49:30 INFO - ##### 04:49:30 INFO - Running pre-action listener: _resource_record_pre_action 04:49:30 INFO - Running main action method: download_and_extract 04:49:30 INFO - mkdir: /builds/slave/test-pgo/build/tests 04:49: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:49:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/test_packages.json 04:49:30 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 04:49:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 04:49:30 INFO - Downloaded 1270 bytes. 04:49:30 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 04:49:30 INFO - Using the following test package requirements: 04:49:30 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 04:49:30 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:49:30 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 04:49:30 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:49:30 INFO - u'jsshell-linux-x86_64.zip'], 04:49:30 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:49:30 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 04:49:30 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 04:49:30 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:49:30 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 04:49:30 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:49:30 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 04:49:30 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:49:30 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 04:49:30 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 04:49:30 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:49:30 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 04:49:30 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:49: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:49:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/firefox-44.0.en-US.linux-x86_64.common.tests.zip 04:49:30 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/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:49:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/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:49:31 INFO - Downloaded 22571743 bytes. 04:49:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 04:49:31 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 04:49:31 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 04:49:32 INFO - Return code: 0 04:49:32 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:32 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/firefox-44.0.en-US.linux-x86_64.common.tests.zip 04:49:32 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/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:49:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/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:49:32 INFO - Downloaded 22571743 bytes. 04:49:32 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:49:32 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 04:49:32 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:49:33 INFO - Return code: 0 04:49: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:49:33 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 04:49:33 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/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:49:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/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:49:34 INFO - Downloaded 11131970 bytes. 04:49:34 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:49:34 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 04:49:34 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:49:34 INFO - Return code: 0 04:49:34 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:49:34 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/firefox-44.0.en-US.linux-x86_64.tar.bz2 04:49:34 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/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:49:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/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:49:35 INFO - Downloaded 56629990 bytes. 04:49:35 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/firefox-44.0.en-US.linux-x86_64.tar.bz2 04:49:35 INFO - mkdir: /builds/slave/test-pgo/properties 04:49:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 04:49:35 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 04:49:35 INFO - Contents: 04:49:35 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/firefox-44.0.en-US.linux-x86_64.tar.bz2 04:49:35 INFO - Running post-action listener: _resource_record_post_action 04:49:35 INFO - Running post-action listener: set_extra_try_arguments 04:49:35 INFO - ##### 04:49:35 INFO - ##### Running populate-webroot step. 04:49:35 INFO - ##### 04:49:35 INFO - Running pre-action listener: _resource_record_pre_action 04:49:35 INFO - Running main action method: populate_webroot 04:49:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:49:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:49:35 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 04:49:35 INFO - '../fennec_ids.txt'], 04:49:35 INFO - 'tests': ['tcheck2']}, 04:49:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:49:35 INFO - '--tppagecycles', 04:49:35 INFO - '7'], 04:49:35 INFO - 'tests': ['tsvgm']}}, 04:49:35 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:49:35 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:49:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:49:35 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:49:35 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:49:35 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:49:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:49:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:49:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:49:35 INFO - 'talos_options': ['--e10s'], 04:49:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:49:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:49:35 INFO - 'tests': ['damp', 'tps']}, 04:49:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:49:35 INFO - 'talos_options': ['--e10s'], 04:49:35 INFO - 'tests': ['damp', 'tps']}, 04:49:35 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:49:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:49:35 INFO - 'other': {'tests': ['a11yr', 04:49:35 INFO - 'ts_paint', 04:49:35 INFO - 'tpaint', 04:49:35 INFO - 'sessionrestore', 04:49:35 INFO - 'sessionrestore_no_auto_restore']}, 04:49:35 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:49:35 INFO - 'tests': ['a11yr', 04:49:35 INFO - 'ts_paint', 04:49:35 INFO - 'tpaint', 04:49:35 INFO - 'sessionrestore', 04:49:35 INFO - 'sessionrestore_no_auto_restore']}, 04:49:35 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:49:35 INFO - 'tests': ['a11yr', 04:49:35 INFO - 'ts_paint', 04:49:35 INFO - 'tpaint', 04:49:35 INFO - 'sessionrestore', 04:49:35 INFO - 'sessionrestore_no_auto_restore']}, 04:49:35 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:49:35 INFO - 'tests': ['a11yr', 04:49:35 INFO - 'ts_paint', 04:49:35 INFO - 'tpaint', 04:49:35 INFO - 'sessionrestore', 04:49:35 INFO - 'sessionrestore_no_auto_restore']}, 04:49:35 INFO - 'other_l64': {'tests': ['a11yr', 04:49:35 INFO - 'ts_paint', 04:49:35 INFO - 'tpaint', 04:49:35 INFO - 'sessionrestore', 04:49:35 INFO - 'sessionrestore_no_auto_restore']}, 04:49:35 INFO - 'other_nol64': {'tests': ['a11yr', 04:49:35 INFO - 'ts_paint', 04:49:35 INFO - 'tpaint', 04:49:35 INFO - 'sessionrestore', 04:49:35 INFO - 'sessionrestore_no_auto_restore']}, 04:49:35 INFO - 'svgr': {'tests': ['tsvgx', 04:49:35 INFO - 'tsvgr_opacity', 04:49:35 INFO - 'tart', 04:49:35 INFO - 'tscrollx', 04:49:35 INFO - 'cart']}, 04:49:35 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:49:35 INFO - 'tests': ['tsvgx', 04:49:35 INFO - 'tsvgr_opacity', 04:49:35 INFO - 'tart', 04:49:35 INFO - 'tscrollx', 04:49:35 INFO - 'cart']}, 04:49:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:49:35 INFO - 'tests': ['tp5o']}, 04:49:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:49:35 INFO - 'talos_options': ['--e10s'], 04:49:35 INFO - 'tests': ['tp5o']}, 04:49:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:49:35 INFO - 'talos_options': ['--xperf_path', 04:49:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:49:35 INFO - 'tests': ['tp5n']}, 04:49:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:49:35 INFO - 'talos_options': ['--e10s', 04:49:35 INFO - '--xperf_path', 04:49:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:49:35 INFO - 'tests': ['tp5n']}}, 04:49:35 INFO - 'talos.zip': {'path': '', 04:49:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:49:35 INFO - Running post-action listener: _resource_record_post_action 04:49:35 INFO - ##### 04:49:35 INFO - ##### Running create-virtualenv step. 04:49:35 INFO - ##### 04:49:35 INFO - Running pre-action listener: _resource_record_pre_action 04:49:35 INFO - Running main action method: create_virtualenv 04:49:35 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 04:49:35 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:49:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 04:49:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:49:35 INFO - Using real prefix '/usr' 04:49:35 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 04:49:35 INFO - Installing distribute.............................................................................................................................................................................................done. 04:49:38 INFO - Installing pip.................done. 04:49:38 INFO - Return code: 0 04:49:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 04:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:38 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 0x7f7e715f0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e350>, '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 0x277f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277f6e0>, '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': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', '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:49:38 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:49:38 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:49:38 INFO - Using env: {'DISPLAY': ':0', 04:49:38 INFO - 'HOME': '/home/cltbld', 04:49:38 INFO - 'LANG': 'en_US.UTF-8', 04:49:38 INFO - 'LANGUAGE': 'en_US:en', 04:49:38 INFO - 'LOGNAME': 'cltbld', 04:49:38 INFO - 'MAIL': '/var/mail/cltbld', 04:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:38 INFO - 'MOZ_NO_REMOTE': '1', 04:49:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:49:38 INFO - 'NO_EM_RESTART': '1', 04:49:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:49:38 INFO - 'PWD': '/builds/slave/test-pgo', 04:49:38 INFO - 'SHELL': '/bin/bash', 04:49:38 INFO - 'SHLVL': '1', 04:49:38 INFO - 'TERM': 'linux', 04:49:38 INFO - 'TMOUT': '86400', 04:49:38 INFO - 'USER': 'cltbld', 04:49:38 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', 04:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:38 INFO - '_': '/tools/buildbot/bin/python'} 04:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:38 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:49:38 INFO - Cleaning up... 04:49:38 INFO - Return code: 0 04:49:38 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 04:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:38 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 0x7f7e715f0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e350>, '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 0x277f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277f6e0>, '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': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', '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:49:38 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:49:38 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:49:38 INFO - Using env: {'DISPLAY': ':0', 04:49:38 INFO - 'HOME': '/home/cltbld', 04:49:38 INFO - 'LANG': 'en_US.UTF-8', 04:49:38 INFO - 'LANGUAGE': 'en_US:en', 04:49:38 INFO - 'LOGNAME': 'cltbld', 04:49:38 INFO - 'MAIL': '/var/mail/cltbld', 04:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:38 INFO - 'MOZ_NO_REMOTE': '1', 04:49:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:49:38 INFO - 'NO_EM_RESTART': '1', 04:49:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:49:38 INFO - 'PWD': '/builds/slave/test-pgo', 04:49:38 INFO - 'SHELL': '/bin/bash', 04:49:38 INFO - 'SHLVL': '1', 04:49:38 INFO - 'TERM': 'linux', 04:49:38 INFO - 'TMOUT': '86400', 04:49:38 INFO - 'USER': 'cltbld', 04:49:38 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', 04:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:38 INFO - '_': '/tools/buildbot/bin/python'} 04:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:38 INFO - Downloading/unpacking psutil>=0.7.1 04:49:38 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:49:38 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:49:38 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:49:38 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:49:38 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:49:38 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:49:40 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 04:49:40 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 04:49:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 04:49:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:49:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:49:40 INFO - Installing collected packages: psutil 04:49:40 INFO - Running setup.py install for psutil 04:49:40 INFO - building 'psutil._psutil_linux' extension 04:49:40 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:49:40 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:49:40 INFO - building 'psutil._psutil_posix' extension 04:49:40 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:49:40 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:49:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:49:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:49:40 INFO - Successfully installed psutil 04:49:40 INFO - Cleaning up... 04:49:40 INFO - Return code: 0 04:49:40 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 04:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:40 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 0x7f7e715f0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e350>, '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 0x277f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277f6e0>, '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': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', '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:49:40 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:49:40 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:49:40 INFO - Using env: {'DISPLAY': ':0', 04:49:40 INFO - 'HOME': '/home/cltbld', 04:49:40 INFO - 'LANG': 'en_US.UTF-8', 04:49:40 INFO - 'LANGUAGE': 'en_US:en', 04:49:40 INFO - 'LOGNAME': 'cltbld', 04:49:40 INFO - 'MAIL': '/var/mail/cltbld', 04:49:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:40 INFO - 'MOZ_NO_REMOTE': '1', 04:49:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:49:40 INFO - 'NO_EM_RESTART': '1', 04:49:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:49:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:49:40 INFO - 'PWD': '/builds/slave/test-pgo', 04:49:40 INFO - 'SHELL': '/bin/bash', 04:49:40 INFO - 'SHLVL': '1', 04:49:40 INFO - 'TERM': 'linux', 04:49:40 INFO - 'TMOUT': '86400', 04:49:40 INFO - 'USER': 'cltbld', 04:49:40 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', 04:49:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:40 INFO - '_': '/tools/buildbot/bin/python'} 04:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:41 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:49:41 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:49:41 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:49:41 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:49:41 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:49:41 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:49:41 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:49:42 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:49:42 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 04:49:42 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:49:42 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:49:42 INFO - Installing collected packages: mozsystemmonitor 04:49:42 INFO - Running setup.py install for mozsystemmonitor 04:49:42 INFO - Successfully installed mozsystemmonitor 04:49:42 INFO - Cleaning up... 04:49:42 INFO - Return code: 0 04:49:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 04:49:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:42 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7e715f0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e350>, '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 0x277f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277f6e0>, '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': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', '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:49:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 04:49:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:49:42 INFO - Using env: {'DISPLAY': ':0', 04:49:42 INFO - 'HOME': '/home/cltbld', 04:49:42 INFO - 'LANG': 'en_US.UTF-8', 04:49:42 INFO - 'LANGUAGE': 'en_US:en', 04:49:42 INFO - 'LOGNAME': 'cltbld', 04:49:42 INFO - 'MAIL': '/var/mail/cltbld', 04:49:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:42 INFO - 'MOZ_NO_REMOTE': '1', 04:49:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:49:42 INFO - 'NO_EM_RESTART': '1', 04:49:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:49:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:49:42 INFO - 'PWD': '/builds/slave/test-pgo', 04:49:42 INFO - 'SHELL': '/bin/bash', 04:49:42 INFO - 'SHLVL': '1', 04:49:42 INFO - 'TERM': 'linux', 04:49:42 INFO - 'TMOUT': '86400', 04:49:42 INFO - 'USER': 'cltbld', 04:49:42 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', 04:49:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:42 INFO - '_': '/tools/buildbot/bin/python'} 04:49:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:43 INFO - Downloading/unpacking blobuploader==1.2.4 04:49:43 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:49:43 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:49:43 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:49:43 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:49:43 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:49:43 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:49:44 INFO - Downloading blobuploader-1.2.4.tar.gz 04:49:44 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 04:49:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:49:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:45 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 04:49:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 04:49:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:49:45 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:49:45 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:49:45 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:49:45 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:49:45 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:49:45 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:49:45 INFO - Downloading docopt-0.6.1.tar.gz 04:49:45 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 04:49:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 04:49:45 INFO - Installing collected packages: blobuploader, requests, docopt 04:49:45 INFO - Running setup.py install for blobuploader 04:49:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:49:45 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 04:49:45 INFO - Running setup.py install for requests 04:49:46 INFO - Running setup.py install for docopt 04:49:46 INFO - Successfully installed blobuploader requests docopt 04:49:46 INFO - Cleaning up... 04:49:46 INFO - Return code: 0 04:49:46 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 04:49:46 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:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:46 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:49:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:46 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 0x7f7e715f0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e350>, '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 0x277f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277f6e0>, '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': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', '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:49:46 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:49:46 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:49:46 INFO - Using env: {'DISPLAY': ':0', 04:49:46 INFO - 'HOME': '/home/cltbld', 04:49:46 INFO - 'LANG': 'en_US.UTF-8', 04:49:46 INFO - 'LANGUAGE': 'en_US:en', 04:49:46 INFO - 'LOGNAME': 'cltbld', 04:49:46 INFO - 'MAIL': '/var/mail/cltbld', 04:49:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:46 INFO - 'MOZ_NO_REMOTE': '1', 04:49:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:49:46 INFO - 'NO_EM_RESTART': '1', 04:49:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:49:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:49:46 INFO - 'PWD': '/builds/slave/test-pgo', 04:49:46 INFO - 'SHELL': '/bin/bash', 04:49:46 INFO - 'SHLVL': '1', 04:49:46 INFO - 'TERM': 'linux', 04:49:46 INFO - 'TMOUT': '86400', 04:49:46 INFO - 'USER': 'cltbld', 04:49:46 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', 04:49:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:46 INFO - '_': '/tools/buildbot/bin/python'} 04:49:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:49:46 INFO - Running setup.py (path:/tmp/pip-yMjuPP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:49:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:49:46 INFO - Running setup.py (path:/tmp/pip-SXlUwA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:49:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:49:46 INFO - Running setup.py (path:/tmp/pip-6TzqDR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:49:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:49:46 INFO - Running setup.py (path:/tmp/pip-OdfOZH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:49:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 04:49:46 INFO - Running setup.py (path:/tmp/pip-mS5Hdc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 04:49:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:49:46 INFO - Running setup.py (path:/tmp/pip-fz6gq_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:49:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:49:46 INFO - Running setup.py (path:/tmp/pip-Qsw_1z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:49:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:49:46 INFO - Running setup.py (path:/tmp/pip-wXlEau-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:49:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 04:49:46 INFO - Running setup.py (path:/tmp/pip-P9XhbU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 04:49:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 04:49:47 INFO - Running setup.py (path:/tmp/pip-1_BC8A-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 04:49:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:49:47 INFO - Running setup.py (path:/tmp/pip-IcrE1e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:49:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:49:47 INFO - Running setup.py (path:/tmp/pip-AlHB3p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:49:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:49:47 INFO - Running setup.py (path:/tmp/pip-qsiMEG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:49:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:49:47 INFO - Running setup.py (path:/tmp/pip-0X1nCK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:49:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:49:47 INFO - Running setup.py (path:/tmp/pip-ReuvM9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:49:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 04:49:47 INFO - Running setup.py (path:/tmp/pip-FPXCuk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 04:49:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 04:49:47 INFO - Running setup.py (path:/tmp/pip-LB4DEx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 04:49:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:49:47 INFO - Running setup.py install for manifestparser 04:49:47 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 04:49:47 INFO - Running setup.py install for mozcrash 04:49:47 INFO - Running setup.py install for mozdebug 04:49:47 INFO - Running setup.py install for mozdevice 04:49:48 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 04:49:48 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 04:49:48 INFO - Running setup.py install for mozfile 04:49:48 INFO - Running setup.py install for mozhttpd 04:49:48 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 04:49:48 INFO - Running setup.py install for mozinfo 04:49:48 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 04:49:48 INFO - Running setup.py install for mozInstall 04:49:48 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 04:49:48 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 04:49:48 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 04:49:48 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 04:49:48 INFO - Running setup.py install for mozleak 04:49:48 INFO - Running setup.py install for mozlog 04:49:48 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 04:49:48 INFO - Running setup.py install for moznetwork 04:49:48 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 04:49:48 INFO - Running setup.py install for mozprocess 04:49:48 INFO - Running setup.py install for mozprofile 04:49:48 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 04:49:48 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 04:49:48 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 04:49:48 INFO - Running setup.py install for mozrunner 04:49:49 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 04:49:49 INFO - Running setup.py install for mozscreenshot 04:49:49 INFO - Running setup.py install for moztest 04:49:49 INFO - Running setup.py install for mozversion 04:49:49 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 04:49:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:49:49 INFO - Cleaning up... 04:49:49 INFO - Return code: 0 04:49:49 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 04:49:49 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:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:49 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:49:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:49 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 0x7f7e715f0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e350>, '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 0x277f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277f6e0>, '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': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', '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:49:49 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:49:49 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:49:49 INFO - Using env: {'DISPLAY': ':0', 04:49:49 INFO - 'HOME': '/home/cltbld', 04:49:49 INFO - 'LANG': 'en_US.UTF-8', 04:49:49 INFO - 'LANGUAGE': 'en_US:en', 04:49:49 INFO - 'LOGNAME': 'cltbld', 04:49:49 INFO - 'MAIL': '/var/mail/cltbld', 04:49:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:49 INFO - 'MOZ_NO_REMOTE': '1', 04:49:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:49:49 INFO - 'NO_EM_RESTART': '1', 04:49:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:49:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:49:49 INFO - 'PWD': '/builds/slave/test-pgo', 04:49:49 INFO - 'SHELL': '/bin/bash', 04:49:49 INFO - 'SHLVL': '1', 04:49:49 INFO - 'TERM': 'linux', 04:49:49 INFO - 'TMOUT': '86400', 04:49:49 INFO - 'USER': 'cltbld', 04:49:49 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', 04:49:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:49 INFO - '_': '/tools/buildbot/bin/python'} 04:49:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:49:49 INFO - Running setup.py (path:/tmp/pip-8yDD2l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:49:49 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:49:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:49:49 INFO - Running setup.py (path:/tmp/pip-WpVE75-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:49:49 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:49:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:49:49 INFO - Running setup.py (path:/tmp/pip-rRw0XY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:49:49 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:49:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:49:49 INFO - Running setup.py (path:/tmp/pip-MNcC97-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:49:49 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:49:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 04:49:49 INFO - Running setup.py (path:/tmp/pip-NBx29h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 04:49:50 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:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:49:50 INFO - Running setup.py (path:/tmp/pip-3empaW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:49:50 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:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:49:50 INFO - Running setup.py (path:/tmp/pip-JQTyOD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:49:50 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:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:49:50 INFO - Running setup.py (path:/tmp/pip-HLJ7Mm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:49:50 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:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 04:49:50 INFO - Running setup.py (path:/tmp/pip-ajGsBP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 04:49:50 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:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 04:49:50 INFO - Running setup.py (path:/tmp/pip-YBL6c7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 04:49:50 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:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:49:50 INFO - Running setup.py (path:/tmp/pip-dEDDgZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:49:50 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:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:49:50 INFO - Running setup.py (path:/tmp/pip-HX66eC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:49:50 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:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:49:50 INFO - Running setup.py (path:/tmp/pip-PEib_P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:49:50 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:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:49:50 INFO - Running setup.py (path:/tmp/pip-iMD9W8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:49:50 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:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:49:50 INFO - Running setup.py (path:/tmp/pip-95SoTf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:49:50 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:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 04:49:50 INFO - Running setup.py (path:/tmp/pip-h836q2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 04:49:50 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:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 04:49:50 INFO - Running setup.py (path:/tmp/pip-w6yAei-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 04:49:50 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:49:50 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:49:50 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:49:50 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:49:50 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:49:50 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:49:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 04:49:50 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:49:50 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:49:50 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:49:50 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:49:50 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:49:50 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:49:52 INFO - Downloading blessings-1.5.1.tar.gz 04:49:52 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:49:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 04:49:52 INFO - Installing collected packages: blessings 04:49:52 INFO - Running setup.py install for blessings 04:49:52 INFO - Successfully installed blessings 04:49:52 INFO - Cleaning up... 04:49:52 INFO - Return code: 0 04:49:52 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 04:49:52 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 04:49:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 04:49:53 INFO - Reading from file tmpfile_stdout 04:49:53 INFO - Current package versions: 04:49:53 INFO - argparse == 1.2.1 04:49:53 INFO - blessings == 1.5.1 04:49:53 INFO - blobuploader == 1.2.4 04:49:53 INFO - docopt == 0.6.1 04:49:53 INFO - manifestparser == 1.1 04:49:53 INFO - mozInstall == 1.12 04:49:53 INFO - mozcrash == 0.16 04:49:53 INFO - mozdebug == 0.1 04:49:53 INFO - mozdevice == 0.46 04:49:53 INFO - mozfile == 1.2 04:49:53 INFO - mozhttpd == 0.7 04:49:53 INFO - mozinfo == 0.8 04:49:53 INFO - mozleak == 0.1 04:49:53 INFO - mozlog == 3.0 04:49:53 INFO - moznetwork == 0.27 04:49:53 INFO - mozprocess == 0.22 04:49:53 INFO - mozprofile == 0.27 04:49:53 INFO - mozrunner == 6.11 04:49:53 INFO - mozscreenshot == 0.1 04:49:53 INFO - mozsystemmonitor == 0.0 04:49:53 INFO - moztest == 0.7 04:49:53 INFO - mozversion == 1.4 04:49:53 INFO - psutil == 3.1.1 04:49:53 INFO - requests == 1.2.3 04:49:53 INFO - wsgiref == 0.1.2 04:49:53 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 04:49:53 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:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:53 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:49:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:53 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 0x7f7e715f0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e350>, '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 0x277f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277f6e0>, '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': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', '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:49:53 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:49:53 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:49:53 INFO - Using env: {'DISPLAY': ':0', 04:49:53 INFO - 'HOME': '/home/cltbld', 04:49:53 INFO - 'LANG': 'en_US.UTF-8', 04:49:53 INFO - 'LANGUAGE': 'en_US:en', 04:49:53 INFO - 'LOGNAME': 'cltbld', 04:49:53 INFO - 'MAIL': '/var/mail/cltbld', 04:49:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:53 INFO - 'MOZ_NO_REMOTE': '1', 04:49:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:49:53 INFO - 'NO_EM_RESTART': '1', 04:49:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:49:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:49:53 INFO - 'PWD': '/builds/slave/test-pgo', 04:49:53 INFO - 'SHELL': '/bin/bash', 04:49:53 INFO - 'SHLVL': '1', 04:49:53 INFO - 'TERM': 'linux', 04:49:53 INFO - 'TMOUT': '86400', 04:49:53 INFO - 'USER': 'cltbld', 04:49:53 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', 04:49:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:53 INFO - '_': '/tools/buildbot/bin/python'} 04:49:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 INFO - Cleaning up... 04:49:53 INFO - Return code: 0 04:49:53 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 04:49:53 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:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:53 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:49:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:53 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 0x7f7e715f0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e350>, '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 0x277f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277f6e0>, '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': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', '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:49:53 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:49:53 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:49:53 INFO - Using env: {'DISPLAY': ':0', 04:49:53 INFO - 'HOME': '/home/cltbld', 04:49:53 INFO - 'LANG': 'en_US.UTF-8', 04:49:53 INFO - 'LANGUAGE': 'en_US:en', 04:49:53 INFO - 'LOGNAME': 'cltbld', 04:49:53 INFO - 'MAIL': '/var/mail/cltbld', 04:49:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:53 INFO - 'MOZ_NO_REMOTE': '1', 04:49:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:49:53 INFO - 'NO_EM_RESTART': '1', 04:49:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:49:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:49:53 INFO - 'PWD': '/builds/slave/test-pgo', 04:49:53 INFO - 'SHELL': '/bin/bash', 04:49:53 INFO - 'SHLVL': '1', 04:49:53 INFO - 'TERM': 'linux', 04:49:53 INFO - 'TMOUT': '86400', 04:49:53 INFO - 'USER': 'cltbld', 04:49:53 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', 04:49:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:53 INFO - '_': '/tools/buildbot/bin/python'} 04:49:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:53 INFO - Downloading/unpacking jsonschema 04:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:49:55 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:49:55 INFO - Downloading/unpacking functools32 (from jsonschema) 04:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:55 INFO - Downloading functools32-3.2.3-2.tar.gz 04:49:55 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:49:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 04:49:55 INFO - warning: no files found matching '*.txt' 04:49:55 INFO - no previously-included directories found matching 'build' 04:49:55 INFO - no previously-included directories found matching 'dist' 04:49:55 INFO - no previously-included directories found matching '.git*' 04:49:55 INFO - Installing collected packages: jsonschema, functools32 04:49:55 INFO - Running setup.py install for functools32 04:49:55 INFO - warning: no files found matching '*.txt' 04:49:55 INFO - no previously-included directories found matching 'build' 04:49:55 INFO - no previously-included directories found matching 'dist' 04:49:55 INFO - no previously-included directories found matching '.git*' 04:49:55 INFO - Successfully installed jsonschema functools32 04:49:55 INFO - Cleaning up... 04:49:55 INFO - Return code: 0 04:49:55 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 04:49:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:55 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7e715f0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e350>, '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 0x277f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277f6e0>, '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': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', '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:49:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 04:49:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 04:49:55 INFO - Using env: {'DISPLAY': ':0', 04:49:55 INFO - 'HOME': '/home/cltbld', 04:49:55 INFO - 'LANG': 'en_US.UTF-8', 04:49:55 INFO - 'LANGUAGE': 'en_US:en', 04:49:55 INFO - 'LOGNAME': 'cltbld', 04:49:55 INFO - 'MAIL': '/var/mail/cltbld', 04:49:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:55 INFO - 'MOZ_NO_REMOTE': '1', 04:49:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:49:55 INFO - 'NO_EM_RESTART': '1', 04:49:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:49:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:49:55 INFO - 'PWD': '/builds/slave/test-pgo', 04:49:55 INFO - 'SHELL': '/bin/bash', 04:49:55 INFO - 'SHLVL': '1', 04:49:55 INFO - 'TERM': 'linux', 04:49:55 INFO - 'TMOUT': '86400', 04:49:55 INFO - 'USER': 'cltbld', 04:49:55 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', 04:49:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:55 INFO - '_': '/tools/buildbot/bin/python'} 04:49:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:55 INFO - Downloading/unpacking flake8 04:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:57 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:49:57 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:49:57 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:57 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:49:57 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:49:57 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:57 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:49:57 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 04:49:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 04:49:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:49:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:49:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:49:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:49:58 INFO - no previously-included directories found matching 'docs/_build' 04:49:58 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:49:58 INFO - Running setup.py install for pep8 04:49:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:49:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:49:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:49:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:49:58 INFO - no previously-included directories found matching 'docs/_build' 04:49:58 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 04:49:58 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:49:58 INFO - Cleaning up... 04:49:58 INFO - Return code: 0 04:49:58 INFO - Running post-action listener: _resource_record_post_action 04:49:58 INFO - Running post-action listener: _start_resource_monitoring 04:49:58 INFO - Starting resource monitoring. 04:49:58 INFO - ##### 04:49:58 INFO - ##### Running install step. 04:49:58 INFO - ##### 04:49:58 INFO - Running pre-action listener: _resource_record_pre_action 04:49:58 INFO - Running main action method: install 04:49:58 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 04:49:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 04:49:58 INFO - Reading from file tmpfile_stdout 04:49:58 INFO - Detecting whether we're running mozinstall >=1.0... 04:49:58 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 04:49:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 04:49:58 INFO - Reading from file tmpfile_stdout 04:49:58 INFO - Output received: 04:49:58 INFO - Usage: mozinstall [options] installer 04:49:58 INFO - Options: 04:49:58 INFO - -h, --help show this help message and exit 04:49:58 INFO - -d DEST, --destination=DEST 04:49:58 INFO - Directory to install application into. [default: 04:49:58 INFO - "/builds/slave/test-pgo"] 04:49:58 INFO - --app=APP Application being installed. [default: firefox] 04:49:58 INFO - mkdir: /builds/slave/test-pgo/build/application 04:49:58 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:49:58 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:50:12 INFO - Reading from file tmpfile_stdout 04:50:12 INFO - Output received: 04:50:12 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 04:50:12 INFO - Running post-action listener: _resource_record_post_action 04:50:12 INFO - ##### 04:50:12 INFO - ##### Running run-tests step. 04:50:12 INFO - ##### 04:50:12 INFO - Running pre-action listener: _resource_record_pre_action 04:50:12 INFO - Running main action method: run_tests 04:50:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 04:50:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 04:50:12 INFO - Python 2.7.3 04:50:12 INFO - Return code: 0 04:50:12 INFO - grabbing minidump binary from tooltool 04:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:12 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e350>, '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 0x277f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277f6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 04:50:12 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 04:50:12 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:50:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:50:12 INFO - Return code: 0 04:50:12 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 04:50:12 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 04:50:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 04:50:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 04:50:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 04:50:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 04:50:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 04:50:13 INFO - Return code: 0 04:50:13 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 04:50:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --webServer localhost 04:50:13 INFO - Using env: {'DISPLAY': ':0', 04:50:13 INFO - 'HOME': '/home/cltbld', 04:50:13 INFO - 'LANG': 'en_US.UTF-8', 04:50:13 INFO - 'LANGUAGE': 'en_US:en', 04:50:13 INFO - 'LOGNAME': 'cltbld', 04:50:13 INFO - 'MAIL': '/var/mail/cltbld', 04:50:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 04:50:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 04:50:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:13 INFO - 'MOZ_NO_REMOTE': '1', 04:50:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 04:50:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:13 INFO - 'NO_EM_RESTART': '1', 04:50:13 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:50:13 INFO - 'PWD': '/builds/slave/test-pgo', 04:50:13 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 04:50:13 INFO - 'SHELL': '/bin/bash', 04:50:13 INFO - 'SHLVL': '1', 04:50:13 INFO - 'TERM': 'linux', 04:50:13 INFO - 'TMOUT': '86400', 04:50:13 INFO - 'USER': 'cltbld', 04:50:13 INFO - 'XDG_SESSION_COOKIE': 'ccb4611811c763f2fad71465000001de-1450270168.481139-315131454', 04:50:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:13 INFO - '_': '/tools/buildbot/bin/python'} 04:50:13 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost'] with output_timeout 3600 04:50:14 INFO - mozversion INFO | application_buildid: 20151215222209 04:50:14 INFO - mozversion INFO | application_changeset: b2d75585bfc485f884f2822b0541f694b069d87c 04:50:14 INFO - mozversion INFO | application_display_name: Nightly 04:50:14 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:50:14 INFO - mozversion INFO | application_name: Firefox 04:50:14 INFO - mozversion INFO | application_remotingname: firefox 04:50:14 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:50:14 INFO - mozversion INFO | application_vendor: Mozilla 04:50:14 INFO - mozversion INFO | application_version: 44.0 04:50:14 INFO - mozversion INFO | platform_buildid: 20151215222209 04:50:14 INFO - mozversion INFO | platform_changeset: b2d75585bfc485f884f2822b0541f694b069d87c 04:50:14 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:50:14 INFO - mozversion INFO | platform_version: 44.0 04:50:14 INFO - 2015-12-16 04:50:14,146 DEBUG : using testdate: 1450270214 04:50:14 INFO - 2015-12-16 04:50:14,146 DEBUG : actual date: 1450270214 04:50:14 INFO - 2015-12-16 04:50:14,154 INFO : starting webserver on 'localhost:50084' 04:50:14 INFO - 2015-12-16 04:50:14,155 INFO : Starting test suite talos-linux64-ix-021 04:50:14 INFO - 2015-12-16 04:50:14,155 INFO : Starting test dromaeo_css 04:50:14 INFO - 2015-12-16 04:50:14,155 DEBUG : operating with platform_type : linux_ 04:50:14 INFO - 2015-12-16 04:50:14,156 INFO : Initialising browser for dromaeo_css test... 04:50:14 INFO - 2015-12-16 04:50:14,170 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpu2qnHA/profile http://localhost:50084/getInfo.html 04:50:19 INFO - 2015-12-16 04:50:19,106 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:50:19 INFO - 2015-12-16 04:50:19,107 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:50:19 INFO - 2015-12-16 04:50:19,107 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:50:19 INFO - 2015-12-16 04:50:19,107 DEBUG : BROWSER_OUTPUT: __metrics 04:50:19 INFO - 2015-12-16 04:50:19,243 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:50:20 INFO - 2015-12-16 04:50:20,791 INFO : Browser initialized. 04:50:20 INFO - 2015-12-16 04:50:20,791 INFO : Running cycle 1/1 for dromaeo_css test... 04:50:20 INFO - 2015-12-16 04:50:20,792 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpu2qnHA/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:50:32 INFO - 2015-12-16 04:50:32,387 DEBUG : BROWSER_OUTPUT: RSS: Main: 130297856 04:50:32 INFO - 2015-12-16 04:50:32,387 DEBUG : BROWSER_OUTPUT: 04:51:23 INFO - 2015-12-16 04:51:23,399 DEBUG : BROWSER_OUTPUT: 1450270283393 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:53:31 INFO - 2015-12-16 04:53:31,843 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50084/tests/dromaeo/cssquery-ext.html) 04:53:32 INFO - 2015-12-16 04:53:32,930 DEBUG : BROWSER_OUTPUT: RSS: Main: 242057216 04:53:32 INFO - 2015-12-16 04:53:32,930 DEBUG : BROWSER_OUTPUT: 04:56:35 INFO - 2015-12-16 04:56:35,074 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/dromaeo/cssquery-ext.html (next: http://localhost:50084/tests/dromaeo/cssquery-jquery.html) 04:56:35 INFO - 2015-12-16 04:56:35,635 DEBUG : BROWSER_OUTPUT: RSS: Main: 211615744 04:56:35 INFO - 2015-12-16 04:56:35,636 DEBUG : BROWSER_OUTPUT: 04:59:37 INFO - 2015-12-16 04:59:37,408 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50084/tests/dromaeo/cssquery-mootools.html) 04:59:37 INFO - 2015-12-16 04:59:37,974 DEBUG : BROWSER_OUTPUT: RSS: Main: 226045952 04:59:37 INFO - 2015-12-16 04:59:37,974 DEBUG : BROWSER_OUTPUT: 05:02:39 INFO - 2015-12-16 05:02:39,702 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50084/tests/dromaeo/cssquery-prototype.html) 05:02:40 INFO - 2015-12-16 05:02:40,263 DEBUG : BROWSER_OUTPUT: RSS: Main: 202792960 05:02:40 INFO - 2015-12-16 05:02:40,263 DEBUG : BROWSER_OUTPUT: 05:05:42 INFO - 2015-12-16 05:05:42,517 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50084/tests/dromaeo/cssquery-yui.html) 05:05:43 INFO - 2015-12-16 05:05:43,100 DEBUG : BROWSER_OUTPUT: RSS: Main: 196956160 05:05:43 INFO - 2015-12-16 05:05:43,100 DEBUG : BROWSER_OUTPUT: 05:08:45 INFO - 2015-12-16 05:08:45,143 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/dromaeo/cssquery-yui.html (next: http://localhost:50084/tests/dromaeo/cssquery-dojo.html) 05:08:45 INFO - 2015-12-16 05:08:45,401 DEBUG : BROWSER_OUTPUT: RSS: Main: 218517504 05:08:45 INFO - 2015-12-16 05:08:45,402 DEBUG : BROWSER_OUTPUT: 05:08:45 INFO - 2015-12-16 05:08:45,402 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:08:45 INFO - 2015-12-16 05:08:45,402 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:08:45 INFO - 2015-12-16 05:08:45,402 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:08:45 INFO - 2015-12-16 05:08:45,402 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:08:45 INFO - 2015-12-16 05:08:45,402 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3330.5503756992807;3409.1306716787217;3425.640307767684;3430.3825311443948;3462.2036931505745;5663.3204015518095;5699.914501282479;5736.512396446296;5750.3962083981205;5772.711364431777;5696.914546281809;5734.197212390258;5749.942500574993;5771.884562308751;5817.040188368914;5006.774695138727;5214.191800270954;5249;5311.49540793624;5330.946690533084;5161.225816127573;5172.586193104543;5182.611304152185;5183.714895680736;5187.014467251211;5086.745662716868;5105.310783044297;5153.768080436389;5156.278121063054;5218.530332270095;5106.744662766847;5208.869778255536;5210.03614331348;5217.113090774577;5217.895642087175;5199.246109314128;5209.791608335662;5242.161254199348;5247.921281180786;5268.736563171826;5350.117230656937;5395.7032363220005;5441.891162176774;5536.390996990328;5563.833085007456;6132.049532322497;6135.294441139237;6139.969300153515;6142.262928448615;6149.13912052313;6137.094037567105;6155.907661385083;6170.444659980623;6175.135481032659;6179.5674302798825;5512.228288039678;5667.829965101054;5768.7980920668;5866.560007999332;5885.470307672373;6250;6250.1874756282305;6257.092721555349;6273.905891411632;6276.6547839869245;5791.739371728282;6114.602550834182;6123.418275263843;6127.080937859357;6127.417895299939;5932.080527518242;6112.908306375408;6211;6216.2540495139965;6218.9378106219265;6082.787102451393;6108.75564977396;6117.541184411098;6117.724702388403;6120.632762034292;5781.537477001913;5902.085176797603;6103.542234332443;6106.5114790817515;6111.411033131329;4612.515685852937;4621.260598304255;4621.445426548836;4622.607078398374;4623.514530974267;4063.8577649782114;4119.320312148522;4150.024744185114;4156.480439945007;4156.58434156588;5808.448197421239;5811.651300921958;5821.563382746311;5853.209816674695;5866.73599688015;5929.6145750526075;6181.103739957681;6184.567080304426;6185.597936134227;6189.257289125334;5682.801101961494;5688.800891968761;5722.084466485342;5774.0184168690585;5802.448767367094;5316.122839731478;5353.197020446886;5355.384130825005;5357.973210133924;5360.544353729899;6067.544934129958;6161.476274516716;6332.081848131995;6333.303336632966;6346.746130154835;6003.90994135097;6102.214328711749;6104.711937638747;6116.480099191618;6129.816105516842;6299.685015749103;6355;6355.396237357444;6355.52333574993;6371.044343348535;5800.332961709458;5916.3492015877355;5923.141144533933;6125.479334256638;6127.632342059491;6032.245969253843;6039.849003774941;6040;6047.576669633259;6056.394360563909;5955.374685657944;5957;5963.314218864886;5964.194833697396;5967.492763115184;5794.855128621818;5900.43945825146;5949.018411961978;5958.225430693982;6108;5896.764129434775;5902.203202567598;5904.734286957183;5910.763569457174;5914.467697907209;5937.495312898452;6022.3977602241175;6026.638401695912;6027.638341699337;6035.547333949883;5654.491095801398;5742.425757424224;5747.655140691572;5824.504917082096;5850.268716410449 05:08:45 INFO - 2015-12-16 05:08:45,403 DEBUG : BROWSER_OUTPUT: |1;ext.html;583.428240324482;614.8155553333999;626.6741294526845;630.3161070238788;634.2959315160174;34972.47541286883;42932.570674293245;42990.14019719612;43012.13975720484;43066.99999999996;46159.30761038579;46616;46646.533534664646;46687;47059.52940470594;8307.252347288742;8495;9138.634454621808;9330.346875718702;10670.199735019865;10862.185336099785;10977.176711746612;10989.06592939601;10997.34015959041;11013.669589912275;10995.725106872313;11092.168087393438;11098.500567474483;11109.055730262937;11121.05471034963;8594.957025214866;8804.955975220148;11171;11442.42787860612;11485.598004069818;10867.45662716861;10950.507227174796;10982.835257471144;10999.285046471954;11020.559177632886;42303.1539369214;42312;42661.57338426607;42676.78661606672;42725.35911961323;3446.190145315849;3497.265574229415;3552.4138517144897;3637.8362973665985;4052.0477687743355;32656.83671581627;32999.50500742491;33092.503612445835;33203.169920751934;33341.499877501854;3946.7631942083567;4060.7969601520103;4071.185762847442;4081.9795901020602;4108.958910410917;313329;314005;314268;317750;318635.4068229667;67395.32604673988;68644.31355686478;71320;71816;71863;74529.62735186289;75675.24324756682;75760.62119689367;75909.62045189849;76221.61889190518;18344.357947471774;18368.265269389074;18375.72436413455;18554.536136596693;18562.628747424977;14410.711785764226;14471.49349772753;14530.128192308495;14539.127652340894;14555.854441455662;22470;22823.771762282497;22879.88560057189;22942;22943.655845162335;8317.209865062809;8350.749477515685;8360.28937540316;8421.410501264974;8519.190676885686;953.2135987810084;956.7416797464646;1003.8544411060356;1024.2215915903819;1092.2245205903898;29254.26864328409;29485.557716634266;29523;29876.55185172224;30597.23506912345;1043.7234132954773;1091.328832767842;1095.0965453500894;1095.4905968724581;1112.4437781109445;15100;15274.541763746884;15843;17756.46730598084;17960;468287;471371;471605;471821;472625;59939;60269.39730602638;60446;60622.69688651529;60644.09033864496;1457.3296283709612;1458.577012666336;1465.0623600895435;1477.793108964746;1485.4949317231976;30526.847365763027;31552.684473154975;34028.31943361169;34538.654613453546;34640;53262.73368633132;53810;53837;53846;53924.46075539194;346220;348084;348694;348888;349405;31200.843995779876;31488.055358339287;35316;36977.630223697415;37044.44433333556;55218;55924.16113758215;56029;56319.43680563142;56640;40893.59106409017;41967;43806.780966094964;45435.318470222315;47613.52386476091;40031.39952900651;40398;40873.795631021654;41142.1771564573;41431.585684142774;40369;40527;40794.388084178165;40879.795601021804;42220;34691;38830.4175437363;39983.400248995706;40443.3933490992;41427.7928610355;27413.725862741117;27666.5850012254;27744.861275693493;28167;28175.859120704266 05:08:45 INFO - 2015-12-16 05:08:45,404 DEBUG : BROWSER_OUTPUT: |2;jquery.html;391.3679407756473;392.0355924425911;393.58869980869986;396.409350068398;398.5615822595144;13918.095323803946;14320.889324895948;17663.29346826127;19408.223671053158;19425.222991080376;13325.466981320735;13724.176549407042;13729.58811235662;20151.59696806063;20444.18223271071;4788.090262850056;4852.344933433994;4888.877778055541;4900.460949295574;4901.191303434946;4830.299606557047;4840.5885499732385;4844.903101937959;4845.878853028667;4846.68496547725;3892.06590418299;4796.592289655384;4801.327814106028;4802.783874725646;4942.777575009133;5267.683938963674;5316.893662126755;5319.281896943901;5328.227407025959;5329.7601607927545;4532.954670453286;4625.213713668684;4627.051454451829;4638.79125439356;4641.837535686269;15425.922870385688;15501.379944802307;15801.683966320608;15821.525354239277;15824;2255.0979608156704;2261.6155253606926;2315.7684231576877;2373.703287089114;2382.630692242705;10995;11033.117350611932;11172.664820055412;11333.206675532716;11400.144989125849;1765.3732924811636;1779.884307520007;1803.9188236529387;1913.7320775091498;1990.8705934114237;68041;68082.65958670224;68346;69535;71024.64487677529;7749.612519374009;7904.84190316202;8015.198480151938;8110.513369197867;8114.269715725613;9653.17947974416;9676.806463870684;9688.90311096894;9690.370125941932;9692.563834627459;6497.64262965532;6594.901076483857;6621.271660117383;6627.906395444697;6632.43624291931;6896.103506544117;6917.550359226634;6922.965385173143;6955.582665040012;6976.372126508639;3515.5781306243416;3696.537932758405;3735.3089678409583;3737.4580685800406;3770.3590389633277;1277.961661150167;1282.1858120093807;1287.5043108403331;1298.811672307529;1317.5586178630074;460.85022367729954;461.227444031253;536.2519285596571;567.7814041594016;569.7379205565486;10705.785884282272;11949.283043017447;12036.518539258332;12117.27296362221;12338.136330456782;489.3662706794709;491.7221769700108;509.0836494310301;536.9758360873692;540.845858930203;7006.929930700628;7164.892526612105;7186.425085993108;7275.4179665626625;7324.4872858900435;445112;445223;449846;450362;656475;85112.57443712742;87514.56242718874;87540.56229718811;87812.56093719491;89467.55266223758;616.4390404731672;627.6892937995722;628.3088602537173;628.6039794929256;629.1066685306876;12090.75818483643;12377.257364558156;12546.1844980076;12599.370031498573;12620.747585048432;39581;39667.60332396639;39756.60243397529;40037;40270.19459610733;48240.7587962072;48427.5157248423;48801;50488.74755626198;50600.49399505958;7293.234210407831;7568.4323675725645;7602.315791578784;7611.314981651678;7621.047369078865;13053.34733263352;13065.934670326587;13094.672633184247;13190.472381104648;13610.251436171106;10876.12990960737;11039.39283339424;11094.112471002461;11107.666769996913;11723.120765942758;9370.297227707813;10226.948865255627;11722;11750.412479375826;11814.350210738492;11056.11551075928;11097.056750176003;11131;11171.497282622138;11225.101991840469;7051.788446346618;7130.144382674112;7139.678714457966;7144.607046612487;7159.391451726661;10473.9476302618;10576.153907687212;10612.8938710615;10791.244612877023;10871.782564348827 05:08:45 INFO - 2015-12-16 05:08:45,405 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1416.1998470863962;1488.6948175623995;1637.5251177158625;1645.835416458355;1813.2021910359433;7713.884291735621;7816.8045798855055;7817.765467035983;7823.687052517899;7827.295543401093;7153.391961683264;7289.125304963399;7720.575368354738;7947.682092716284;7998.240167184123;2388.904443822245;2399.20826127378;2413.9034438622434;2428.890699918499;2442.1086303499183;2155.137944822076;2439.4511234972165;2452.3746444656585;2481.8883150258193;2487.9502409951792;2148.645473496871;2384.5707772600927;2437.549053425113;2437.622168563875;2439.0975339124548;2378.1676413255395;2415.4323733922515;2422.3338581889943;2423.478952025323;2423.5758742220128;2391.2228525729033;2408.5182963407215;2421;2440.5240978009297;2448.4980578981445;7987.321077708401;7995.2804247618;8043.034835819681;8068.6369113389455;8074.354051675852;5076.416212135578;5142.125838607446;5150.072986862363;5150.278960945433;5164.070467315882;3324.517944898;3336.816475093869;3357.3117510910206;3395.405803984293;3417.1969587147;1242.944067516964;1248.7065539598186;1254.0719867298226;1259.829922960407;1300.856905740368;10791.29856559329;10821.621243256448;10823.783524329468;10831.241813073088;10832.241743077988;4660.39414876064;4666.976665116653;4704.764761761899;4739.834105806349;4747.548982846624;9388.108129727665;9391.389559678599;9409.764755881159;9410.294227932933;9414.764630884145;5175.611829112831;5189.6886186828915;5190.81832135881;5191.558717509054;5193.091209038479;4731.408573928259;4733.053389322149;4740.028294199681;4744;4746.833860814924;3867.5939026401825;3892.5328960524917;3894.084890050836;3913.099987002948;3955.940660890089;1131.688785583971;1188.6612315490045;1206.4028305988593;1225.209739717877;1274.3688079258563;1117.776444711061;1139.9772004559863;1139.9943000284945;1353.6683512539491;1423.1247782613618;7706.229377062361;7725.5364678119495;7768.339691126204;7836.451448398671;7856.410769192219;1156.9595064172713;1157.866845312783;1171.6133675886938;1175.7119505721153;1194.665493661776;6186.041163619646;6207.379262073847;6222.0355844844125;6225.782097626561;6247.656378899113;9530.475823829756;9614;9617.855732164024;9618.663346782829;9619.759506012406;9621.037896210462;9758.316917815684;9774.5601447935;9786.902130978742;9800.65697700577;7684.269994350528;7788.84422311562;7793;7800.141984381714;7802.765917022499;7530.661120249492;7586.848263034598;7605;7629.351505122127;7634.236576342321;8828.073052329413;8946.418482798597;8970.865437018581;8988.191062804379;8998.100189980949;8877.511736854534;9099.863502047343;9111.95444022802;9112.908870911471;9113.77215569616;7308.634568271672;7371.41028717712;7376.668049937658;7386.889196662162;7387.9261207387235;7818.921810782047;7881.13307536182;7901.486403383762;8019.759407217793;8059.3552515799765;6453.257875344208;7407.333339999426;7447.404207663482;7552.6223688816435;7556.319931206218;6917.619530925847;7337.413006959324;7352.080989876265;7437.925620743939;7446.73936412223;7419.517731347445;7481.925180748341;7482.625868706652;7492;7501;6828.897566536605;6861.759838405632;6892.104026476726;6907.412869906114;6918.411934985583;7004.754833580801;7018.929810701828;7073.929260707533;7085.397741192057;7088.929110708827 05:08:45 INFO - 2015-12-16 05:08:45,406 DEBUG : BROWSER_OUTPUT: |4;prototype.html;362.1326922021758;370.46097927515467;370.71084554047854;371;376.1142509390386;5991.5506337024735;5996.790112346058;6049.516038716898;6052.63684178949;6054.7587744512375;5723.627964182325;5782.074868021107;5801.274840644919;5922.289325280962;5937.64374137552;4610.884727881797;4625.121226966866;4657.138429390574;4667.043256132485;4671.369365135697;4651.465081515618;4672.2057250267535;4675.719456832601;4681.976590117045;4682.08699303636;4627.537246275362;4627.976860115711;4630.0276941842085;4637.1189474;4638.7216766993915;4562.6121779648765;4605;4607.308903664477;4611.077784443108;4646.303054541812;3976.8011599419915;4008.198360327946;4045.1302969861326;4048.7975601219823;4066.654334381581;5567.860803479945;5580.693061881595;5582.609217354787;5591;5592.86017849553;5283.920741188886;5285.709285989269;5286.894262114736;5290.259363689087;5290.9735451322495;6144.662043587601;6244.219472565929;6255.437010669061;6308.179936608258;6310.337414571496;4467.486239082515;4513.232750432434;4515.458145022585;4516.390287311204;4519.706219095749;6464.482841372679;6538.967305163491;6565.835854103638;6571.835704107436;6573.835654108638;4572.154151481986;4668.486466488684;4669.649776266793;4681.9297710534365;4686.7656617169005;4680.204365257914;4710.411198600175;4719.362886010414;4719.5752382285755;4720.811167553328;5396.1905714143195;5409.945900541023;5410.08028635133;5413.106837371868;5415.83752487426;6401.935980640227;6402.48780097591;6411.711472983727;6413.583117097373;6416.935830641727;1656.138807819951;1691.4587332053625;1694.246060503089;1695.0423010998745;1700.2518891687619;1077.2620754783;1109.5284504085732;1141.1099342512853;1162.110985096402;1164.1850704506878;6046.697665116727;6068.9696551517845;6069.0592958091565;6069.423404776539;6074.878502429927;6380.904286435707;6388.169537960035;6422.903656445157;6428.967855160694;6431;5950.107483877367;6060.363661815446;6064.090386441981;6069.726862291119;6078.0579010252595;3583.4266517357096;3616.87340943071;3618.005048611653;3618.4210526316187;3621.3300539400293;6667.233268174222;6692.59844409337;6698.799036028927;6700.798976030727;6705.09481220029;6612.94192929129;6652.101966234496;6695.598264104169;6696.86606267872;6704.094947182166;6798.082258895085;6807.897881531781;6837.2820853809635;6841.828954276183;6847.760328388483;6646.667666616553;6663.233728121328;6664.800055998134;6667.799966001027;6669.0663307136065;6407.135036769976;6504.252011018793;6506.796242695116;6533.379328963741;6585.176852893388;6769.052332673331;6774.93225067743;6784.21981472137;6784.93215067843;6788.93211067883;4579.335996280587;4599.93100103492;4606.516315786794;4608.101420223024;4609.446866376056;6481.319461456667;6550.737970481127;6556.868862622816;6559.311272316529;6600.339966003361;6473.546851720523;6473.9676301620075;6479.8056058318325;6483.837904052248;6490.448311893542;6416.967915160394;6441.291457939717;6442.838929026624;6463.612183269019;6465.741370345134;6235.3452887446165;6349.49204063683;6364.363563643598;6364.968175159094;6377.649229292248;6498.057781621733;6525.445337146396;6528.902066469102;6529.347065293433;6533.281339052795;6364.490840732823;6366.522510811615;6368;6369.87260254783;6371.139896113966 05:08:45 INFO - 2015-12-16 05:08:45,408 DEBUG : BROWSER_OUTPUT: |5;yui.html;822.9259366657;858.6822875536053;899.7660608241857;938.9718258506934;942.7407462947684;14638.26808659568;15043.699126017473;15044.924775376108;15060;15069;14595.124292542429;14679;14695.632609184775;14725.631859203526;14726;776.1074764021364;776.8407476467311;906.7733066733333;910.0035461170041;919.8528235482324;924.3714274293478;924.796544760155;925.5973651461636;928.8003079337941;931.310829985809;795.4869109424446;967.0667805567626;967.4147140979711;967.8257913575588;970.5632465390568;792.5720111139977;947.758321627984;961.5961296255589;964.5852283518085;966.183574879226;710.2364957670525;710.5594531390519;713.4221280762599;714.9463790215755;715.2382712411318;14841.628959275997;14915.925420372938;15041.398344066225;15063.096214227182;15065.774013389808;13458.461661533527;14015.229162396065;14016.369263383172;14027.78958315626;14056.578302650936;10858.185636077325;10921.453927303683;11010.174236932262;11048.337099774039;11273.830892536618;3790;3891.8054097295303;3897.824597893073;3917.4515567820526;3930.4890364252465;50550.7472462639;52326.47673523254;53335.733321333144;53465.1980220297;53519.73240133814;10060.949695251476;10151.340162889388;10307.948460257801;10435.530401131815;10446.477676116163;20824.1670333188;20953.580928381347;21005.264815731374;21020.684689729667;21049.684254736192;7138.214796372395;7277.7452789153185;8681.869771953427;8823.382363234512;8868.33487488441;4680.859574212779;4752.049590081998;4784.784684689197;4838.564529192389;4914.926276105862;2939.5149800283143;2977.925551861221;2982.89559865408;2983.6419629644583;2990.7158819912074;490.8699194713404;508.18182725811414;509.1471784760511;544.4990608640061;572.8567858035491;669.5771485593115;671.7850287907822;673.5147306439223;673.6934694713893;700.6216643012818;8859.335549833788;9054.139856713733;9093.681721139728;9295.442273463614;9315.767105822408;506.22294777516333;595.6574969392617;599.937006614308;693.5457275484565;708.982275443118;4406.647468202536;4466.397036400044;4497;4502.482214545369;4523.751193684379;67123;67235.66382168156;67327.3267267321;67476.32523674799;67517.66241168763;59995.400045998984;60756;61110.38889611047;61868.69065654733;62073.689631551555;53709;54457;54528.72735636376;55026.44973550293;55116.72441637766;8965.924089109349;9132.726018219464;9323.393979391318;9486.33595648298;9854.310198286052;40123.598764011986;40739.592604074765;40934.18131637291;41178.38232426456;41505.58494415138;53225.20162197493;54561.181582277095;54797.178042328596;54915.72542137264;55502.72248638731;8387.370947178864;8470.195331443503;8644.610992505479;8736.78158046054;8761.474311541328;12094;12588.685282868002;12774.23354598727;12800.231986080866;13333.266670333225;11763.176577639482;11950.880491194977;12065.51737930508;12097.2136811107;12339;11331.716707082389;11549.88450115488;11898.702532436759;12113.818292725793;12121.393930303626;10912;10995.615153469591;11391.772164556496;11443.542258309908;11607.245529040585;9722.222222222346;9732.708018759165;9795.412275263507;9849.901500985185;9946.801063978535;9647.228221742384;9902.75243118899;9955.20358371314;9993.20054395632;9996.60013599477 05:08:45 INFO - 2015-12-16 05:08:45,408 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:08:45 INFO - 2015-12-16 05:08:45,408 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:08:45 INFO - 2015-12-16 05:08:45,408 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,845 05:08:45 INFO - 2015-12-16 05:08:45,408 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:08:45 INFO - 2015-12-16 05:08:45,408 DEBUG : BROWSER_OUTPUT: __startTimestamp1450271325397__endTimestamp 05:08:45 INFO - 2015-12-16 05:08:45,408 DEBUG : BROWSER_OUTPUT: 05:08:45 INFO - 2015-12-16 05:08:45,408 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:08:45 INFO - 2015-12-16 05:08:45,408 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:08:45 INFO - 2015-12-16 05:08:45,408 DEBUG : BROWSER_OUTPUT: 05:08:45 INFO - 2015-12-16 05:08:45,408 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5667.23 Median:5866.74 stddev:637.12 (10.9%) stddev-sans-first:612.14 05:08:45 INFO - 2015-12-16 05:08:45,408 DEBUG : BROWSER_OUTPUT: Values: 3330.6 3409.1 3425.6 3430.4 3462.2 5663.3 5699.9 5736.5 5750.4 5772.7 5696.9 5734.2 5749.9 5771.9 5817.0 5006.8 5214.2 5249.0 5311.5 5330.9 5161.2 5172.6 5182.6 5183.7 5187.0 5086.7 5105.3 5153.8 5156.3 5218.5 5106.7 5208.9 5210.0 5217.1 5217.9 5199.2 5209.8 5242.2 5247.9 5268.7 5350.1 5395.7 5441.9 5536.4 5563.8 6132.0 6135.3 6140.0 6142.3 6149.1 6137.1 6155.9 6170.4 6175.1 6179.6 5512.2 5667.8 5768.8 5866.6 5885.5 6250.0 6250.2 6257.1 6273.9 6276.7 5791.7 6114.6 6123.4 6127.1 6127.4 5932.1 6112.9 6211.0 6216.3 6218.9 6082.8 6108.8 6117.5 6117.7 6120.6 5781.5 5902.1 6103.5 6106.5 6111.4 4612.5 4621.3 4621.4 4622.6 4623.5 4063.9 4119.3 4150.0 4156.5 4156.6 5808.4 5811.7 5821.6 5853.2 5866.7 5929.6 6181.1 6184.6 6185.6 6189.3 5682.8 5688.8 5722.1 5774.0 5802.4 5316.1 5353.2 5355.4 5358.0 5360.5 6067.5 6161.5 6332.1 6333.3 6346.7 6003.9 6102.2 6104.7 6116.5 6129.8 6299.7 6355.0 6355.4 6355.5 6371.0 5800.3 5916.3 5923.1 6125.5 6127.6 6032.2 6039.8 6040.0 6047.6 6056.4 5955.4 5957.0 5963.3 5964.2 5967.5 5794.9 5900.4 5949.0 5958.2 6108.0 5896.8 5902.2 5904.7 5910.8 5914.5 5937.5 6022.4 6026.6 6027.6 6035.5 5654.5 5742.4 5747.7 5824.5 5850.3 05:08:45 INFO - 2015-12-16 05:08:45,409 DEBUG : BROWSER_OUTPUT: 05:08:45 INFO - 2015-12-16 05:08:45,409 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:56924.98 Median:20307.73 stddev:101124.27 (498.0%) stddev-sans-first:101319.47 05:08:45 INFO - 2015-12-16 05:08:45,409 DEBUG : BROWSER_OUTPUT: Values: 583.4 614.8 626.7 630.3 634.3 34972.5 42932.6 42990.1 43012.1 43067.0 46159.3 46616.0 46646.5 46687.0 47059.5 8307.3 8495.0 9138.6 9330.3 10670.2 10862.2 10977.2 10989.1 10997.3 11013.7 10995.7 11092.2 11098.5 11109.1 11121.1 8595.0 8805.0 11171.0 11442.4 11485.6 10867.5 10950.5 10982.8 10999.3 11020.6 42303.2 42312.0 42661.6 42676.8 42725.4 3446.2 3497.3 3552.4 3637.8 4052.0 32656.8 32999.5 33092.5 33203.2 33341.5 3946.8 4060.8 4071.2 4082.0 4109.0 313329.0 314005.0 314268.0 317750.0 318635.4 67395.3 68644.3 71320.0 71816.0 71863.0 74529.6 75675.2 75760.6 75909.6 76221.6 18344.4 18368.3 18375.7 18554.5 18562.6 14410.7 14471.5 14530.1 14539.1 14555.9 22470.0 22823.8 22879.9 22942.0 22943.7 8317.2 8350.7 8360.3 8421.4 8519.2 953.2 956.7 1003.9 1024.2 1092.2 29254.3 29485.6 29523.0 29876.6 30597.2 1043.7 1091.3 1095.1 1095.5 1112.4 15100.0 15274.5 15843.0 17756.5 17960.0 468287.0 471371.0 471605.0 471821.0 472625.0 59939.0 60269.4 60446.0 60622.7 60644.1 1457.3 1458.6 1465.1 1477.8 1485.5 30526.8 31552.7 34028.3 34538.7 34640.0 53262.7 53810.0 53837.0 53846.0 53924.5 346220.0 348084.0 348694.0 348888.0 349405.0 31200.8 31488.1 35316.0 36977.6 37044.4 55218.0 55924.2 56029.0 56319.4 56640.0 40893.6 41967.0 43806.8 45435.3 47613.5 40031.4 40398.0 40873.8 41142.2 41431.6 40369.0 40527.0 40794.4 40879.8 42220.0 34691.0 38830.4 39983.4 40443.4 41427.8 27413.7 27666.6 27744.9 28167.0 28175.9 05:08:45 INFO - 2015-12-16 05:08:45,409 DEBUG : BROWSER_OUTPUT: 05:08:45 INFO - 2015-12-16 05:08:45,409 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26808.68 Median:461.04 stddev:81794.13 (17741.3%) stddev-sans-first:81999.53 05:08:45 INFO - 2015-12-16 05:08:45,410 DEBUG : BROWSER_OUTPUT: Values: 391.4 392.0 393.6 396.4 398.6 13918.1 14320.9 17663.3 19408.2 19425.2 13325.5 13724.2 13729.6 20151.6 20444.2 4788.1 4852.3 4888.9 4900.5 4901.2 4830.3 4840.6 4844.9 4845.9 4846.7 3892.1 4796.6 4801.3 4802.8 4942.8 5267.7 5316.9 5319.3 5328.2 5329.8 4533.0 4625.2 4627.1 4638.8 4641.8 15425.9 15501.4 15801.7 15821.5 15824.0 2255.1 2261.6 2315.8 2373.7 2382.6 10995.0 11033.1 11172.7 11333.2 11400.1 1765.4 1779.9 1803.9 1913.7 1990.9 68041.0 68082.7 68346.0 69535.0 71024.6 7749.6 7904.8 8015.2 8110.5 8114.3 9653.2 9676.8 9688.9 9690.4 9692.6 6497.6 6594.9 6621.3 6627.9 6632.4 6896.1 6917.6 6923.0 6955.6 6976.4 3515.6 3696.5 3735.3 3737.5 3770.4 1278.0 1282.2 1287.5 1298.8 1317.6 460.9 461.2 536.3 567.8 569.7 10705.8 11949.3 12036.5 12117.3 12338.1 489.4 491.7 509.1 537.0 540.8 7006.9 7164.9 7186.4 7275.4 7324.5 445112.0 445223.0 449846.0 450362.0 656475.0 85112.6 87514.6 87540.6 87812.6 89467.6 616.4 627.7 628.3 628.6 629.1 12090.8 12377.3 12546.2 12599.4 12620.7 39581.0 39667.6 39756.6 40037.0 40270.2 48240.8 48427.5 48801.0 50488.7 50600.5 7293.2 7568.4 7602.3 7611.3 7621.0 13053.3 13065.9 13094.7 13190.5 13610.3 10876.1 11039.4 11094.1 11107.7 11723.1 9370.3 10226.9 11722.0 11750.4 11814.4 11056.1 11097.1 11131.0 11171.5 11225.1 7051.8 7130.1 7139.7 7144.6 7159.4 10473.9 10576.2 10612.9 10791.2 10871.8 05:08:45 INFO - 2015-12-16 05:08:45,410 DEBUG : BROWSER_OUTPUT: 05:08:45 INFO - 2015-12-16 05:08:45,410 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5756.48 Median:6641.08 stddev:2902.48 (43.7%) stddev-sans-first:2892.28 05:08:45 INFO - 2015-12-16 05:08:45,412 DEBUG : BROWSER_OUTPUT: Values: 1416.2 1488.7 1637.5 1645.8 1813.2 7713.9 7816.8 7817.8 7823.7 7827.3 7153.4 7289.1 7720.6 7947.7 7998.2 2388.9 2399.2 2413.9 2428.9 2442.1 2155.1 2439.5 2452.4 2481.9 2488.0 2148.6 2384.6 2437.5 2437.6 2439.1 2378.2 2415.4 2422.3 2423.5 2423.6 2391.2 2408.5 2421.0 2440.5 2448.5 7987.3 7995.3 8043.0 8068.6 8074.4 5076.4 5142.1 5150.1 5150.3 5164.1 3324.5 3336.8 3357.3 3395.4 3417.2 1242.9 1248.7 1254.1 1259.8 1300.9 10791.3 10821.6 10823.8 10831.2 10832.2 4660.4 4667.0 4704.8 4739.8 4747.5 9388.1 9391.4 9409.8 9410.3 9414.8 5175.6 5189.7 5190.8 5191.6 5193.1 4731.4 4733.1 4740.0 4744.0 4746.8 3867.6 3892.5 3894.1 3913.1 3955.9 1131.7 1188.7 1206.4 1225.2 1274.4 1117.8 1140.0 1140.0 1353.7 1423.1 7706.2 7725.5 7768.3 7836.5 7856.4 1157.0 1157.9 1171.6 1175.7 1194.7 6186.0 6207.4 6222.0 6225.8 6247.7 9530.5 9614.0 9617.9 9618.7 9619.8 9621.0 9758.3 9774.6 9786.9 9800.7 7684.3 7788.8 7793.0 7800.1 7802.8 7530.7 7586.8 7605.0 7629.4 7634.2 8828.1 8946.4 8970.9 8988.2 8998.1 8877.5 9099.9 9112.0 9112.9 9113.8 7308.6 7371.4 7376.7 7386.9 7387.9 7818.9 7881.1 7901.5 8019.8 8059.4 6453.3 7407.3 7447.4 7552.6 7556.3 6917.6 7337.4 7352.1 7437.9 7446.7 7419.5 7481.9 7482.6 7492.0 7501.0 6828.9 6861.8 6892.1 6907.4 6918.4 7004.8 7018.9 7073.9 7085.4 7088.9 05:08:45 INFO - 2015-12-16 05:08:45,412 DEBUG : BROWSER_OUTPUT: 05:08:45 INFO - 2015-12-16 05:08:45,412 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5349.30 Median:6053.70 stddev:1580.11 (26.1%) stddev-sans-first:1539.57 05:08:45 INFO - 2015-12-16 05:08:45,412 DEBUG : BROWSER_OUTPUT: Values: 362.1 370.5 370.7 371.0 376.1 5991.6 5996.8 6049.5 6052.6 6054.8 5723.6 5782.1 5801.3 5922.3 5937.6 4610.9 4625.1 4657.1 4667.0 4671.4 4651.5 4672.2 4675.7 4682.0 4682.1 4627.5 4628.0 4630.0 4637.1 4638.7 4562.6 4605.0 4607.3 4611.1 4646.3 3976.8 4008.2 4045.1 4048.8 4066.7 5567.9 5580.7 5582.6 5591.0 5592.9 5283.9 5285.7 5286.9 5290.3 5291.0 6144.7 6244.2 6255.4 6308.2 6310.3 4467.5 4513.2 4515.5 4516.4 4519.7 6464.5 6539.0 6565.8 6571.8 6573.8 4572.2 4668.5 4669.6 4681.9 4686.8 4680.2 4710.4 4719.4 4719.6 4720.8 5396.2 5409.9 5410.1 5413.1 5415.8 6401.9 6402.5 6411.7 6413.6 6416.9 1656.1 1691.5 1694.2 1695.0 1700.3 1077.3 1109.5 1141.1 1162.1 1164.2 6046.7 6069.0 6069.1 6069.4 6074.9 6380.9 6388.2 6422.9 6429.0 6431.0 5950.1 6060.4 6064.1 6069.7 6078.1 3583.4 3616.9 3618.0 3618.4 3621.3 6667.2 6692.6 6698.8 6700.8 6705.1 6612.9 6652.1 6695.6 6696.9 6704.1 6798.1 6807.9 6837.3 6841.8 6847.8 6646.7 6663.2 6664.8 6667.8 6669.1 6407.1 6504.3 6506.8 6533.4 6585.2 6769.1 6774.9 6784.2 6784.9 6788.9 4579.3 4599.9 4606.5 4608.1 4609.4 6481.3 6550.7 6556.9 6559.3 6600.3 6473.5 6474.0 6479.8 6483.8 6490.4 6417.0 6441.3 6442.8 6463.6 6465.7 6235.3 6349.5 6364.4 6365.0 6377.6 6498.1 6525.4 6528.9 6529.3 6533.3 6364.5 6366.5 6368.0 6369.9 6371.1 05:08:45 INFO - 2015-12-16 05:08:45,412 DEBUG : BROWSER_OUTPUT: 05:08:45 INFO - 2015-12-16 05:08:45,412 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15500.43 Median:27126.75 stddev:18840.70 (69.5%) stddev-sans-first:18861.31 05:08:45 INFO - 2015-12-16 05:08:45,413 DEBUG : BROWSER_OUTPUT: Values: 822.9 858.7 899.8 939.0 942.7 14638.3 15043.7 15044.9 15060.0 15069.0 14595.1 14679.0 14695.6 14725.6 14726.0 776.1 776.8 906.8 910.0 919.9 924.4 924.8 925.6 928.8 931.3 795.5 967.1 967.4 967.8 970.6 792.6 947.8 961.6 964.6 966.2 710.2 710.6 713.4 714.9 715.2 14841.6 14915.9 15041.4 15063.1 15065.8 13458.5 14015.2 14016.4 14027.8 14056.6 10858.2 10921.5 11010.2 11048.3 11273.8 3790.0 3891.8 3897.8 3917.5 3930.5 50550.7 52326.5 53335.7 53465.2 53519.7 10060.9 10151.3 10307.9 10435.5 10446.5 20824.2 20953.6 21005.3 21020.7 21049.7 7138.2 7277.7 8681.9 8823.4 8868.3 4680.9 4752.0 4784.8 4838.6 4914.9 2939.5 2977.9 2982.9 2983.6 2990.7 490.9 508.2 509.1 544.5 572.9 669.6 671.8 673.5 673.7 700.6 8859.3 9054.1 9093.7 9295.4 9315.8 506.2 595.7 599.9 693.5 709.0 4406.6 4466.4 4497.0 4502.5 4523.8 67123.0 67235.7 67327.3 67476.3 67517.7 59995.4 60756.0 61110.4 61868.7 62073.7 53709.0 54457.0 54528.7 55026.4 55116.7 8965.9 9132.7 9323.4 9486.3 9854.3 40123.6 40739.6 40934.2 41178.4 41505.6 53225.2 54561.2 54797.2 54915.7 55502.7 8387.4 8470.2 8644.6 8736.8 8761.5 12094.0 12588.7 12774.2 12800.2 13333.3 11763.2 11950.9 12065.5 12097.2 12339.0 11331.7 11549.9 11898.7 12113.8 12121.4 10912.0 10995.6 11391.8 11443.5 11607.2 9722.2 9732.7 9795.4 9849.9 9946.8 9647.2 9902.8 9955.2 9993.2 9996.6 05:08:45 INFO - 2015-12-16 05:08:45,413 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:08:45 INFO - 2015-12-16 05:08:45,413 DEBUG : BROWSER_OUTPUT: 05:08:46 INFO - 2015-12-16 05:08:46,001 INFO : Browser exited with error code: 0 05:08:46 INFO - 2015-12-16 05:08:46,024 INFO : Completed test dromaeo_css (00:18:31) 05:08:46 INFO - 2015-12-16 05:08:46,024 INFO : Starting test kraken 05:08:46 INFO - 2015-12-16 05:08:46,024 DEBUG : operating with platform_type : linux_ 05:08:46 INFO - 2015-12-16 05:08:46,024 INFO : Initialising browser for kraken test... 05:08:46 INFO - 2015-12-16 05:08:46,031 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZn8zQu/profile http://localhost:50084/getInfo.html 05:08:50 INFO - 2015-12-16 05:08:50,495 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:08:50 INFO - 2015-12-16 05:08:50,495 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:08:50 INFO - 2015-12-16 05:08:50,495 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:08:50 INFO - 2015-12-16 05:08:50,495 DEBUG : BROWSER_OUTPUT: __metrics 05:08:50 INFO - 2015-12-16 05:08:50,620 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:08:52 INFO - 2015-12-16 05:08:52,200 INFO : Browser initialized. 05:08:52 INFO - 2015-12-16 05:08:52,200 INFO : Running cycle 1/1 for kraken test... 05:08:52 INFO - 2015-12-16 05:08:52,200 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZn8zQu/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:09:03 INFO - 2015-12-16 05:09:03,889 DEBUG : BROWSER_OUTPUT: RSS: Main: 130797568 05:09:03 INFO - 2015-12-16 05:09:03,889 DEBUG : BROWSER_OUTPUT: 05:09:06 INFO - 2015-12-16 05:09:06,296 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50084/tests/kraken/driver.html?testName=audio-beat-detection) 05:09:06 INFO - 2015-12-16 05:09:06,877 DEBUG : BROWSER_OUTPUT: RSS: Main: 164413440 05:09:06 INFO - 2015-12-16 05:09:06,877 DEBUG : BROWSER_OUTPUT: 05:09:09 INFO - 2015-12-16 05:09:09,394 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50084/tests/kraken/driver.html?testName=audio-dft) 05:09:09 INFO - 2015-12-16 05:09:09,956 DEBUG : BROWSER_OUTPUT: RSS: Main: 161976320 05:09:09 INFO - 2015-12-16 05:09:09,956 DEBUG : BROWSER_OUTPUT: 05:09:12 INFO - 2015-12-16 05:09:12,698 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50084/tests/kraken/driver.html?testName=audio-fft) 05:09:13 INFO - 2015-12-16 05:09:13,260 DEBUG : BROWSER_OUTPUT: RSS: Main: 158748672 05:09:13 INFO - 2015-12-16 05:09:13,260 DEBUG : BROWSER_OUTPUT: 05:09:15 INFO - 2015-12-16 05:09:15,213 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50084/tests/kraken/driver.html?testName=audio-oscillator) 05:09:15 INFO - 2015-12-16 05:09:15,774 DEBUG : BROWSER_OUTPUT: RSS: Main: 159920128 05:09:15 INFO - 2015-12-16 05:09:15,774 DEBUG : BROWSER_OUTPUT: 05:09:17 INFO - 2015-12-16 05:09:17,876 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50084/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:09:18 INFO - 2015-12-16 05:09:18,430 DEBUG : BROWSER_OUTPUT: RSS: Main: 157216768 05:09:18 INFO - 2015-12-16 05:09:18,430 DEBUG : BROWSER_OUTPUT: 05:09:21 INFO - 2015-12-16 05:09:21,314 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50084/tests/kraken/driver.html?testName=imaging-darkroom) 05:09:21 INFO - 2015-12-16 05:09:21,880 DEBUG : BROWSER_OUTPUT: RSS: Main: 155439104 05:09:21 INFO - 2015-12-16 05:09:21,880 DEBUG : BROWSER_OUTPUT: 05:09:25 INFO - 2015-12-16 05:09:25,263 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50084/tests/kraken/driver.html?testName=imaging-desaturate) 05:09:25 INFO - 2015-12-16 05:09:25,844 DEBUG : BROWSER_OUTPUT: RSS: Main: 158064640 05:09:25 INFO - 2015-12-16 05:09:25,845 DEBUG : BROWSER_OUTPUT: 05:09:28 INFO - 2015-12-16 05:09:28,543 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50084/tests/kraken/driver.html?testName=json-parse-financial) 05:09:29 INFO - 2015-12-16 05:09:29,107 DEBUG : BROWSER_OUTPUT: RSS: Main: 156745728 05:09:29 INFO - 2015-12-16 05:09:29,107 DEBUG : BROWSER_OUTPUT: 05:09:30 INFO - 2015-12-16 05:09:30,627 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50084/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:09:31 INFO - 2015-12-16 05:09:31,189 DEBUG : BROWSER_OUTPUT: RSS: Main: 161882112 05:09:31 INFO - 2015-12-16 05:09:31,189 DEBUG : BROWSER_OUTPUT: 05:09:32 INFO - 2015-12-16 05:09:32,919 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50084/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:09:33 INFO - 2015-12-16 05:09:33,506 DEBUG : BROWSER_OUTPUT: RSS: Main: 161447936 05:09:33 INFO - 2015-12-16 05:09:33,506 DEBUG : BROWSER_OUTPUT: 05:09:35 INFO - 2015-12-16 05:09:35,445 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50084/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:09:36 INFO - 2015-12-16 05:09:36,018 DEBUG : BROWSER_OUTPUT: RSS: Main: 165511168 05:09:36 INFO - 2015-12-16 05:09:36,019 DEBUG : BROWSER_OUTPUT: 05:09:38 INFO - 2015-12-16 05:09:38,294 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50084/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:09:38 INFO - 2015-12-16 05:09:38,867 DEBUG : BROWSER_OUTPUT: RSS: Main: 169807872 05:09:38 INFO - 2015-12-16 05:09:38,868 DEBUG : BROWSER_OUTPUT: 05:09:41 INFO - 2015-12-16 05:09:41,275 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50084/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:09:41 INFO - 2015-12-16 05:09:41,837 DEBUG : BROWSER_OUTPUT: RSS: Main: 167399424 05:09:41 INFO - 2015-12-16 05:09:41,837 DEBUG : BROWSER_OUTPUT: 05:09:43 INFO - 2015-12-16 05:09:43,446 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50084/tests/kraken/driver.html?testName=ai-astar) 05:09:43 INFO - 2015-12-16 05:09:43,690 DEBUG : BROWSER_OUTPUT: RSS: Main: 227991552 05:09:43 INFO - 2015-12-16 05:09:43,690 DEBUG : BROWSER_OUTPUT: 05:09:43 INFO - 2015-12-16 05:09:43,691 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:09:43 INFO - 2015-12-16 05:09:43,693 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:09:43 INFO - 2015-12-16 05:09:43,693 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:09:43 INFO - 2015-12-16 05:09:43,693 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:09:43 INFO - 2015-12-16 05:09:43,693 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;110;104;108;109;110;97;109;106;108 05:09:43 INFO - 2015-12-16 05:09:43,694 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;147;120;147;155;136;147;151;134;157;120 05:09:43 INFO - 2015-12-16 05:09:43,694 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;159;161;161;159;161;159;164;161;159 05:09:43 INFO - 2015-12-16 05:09:43,694 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;71;156;71;71;71;72;153;70 05:09:43 INFO - 2015-12-16 05:09:43,694 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;89;135;88;90;89;130;88;90;137 05:09:43 INFO - 2015-12-16 05:09:43,694 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;109;109;110;109;109;109;110;110 05:09:43 INFO - 2015-12-16 05:09:43,694 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;146;146;146;145;145;146;146;146 05:09:43 INFO - 2015-12-16 05:09:43,695 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;96;96;96;95;96;96;97 05:09:43 INFO - 2015-12-16 05:09:43,695 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;64;59;55;56;55;56;64;52;53 05:09:43 INFO - 2015-12-16 05:09:43,695 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;62;61;61;63;62;61;62;61;62;62 05:09:43 INFO - 2015-12-16 05:09:43,695 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;77;80;79;79;77;77;78;77;78 05:09:43 INFO - 2015-12-16 05:09:43,695 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;107;112;107;107;112;110;121;106;113;113 05:09:43 INFO - 2015-12-16 05:09:43,695 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;148;134;134;135;137;135;136;133;141;135 05:09:43 INFO - 2015-12-16 05:09:43,695 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;62;62;65;63;60;62;60;62;60;61 05:09:43 INFO - 2015-12-16 05:09:43,696 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:09:43 INFO - 2015-12-16 05:09:43,696 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:09:43 INFO - 2015-12-16 05:09:43,696 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,865 05:09:43 INFO - 2015-12-16 05:09:43,696 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:09:43 INFO - 2015-12-16 05:09:43,696 DEBUG : BROWSER_OUTPUT: __startTimestamp1450271383691__endTimestamp 05:09:43 INFO - 2015-12-16 05:09:43,696 DEBUG : BROWSER_OUTPUT: 05:09:43 INFO - 2015-12-16 05:09:43,700 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:09:43 INFO - 2015-12-16 05:09:43,700 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:09:43 INFO - 2015-12-16 05:09:43,700 DEBUG : BROWSER_OUTPUT: 05:09:43 INFO - 2015-12-16 05:09:43,700 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.00 Median:109.00 stddev:3.97 (3.6%) stddev-sans-first:4.15 05:09:43 INFO - 2015-12-16 05:09:43,701 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 104.0 108.0 109.0 110.0 97.0 109.0 106.0 108.0 05:09:43 INFO - 2015-12-16 05:09:43,701 DEBUG : BROWSER_OUTPUT: 05:09:43 INFO - 2015-12-16 05:09:43,701 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.40 Median:147.00 stddev:13.39 (9.1%) stddev-sans-first:14.05 05:09:43 INFO - 2015-12-16 05:09:43,701 DEBUG : BROWSER_OUTPUT: Values: 147.0 120.0 147.0 155.0 136.0 147.0 151.0 134.0 157.0 120.0 05:09:43 INFO - 2015-12-16 05:09:43,701 DEBUG : BROWSER_OUTPUT: 05:09:43 INFO - 2015-12-16 05:09:43,701 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:1.58 (1.0%) stddev-sans-first:1.67 05:09:43 INFO - 2015-12-16 05:09:43,701 DEBUG : BROWSER_OUTPUT: Values: 160.0 159.0 161.0 161.0 159.0 161.0 159.0 164.0 161.0 159.0 05:09:43 INFO - 2015-12-16 05:09:43,702 DEBUG : BROWSER_OUTPUT: 05:09:43 INFO - 2015-12-16 05:09:43,702 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.80 Median:71.00 stddev:35.17 (49.5%) stddev-sans-first:36.77 05:09:43 INFO - 2015-12-16 05:09:43,702 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 71.0 156.0 71.0 71.0 71.0 72.0 153.0 70.0 05:09:43 INFO - 2015-12-16 05:09:43,702 DEBUG : BROWSER_OUTPUT: 05:09:43 INFO - 2015-12-16 05:09:43,702 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.50 Median:89.00 stddev:21.81 (24.5%) stddev-sans-first:22.58 05:09:43 INFO - 2015-12-16 05:09:43,702 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 135.0 88.0 90.0 89.0 130.0 88.0 90.0 137.0 05:09:43 INFO - 2015-12-16 05:09:43,702 DEBUG : BROWSER_OUTPUT: 05:09:43 INFO - 2015-12-16 05:09:43,702 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.40 Median:109.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 05:09:43 INFO - 2015-12-16 05:09:43,702 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 110.0 110.0 05:09:43 INFO - 2015-12-16 05:09:43,702 DEBUG : BROWSER_OUTPUT: 05:09:43 INFO - 2015-12-16 05:09:43,703 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 05:09:43 INFO - 2015-12-16 05:09:43,703 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 146.0 146.0 146.0 145.0 145.0 146.0 146.0 146.0 05:09:43 INFO - 2015-12-16 05:09:43,703 DEBUG : BROWSER_OUTPUT: 05:09:43 INFO - 2015-12-16 05:09:43,703 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 05:09:43 INFO - 2015-12-16 05:09:43,703 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 96.0 96.0 96.0 95.0 96.0 96.0 97.0 05:09:43 INFO - 2015-12-16 05:09:43,703 DEBUG : BROWSER_OUTPUT: 05:09:43 INFO - 2015-12-16 05:09:43,703 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.80 Median:56.00 stddev:4.24 (7.6%) stddev-sans-first:4.37 05:09:43 INFO - 2015-12-16 05:09:43,703 DEBUG : BROWSER_OUTPUT: Values: 54.0 64.0 59.0 55.0 56.0 55.0 56.0 64.0 52.0 53.0 05:09:43 INFO - 2015-12-16 05:09:43,703 DEBUG : BROWSER_OUTPUT: 05:09:43 INFO - 2015-12-16 05:09:43,703 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:61.70 Median:62.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 05:09:43 INFO - 2015-12-16 05:09:43,703 DEBUG : BROWSER_OUTPUT: Values: 62.0 61.0 61.0 63.0 62.0 61.0 62.0 61.0 62.0 62.0 05:09:43 INFO - 2015-12-16 05:09:43,703 DEBUG : BROWSER_OUTPUT: 05:09:43 INFO - 2015-12-16 05:09:43,703 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:78.00 stddev:1.05 (1.4%) stddev-sans-first:1.12 05:09:43 INFO - 2015-12-16 05:09:43,704 DEBUG : BROWSER_OUTPUT: Values: 78.0 77.0 80.0 79.0 79.0 77.0 77.0 78.0 77.0 78.0 05:09:43 INFO - 2015-12-16 05:09:43,704 DEBUG : BROWSER_OUTPUT: 05:09:43 INFO - 2015-12-16 05:09:43,704 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:110.80 Median:112.00 stddev:4.52 (4.0%) stddev-sans-first:4.58 05:09:43 INFO - 2015-12-16 05:09:43,704 DEBUG : BROWSER_OUTPUT: Values: 107.0 112.0 107.0 107.0 112.0 110.0 121.0 106.0 113.0 113.0 05:09:43 INFO - 2015-12-16 05:09:43,704 DEBUG : BROWSER_OUTPUT: 05:09:43 INFO - 2015-12-16 05:09:43,704 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:136.80 Median:135.50 stddev:4.52 (3.3%) stddev-sans-first:2.35 05:09:43 INFO - 2015-12-16 05:09:43,704 DEBUG : BROWSER_OUTPUT: Values: 148.0 134.0 134.0 135.0 137.0 135.0 136.0 133.0 141.0 135.0 05:09:43 INFO - 2015-12-16 05:09:43,704 DEBUG : BROWSER_OUTPUT: 05:09:43 INFO - 2015-12-16 05:09:43,704 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:62.00 stddev:1.57 (2.5%) stddev-sans-first:1.66 05:09:43 INFO - 2015-12-16 05:09:43,704 DEBUG : BROWSER_OUTPUT: Values: 62.0 62.0 65.0 63.0 60.0 62.0 60.0 62.0 60.0 61.0 05:09:43 INFO - 2015-12-16 05:09:43,704 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:09:43 INFO - 2015-12-16 05:09:43,704 DEBUG : BROWSER_OUTPUT: 05:09:44 INFO - 2015-12-16 05:09:44,417 INFO : Browser exited with error code: 0 05:09:44 INFO - 2015-12-16 05:09:44,472 INFO : Completed test kraken (00:00:58) 05:09:44 INFO - 2015-12-16 05:09:44,473 INFO : Starting test v8_7 05:09:44 INFO - 2015-12-16 05:09:44,473 DEBUG : operating with platform_type : linux_ 05:09:44 INFO - 2015-12-16 05:09:44,473 INFO : Initialising browser for v8_7 test... 05:09:44 INFO - 2015-12-16 05:09:44,479 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUS1VMk/profile http://localhost:50084/getInfo.html 05:09:49 INFO - 2015-12-16 05:09:49,183 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:09:49 INFO - 2015-12-16 05:09:49,184 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:09:49 INFO - 2015-12-16 05:09:49,184 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:09:49 INFO - 2015-12-16 05:09:49,184 DEBUG : BROWSER_OUTPUT: __metrics 05:09:49 INFO - 2015-12-16 05:09:49,264 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:09:50 INFO - 2015-12-16 05:09:50,749 INFO : Browser initialized. 05:09:50 INFO - 2015-12-16 05:09:50,749 INFO : Running cycle 1/1 for v8_7 test... 05:09:50 INFO - 2015-12-16 05:09:50,749 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUS1VMk/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:10:02 INFO - 2015-12-16 05:10:02,371 DEBUG : BROWSER_OUTPUT: RSS: Main: 131416064 05:10:02 INFO - 2015-12-16 05:10:02,371 DEBUG : BROWSER_OUTPUT: 05:10:23 INFO - 2015-12-16 05:10:23,797 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50084/tests/v8_7/run.html (next: http://localhost:50084/tests/v8_7/run.html) 05:10:24 INFO - 2015-12-16 05:10:24,048 DEBUG : BROWSER_OUTPUT: RSS: Main: 419340288 05:10:24 INFO - 2015-12-16 05:10:24,048 DEBUG : BROWSER_OUTPUT: 05:10:24 INFO - 2015-12-16 05:10:24,049 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:10:24 INFO - 2015-12-16 05:10:24,049 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:10:24 INFO - 2015-12-16 05:10:24,049 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:10:24 INFO - 2015-12-16 05:10:24,049 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:10:24 INFO - 2015-12-16 05:10:24,049 DEBUG : BROWSER_OUTPUT: |0;Richards;163.371998039536 05:10:24 INFO - 2015-12-16 05:10:24,049 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.06802721088437 05:10:24 INFO - 2015-12-16 05:10:24,050 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.3296089385475 05:10:24 INFO - 2015-12-16 05:10:24,050 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5020 05:10:24 INFO - 2015-12-16 05:10:24,050 DEBUG : BROWSER_OUTPUT: |4;RayTrace;938.9671361502348 05:10:24 INFO - 2015-12-16 05:10:24,050 DEBUG : BROWSER_OUTPUT: |5;Earley;552.4861878453039 05:10:24 INFO - 2015-12-16 05:10:24,051 DEBUG : BROWSER_OUTPUT: |6;Boyer;10443.298969072164 05:10:24 INFO - 2015-12-16 05:10:24,051 DEBUG : BROWSER_OUTPUT: |7;RegExp;23904.761904761905 05:10:24 INFO - 2015-12-16 05:10:24,051 DEBUG : BROWSER_OUTPUT: |8;Splay;517.8663904712585 05:10:24 INFO - 2015-12-16 05:10:24,051 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4873.78640776699 05:10:24 INFO - 2015-12-16 05:10:24,051 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:10:24 INFO - 2015-12-16 05:10:24,051 DEBUG : BROWSER_OUTPUT: __startTimestamp1450271424046__endTimestamp 05:10:24 INFO - 2015-12-16 05:10:24,051 DEBUG : BROWSER_OUTPUT: 05:10:24 INFO - 2015-12-16 05:10:24,051 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:10:24 INFO - 2015-12-16 05:10:24,051 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:10:24 INFO - 2015-12-16 05:10:24,051 DEBUG : BROWSER_OUTPUT: 05:10:24 INFO - 2015-12-16 05:10:24,051 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.37 Median:163.37 stddev:0.00 (0.0%) 05:10:24 INFO - 2015-12-16 05:10:24,051 DEBUG : BROWSER_OUTPUT: Values: 163.4 05:10:24 INFO - 2015-12-16 05:10:24,052 DEBUG : BROWSER_OUTPUT: 05:10:24 INFO - 2015-12-16 05:10:24,052 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.07 Median:170.07 stddev:0.00 (0.0%) 05:10:24 INFO - 2015-12-16 05:10:24,052 DEBUG : BROWSER_OUTPUT: Values: 170.1 05:10:24 INFO - 2015-12-16 05:10:24,052 DEBUG : BROWSER_OUTPUT: 05:10:24 INFO - 2015-12-16 05:10:24,052 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.33 Median:279.33 stddev:0.00 (0.0%) 05:10:24 INFO - 2015-12-16 05:10:24,052 DEBUG : BROWSER_OUTPUT: Values: 279.3 05:10:24 INFO - 2015-12-16 05:10:24,052 DEBUG : BROWSER_OUTPUT: 05:10:24 INFO - 2015-12-16 05:10:24,052 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5020.00 Median:5020.00 stddev:0.00 (0.0%) 05:10:24 INFO - 2015-12-16 05:10:24,052 DEBUG : BROWSER_OUTPUT: Values: 5020.0 05:10:24 INFO - 2015-12-16 05:10:24,052 DEBUG : BROWSER_OUTPUT: 05:10:24 INFO - 2015-12-16 05:10:24,052 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:938.97 Median:938.97 stddev:0.00 (0.0%) 05:10:24 INFO - 2015-12-16 05:10:24,052 DEBUG : BROWSER_OUTPUT: Values: 939.0 05:10:24 INFO - 2015-12-16 05:10:24,052 DEBUG : BROWSER_OUTPUT: 05:10:24 INFO - 2015-12-16 05:10:24,053 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:552.49 Median:552.49 stddev:0.00 (0.0%) 05:10:24 INFO - 2015-12-16 05:10:24,053 DEBUG : BROWSER_OUTPUT: Values: 552.5 05:10:24 INFO - 2015-12-16 05:10:24,053 DEBUG : BROWSER_OUTPUT: 05:10:24 INFO - 2015-12-16 05:10:24,053 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10443.30 Median:10443.30 stddev:0.00 (0.0%) 05:10:24 INFO - 2015-12-16 05:10:24,053 DEBUG : BROWSER_OUTPUT: Values: 10443.3 05:10:24 INFO - 2015-12-16 05:10:24,053 DEBUG : BROWSER_OUTPUT: 05:10:24 INFO - 2015-12-16 05:10:24,053 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23904.76 Median:23904.76 stddev:0.00 (0.0%) 05:10:24 INFO - 2015-12-16 05:10:24,053 DEBUG : BROWSER_OUTPUT: Values: 23904.8 05:10:24 INFO - 2015-12-16 05:10:24,053 DEBUG : BROWSER_OUTPUT: 05:10:24 INFO - 2015-12-16 05:10:24,053 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:517.87 Median:517.87 stddev:0.00 (0.0%) 05:10:24 INFO - 2015-12-16 05:10:24,053 DEBUG : BROWSER_OUTPUT: Values: 517.9 05:10:24 INFO - 2015-12-16 05:10:24,053 DEBUG : BROWSER_OUTPUT: 05:10:24 INFO - 2015-12-16 05:10:24,053 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4873.79 Median:4873.79 stddev:0.00 (0.0%) 05:10:24 INFO - 2015-12-16 05:10:24,054 DEBUG : BROWSER_OUTPUT: Values: 4873.8 05:10:24 INFO - 2015-12-16 05:10:24,054 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:10:24 INFO - 2015-12-16 05:10:24,054 DEBUG : BROWSER_OUTPUT: 05:10:24 INFO - 2015-12-16 05:10:24,737 INFO : Browser exited with error code: 0 05:10:24 INFO - 2015-12-16 05:10:24,754 INFO : Completed test v8_7 (00:00:40) 05:10:25 INFO - 2015-12-16 05:10:25,039 INFO : Completed test suite (00:20:10) 05:10:25 INFO - 2015-12-16 05:10:25,040 DEBUG : Working with test: dromaeo_css 05:10:25 INFO - 2015-12-16 05:10:25,040 DEBUG : Generating results file: dromaeo_css 05:10:25 INFO - 2015-12-16 05:10:25,041 DEBUG : Working with test: kraken 05:10:25 INFO - 2015-12-16 05:10:25,041 DEBUG : Generating results file: kraken 05:10:25 INFO - 2015-12-16 05:10:25,041 INFO : javascript benchmark 05:10:25 INFO - 2015-12-16 05:10:25,041 DEBUG : Working with test: v8_7 05:10:25 INFO - 2015-12-16 05:10:25,041 DEBUG : Generating results file: v8_7 05:10:25 INFO - 2015-12-16 05:10:25,042 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:10:25 INFO - 2015-12-16 05:10:25,106 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 05:10:25 INFO - 2015-12-16 05:10:25,106 DEBUG : process_Request line: dromaeo_css 7215.27 graph.html#tests=[[72,53,35]] 05:10:25 INFO - 2015-12-16 05:10:25,106 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:10:25 INFO - 2015-12-16 05:10:25,149 DEBUG : process_Request line: kraken 1455.70 graph.html#tests=[[232,53,35]] 05:10:25 INFO - 2015-12-16 05:10:25,149 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:10:25 INFO - 2015-12-16 05:10:25,263 DEBUG : process_Request line: v8_7 23380.56 graph.html#tests=[[230,53,35]] 05:10:25 INFO - 2015-12-16 05:10:25,264 INFO : javascript benchmark 05:10:25 INFO - 2015-12-16 05:10:25,268 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3330.5503756992807, 3409.1306716787217, 3425.640307767684, 3430.3825311443948, 3462.2036931505745, 5663.3204015518095, 5699.914501282479, 5736.512396446296, 5750.3962083981205, 5772.711364431777, 5696.914546281809, 5734.197212390258, 5749.942500574993, 5771.884562308751, 5817.040188368914, 5006.774695138727, 5214.191800270954, 5249.0, 5311.49540793624, 5330.946690533084, 5161.225816127573, 5172.586193104543, 5182.611304152185, 5183.714895680736, 5187.014467251211, 5086.745662716868, 5105.310783044297, 5153.768080436389, 5156.278121063054, 5218.530332270095, 5106.744662766847, 5208.869778255536, 5210.03614331348, 5217.113090774577, 5217.895642087175, 5199.246109314128, 5209.791608335662, 5242.161254199348, 5247.921281180786, 5268.736563171826, 5350.117230656937, 5395.7032363220005, 5441.891162176774, 5536.390996990328, 5563.833085007456, 6132.049532322497, 6135.294441139237, 6139.969300153515, 6142.262928448615, 6149.13912052313, 6137.094037567105, 6155.907661385083, 6170.444659980623, 6175.135481032659, 6179.5674302798825, 5512.228288039678, 5667.829965101054, 5768.7980920668, 5866.560007999332, 5885.470307672373, 6250.0, 6250.1874756282305, 6257.092721555349, 6273.905891411632, 6276.6547839869245, 5791.739371728282, 6114.602550834182, 6123.418275263843, 6127.080937859357, 6127.417895299939, 5932.080527518242, 6112.908306375408, 6211.0, 6216.2540495139965, 6218.9378106219265, 6082.787102451393, 6108.75564977396, 6117.541184411098, 6117.724702388403, 6120.632762034292, 5781.537477001913, 5902.085176797603, 6103.542234332443, 6106.5114790817515, 6111.411033131329, 4612.515685852937, 4621.260598304255, 4621.445426548836, 4622.607078398374, 4623.514530974267, 4063.8577649782114, 4119.320312148522, 4150.024744185114, 4156.480439945007, 4156.58434156588, 5808.448197421239, 5811.651300921958, 5821.563382746311, 5853.209816674695, 5866.73599688015, 5929.6145750526075, 6181.103739957681, 6184.567080304426, 6185.597936134227, 6189.257289125334, 5682.801101961494, 5688.800891968761, 5722.084466485342, 5774.0184168690585, 5802.448767367094, 5316.122839731478, 5353.197020446886, 5355.384130825005, 5357.973210133924, 5360.544353729899, 6067.544934129958, 6161.476274516716, 6332.081848131995, 6333.303336632966, 6346.746130154835, 6003.90994135097, 6102.214328711749, 6104.711937638747, 6116.480099191618, 6129.816105516842, 6299.685015749103, 6355.0, 6355.396237357444, 6355.52333574993, 6371.044343348535, 5800.332961709458, 5916.3492015877355, 5923.141144533933, 6125.479334256638, 6127.632342059491, 6032.245969253843, 6039.849003774941, 6040.0, 6047.576669633259, 6056.394360563909, 5955.374685657944, 5957.0, 5963.314218864886, 5964.194833697396, 5967.492763115184, 5794.855128621818, 5900.43945825146, 5949.018411961978, 5958.225430693982, 6108.0, 5896.764129434775, 5902.203202567598, 5904.734286957183, 5910.763569457174, 5914.467697907209, 5937.495312898452, 6022.3977602241175, 6026.638401695912, 6027.638341699337, 6035.547333949883, 5654.491095801398, 5742.425757424224, 5747.655140691572, 5824.504917082096, 5850.268716410449], "unit": "ms", "name": "dojo.html", "value": 5625.443718960583}, {"lowerIsBetter": false, "replicates": [583.428240324482, 614.8155553333999, 626.6741294526845, 630.3161070238788, 634.2959315160174, 34972.47541286883, 42932.570674293245, 42990.14019719612, 43012.13975720484, 43066.99999999996, 46159.30761038579, 46616.0, 46646.533534664646, 46687.0, 47059.52940470594, 8307.252347288742, 8495.0, 9138.634454621808, 9330.346875718702, 10670.199735019865, 10862.185336099785, 10977.176711746612, 10989.06592939601, 10997.34015959041, 11013.669589912275, 10995.725106872313, 11092.168087393438, 11098.500567474483, 11109.055730262937, 11121.05471034963, 8594.957025214866, 8804.955975220148, 11171.0, 11442.42787860612, 11485.598004069818, 10867.45662716861, 10950.507227174796, 10982.835257471144, 10999.285046471954, 11020.559177632886, 42303.1539369214, 42312.0, 42661.57338426607, 42676.78661606672, 42725.35911961323, 3446.190145315849, 3497.265574229415, 3552.4138517144897, 3637.8362973665985, 4052.0477687743355, 32656.83671581627, 32999.50500742491, 33092.503612445835, 33203.169920751934, 33341.499877501854, 3946.7631942083567, 4060.7969601520103, 4071.185762847442, 4081.9795901020602, 4108.958910410917, 313329.0, 314005.0, 314268.0, 317750.0, 318635.4068229667, 67395.32604673988, 68644.31355686478, 71320.0, 71816.0, 71863.0, 74529.62735186289, 75675.24324756682, 75760.62119689367, 75909.62045189849, 76221.61889190518, 18344.357947471774, 18368.265269389074, 18375.72436413455, 18554.536136596693, 18562.628747424977, 14410.711785764226, 14471.49349772753, 14530.128192308495, 14539.127652340894, 14555.854441455662, 22470.0, 22823.771762282497, 22879.88560057189, 22942.0, 22943.655845162335, 8317.209865062809, 8350.749477515685, 8360.28937540316, 8421.410501264974, 8519.190676885686, 953.2135987810084, 956.7416797464646, 1003.8544411060356, 1024.2215915903819, 1092.2245205903898, 29254.26864328409, 29485.557716634266, 29523.0, 29876.55185172224, 30597.23506912345, 1043.7234132954773, 1091.328832767842, 1095.0965453500894, 1095.4905968724581, 1112.4437781109445, 15100.0, 15274.541763746884, 15843.0, 17756.46730598084, 17960.0, 468287.0, 471371.0, 471605.0, 471821.0, 472625.0, 59939.0, 60269.39730602638, 60446.0, 60622.69688651529, 60644.09033864496, 1457.3296283709612, 1458.577012666336, 1465.0623600895435, 1477.793108964746, 1485.4949317231976, 30526.847365763027, 31552.684473154975, 34028.31943361169, 34538.654613453546, 34640.0, 53262.73368633132, 53810.0, 53837.0, 53846.0, 53924.46075539194, 346220.0, 348084.0, 348694.0, 348888.0, 349405.0, 31200.843995779876, 31488.055358339287, 35316.0, 36977.630223697415, 37044.44433333556, 55218.0, 55924.16113758215, 56029.0, 56319.43680563142, 56640.0, 40893.59106409017, 41967.0, 43806.780966094964, 45435.318470222315, 47613.52386476091, 40031.39952900651, 40398.0, 40873.795631021654, 41142.1771564573, 41431.585684142774, 40369.0, 40527.0, 40794.388084178165, 40879.795601021804, 42220.0, 34691.0, 38830.4175437363, 39983.400248995706, 40443.3933490992, 41427.7928610355, 27413.725862741117, 27666.5850012254, 27744.861275693493, 28167.0, 28175.859120704266], "unit": "ms", "name": "ext.html", "value": 21210.785162887372}, {"lowerIsBetter": false, "replicates": [391.3679407756473, 392.0355924425911, 393.58869980869986, 396.409350068398, 398.5615822595144, 13918.095323803946, 14320.889324895948, 17663.29346826127, 19408.223671053158, 19425.222991080376, 13325.466981320735, 13724.176549407042, 13729.58811235662, 20151.59696806063, 20444.18223271071, 4788.090262850056, 4852.344933433994, 4888.877778055541, 4900.460949295574, 4901.191303434946, 4830.299606557047, 4840.5885499732385, 4844.903101937959, 4845.878853028667, 4846.68496547725, 3892.06590418299, 4796.592289655384, 4801.327814106028, 4802.783874725646, 4942.777575009133, 5267.683938963674, 5316.893662126755, 5319.281896943901, 5328.227407025959, 5329.7601607927545, 4532.954670453286, 4625.213713668684, 4627.051454451829, 4638.79125439356, 4641.837535686269, 15425.922870385688, 15501.379944802307, 15801.683966320608, 15821.525354239277, 15824.0, 2255.0979608156704, 2261.6155253606926, 2315.7684231576877, 2373.703287089114, 2382.630692242705, 10995.0, 11033.117350611932, 11172.664820055412, 11333.206675532716, 11400.144989125849, 1765.3732924811636, 1779.884307520007, 1803.9188236529387, 1913.7320775091498, 1990.8705934114237, 68041.0, 68082.65958670224, 68346.0, 69535.0, 71024.64487677529, 7749.612519374009, 7904.84190316202, 8015.198480151938, 8110.513369197867, 8114.269715725613, 9653.17947974416, 9676.806463870684, 9688.90311096894, 9690.370125941932, 9692.563834627459, 6497.64262965532, 6594.901076483857, 6621.271660117383, 6627.906395444697, 6632.43624291931, 6896.103506544117, 6917.550359226634, 6922.965385173143, 6955.582665040012, 6976.372126508639, 3515.5781306243416, 3696.537932758405, 3735.3089678409583, 3737.4580685800406, 3770.3590389633277, 1277.961661150167, 1282.1858120093807, 1287.5043108403331, 1298.811672307529, 1317.5586178630074, 460.85022367729954, 461.227444031253, 536.2519285596571, 567.7814041594016, 569.7379205565486, 10705.785884282272, 11949.283043017447, 12036.518539258332, 12117.27296362221, 12338.136330456782, 489.3662706794709, 491.7221769700108, 509.0836494310301, 536.9758360873692, 540.845858930203, 7006.929930700628, 7164.892526612105, 7186.425085993108, 7275.4179665626625, 7324.4872858900435, 445112.0, 445223.0, 449846.0, 450362.0, 656475.0, 85112.57443712742, 87514.56242718874, 87540.56229718811, 87812.56093719491, 89467.55266223758, 616.4390404731672, 627.6892937995722, 628.3088602537173, 628.6039794929256, 629.1066685306876, 12090.75818483643, 12377.257364558156, 12546.1844980076, 12599.370031498573, 12620.747585048432, 39581.0, 39667.60332396639, 39756.60243397529, 40037.0, 40270.19459610733, 48240.7587962072, 48427.5157248423, 48801.0, 50488.74755626198, 50600.49399505958, 7293.234210407831, 7568.4323675725645, 7602.315791578784, 7611.314981651678, 7621.047369078865, 13053.34733263352, 13065.934670326587, 13094.672633184247, 13190.472381104648, 13610.251436171106, 10876.12990960737, 11039.39283339424, 11094.112471002461, 11107.666769996913, 11723.120765942758, 9370.297227707813, 10226.948865255627, 11722.0, 11750.412479375826, 11814.350210738492, 11056.11551075928, 11097.056750176003, 11131.0, 11171.497282622138, 11225.101991840469, 7051.788446346618, 7130.144382674112, 7139.678714457966, 7144.607046612487, 7159.391451726661, 10473.9476302618, 10576.153907687212, 10612.8938710615, 10791.244612877023, 10871.782564348827], "unit": "ms", "name": "jquery.html", "value": 7545.667541791328}, {"lowerIsBetter": false, "replicates": [1416.1998470863962, 1488.6948175623995, 1637.5251177158625, 1645.835416458355, 1813.2021910359433, 7713.884291735621, 7816.8045798855055, 7817.765467035983, 7823.687052517899, 7827.295543401093, 7153.391961683264, 7289.125304963399, 7720.575368354738, 7947.682092716284, 7998.240167184123, 2388.904443822245, 2399.20826127378, 2413.9034438622434, 2428.890699918499, 2442.1086303499183, 2155.137944822076, 2439.4511234972165, 2452.3746444656585, 2481.8883150258193, 2487.9502409951792, 2148.645473496871, 2384.5707772600927, 2437.549053425113, 2437.622168563875, 2439.0975339124548, 2378.1676413255395, 2415.4323733922515, 2422.3338581889943, 2423.478952025323, 2423.5758742220128, 2391.2228525729033, 2408.5182963407215, 2421.0, 2440.5240978009297, 2448.4980578981445, 7987.321077708401, 7995.2804247618, 8043.034835819681, 8068.6369113389455, 8074.354051675852, 5076.416212135578, 5142.125838607446, 5150.072986862363, 5150.278960945433, 5164.070467315882, 3324.517944898, 3336.816475093869, 3357.3117510910206, 3395.405803984293, 3417.1969587147, 1242.944067516964, 1248.7065539598186, 1254.0719867298226, 1259.829922960407, 1300.856905740368, 10791.29856559329, 10821.621243256448, 10823.783524329468, 10831.241813073088, 10832.241743077988, 4660.39414876064, 4666.976665116653, 4704.764761761899, 4739.834105806349, 4747.548982846624, 9388.108129727665, 9391.389559678599, 9409.764755881159, 9410.294227932933, 9414.764630884145, 5175.611829112831, 5189.6886186828915, 5190.81832135881, 5191.558717509054, 5193.091209038479, 4731.408573928259, 4733.053389322149, 4740.028294199681, 4744.0, 4746.833860814924, 3867.5939026401825, 3892.5328960524917, 3894.084890050836, 3913.099987002948, 3955.940660890089, 1131.688785583971, 1188.6612315490045, 1206.4028305988593, 1225.209739717877, 1274.3688079258563, 1117.776444711061, 1139.9772004559863, 1139.9943000284945, 1353.6683512539491, 1423.1247782613618, 7706.229377062361, 7725.5364678119495, 7768.339691126204, 7836.451448398671, 7856.410769192219, 1156.9595064172713, 1157.866845312783, 1171.6133675886938, 1175.7119505721153, 1194.665493661776, 6186.041163619646, 6207.379262073847, 6222.0355844844125, 6225.782097626561, 6247.656378899113, 9530.475823829756, 9614.0, 9617.855732164024, 9618.663346782829, 9619.759506012406, 9621.037896210462, 9758.316917815684, 9774.5601447935, 9786.902130978742, 9800.65697700577, 7684.269994350528, 7788.84422311562, 7793.0, 7800.141984381714, 7802.765917022499, 7530.661120249492, 7586.848263034598, 7605.0, 7629.351505122127, 7634.236576342321, 8828.073052329413, 8946.418482798597, 8970.865437018581, 8988.191062804379, 8998.100189980949, 8877.511736854534, 9099.863502047343, 9111.95444022802, 9112.908870911471, 9113.77215569616, 7308.634568271672, 7371.41028717712, 7376.668049937658, 7386.889196662162, 7387.9261207387235, 7818.921810782047, 7881.13307536182, 7901.486403383762, 8019.759407217793, 8059.3552515799765, 6453.257875344208, 7407.333339999426, 7447.404207663482, 7552.6223688816435, 7556.319931206218, 6917.619530925847, 7337.413006959324, 7352.080989876265, 7437.925620743939, 7446.73936412223, 7419.517731347445, 7481.925180748341, 7482.625868706652, 7492.0, 7501.0, 6828.897566536605, 6861.759838405632, 6892.104026476726, 6907.412869906114, 6918.411934985583, 7004.754833580801, 7018.929810701828, 7073.929260707533, 7085.397741192057, 7088.929110708827], "unit": "ms", "name": "mootools.html", "value": 4767.872186952402}, {"lowerIsBetter": false, "replicates": [362.1326922021758, 370.46097927515467, 370.71084554047854, 371.0, 376.1142509390386, 5991.5506337024735, 5996.790112346058, 6049.516038716898, 6052.63684178949, 6054.7587744512375, 5723.627964182325, 5782.074868021107, 5801.274840644919, 5922.289325280962, 5937.64374137552, 4610.884727881797, 4625.121226966866, 4657.138429390574, 4667.043256132485, 4671.369365135697, 4651.465081515618, 4672.2057250267535, 4675.719456832601, 4681.976590117045, 4682.08699303636, 4627.537246275362, 4627.976860115711, 4630.0276941842085, 4637.1189474, 4638.7216766993915, 4562.6121779648765, 4605.0, 4607.308903664477, 4611.077784443108, 4646.303054541812, 3976.8011599419915, 4008.198360327946, 4045.1302969861326, 4048.7975601219823, 4066.654334381581, 5567.860803479945, 5580.693061881595, 5582.609217354787, 5591.0, 5592.86017849553, 5283.920741188886, 5285.709285989269, 5286.894262114736, 5290.259363689087, 5290.9735451322495, 6144.662043587601, 6244.219472565929, 6255.437010669061, 6308.179936608258, 6310.337414571496, 4467.486239082515, 4513.232750432434, 4515.458145022585, 4516.390287311204, 4519.706219095749, 6464.482841372679, 6538.967305163491, 6565.835854103638, 6571.835704107436, 6573.835654108638, 4572.154151481986, 4668.486466488684, 4669.649776266793, 4681.9297710534365, 4686.7656617169005, 4680.204365257914, 4710.411198600175, 4719.362886010414, 4719.5752382285755, 4720.811167553328, 5396.1905714143195, 5409.945900541023, 5410.08028635133, 5413.106837371868, 5415.83752487426, 6401.935980640227, 6402.48780097591, 6411.711472983727, 6413.583117097373, 6416.935830641727, 1656.138807819951, 1691.4587332053625, 1694.246060503089, 1695.0423010998745, 1700.2518891687619, 1077.2620754783, 1109.5284504085732, 1141.1099342512853, 1162.110985096402, 1164.1850704506878, 6046.697665116727, 6068.9696551517845, 6069.0592958091565, 6069.423404776539, 6074.878502429927, 6380.904286435707, 6388.169537960035, 6422.903656445157, 6428.967855160694, 6431.0, 5950.107483877367, 6060.363661815446, 6064.090386441981, 6069.726862291119, 6078.0579010252595, 3583.4266517357096, 3616.87340943071, 3618.005048611653, 3618.4210526316187, 3621.3300539400293, 6667.233268174222, 6692.59844409337, 6698.799036028927, 6700.798976030727, 6705.09481220029, 6612.94192929129, 6652.101966234496, 6695.598264104169, 6696.86606267872, 6704.094947182166, 6798.082258895085, 6807.897881531781, 6837.2820853809635, 6841.828954276183, 6847.760328388483, 6646.667666616553, 6663.233728121328, 6664.800055998134, 6667.799966001027, 6669.0663307136065, 6407.135036769976, 6504.252011018793, 6506.796242695116, 6533.379328963741, 6585.176852893388, 6769.052332673331, 6774.93225067743, 6784.21981472137, 6784.93215067843, 6788.93211067883, 4579.335996280587, 4599.93100103492, 4606.516315786794, 4608.101420223024, 4609.446866376056, 6481.319461456667, 6550.737970481127, 6556.868862622816, 6559.311272316529, 6600.339966003361, 6473.546851720523, 6473.9676301620075, 6479.8056058318325, 6483.837904052248, 6490.448311893542, 6416.967915160394, 6441.291457939717, 6442.838929026624, 6463.612183269019, 6465.741370345134, 6235.3452887446165, 6349.49204063683, 6364.363563643598, 6364.968175159094, 6377.649229292248, 6498.057781621733, 6525.445337146396, 6528.902066469102, 6529.347065293433, 6533.281339052795, 6364.490840732823, 6366.522510811615, 6368.0, 6369.87260254783, 6371.139896113966], "unit": "ms", "name": "prototype.html", "value": 4849.6665822661835}, {"lowerIsBetter": false, "replicates": [822.9259366657, 858.6822875536053, 899.7660608241857, 938.9718258506934, 942.7407462947684, 14638.26808659568, 15043.699126017473, 15044.924775376108, 15060.0, 15069.0, 14595.124292542429, 14679.0, 14695.632609184775, 14725.631859203526, 14726.0, 776.1074764021364, 776.8407476467311, 906.7733066733333, 910.0035461170041, 919.8528235482324, 924.3714274293478, 924.796544760155, 925.5973651461636, 928.8003079337941, 931.310829985809, 795.4869109424446, 967.0667805567626, 967.4147140979711, 967.8257913575588, 970.5632465390568, 792.5720111139977, 947.758321627984, 961.5961296255589, 964.5852283518085, 966.183574879226, 710.2364957670525, 710.5594531390519, 713.4221280762599, 714.9463790215755, 715.2382712411318, 14841.628959275997, 14915.925420372938, 15041.398344066225, 15063.096214227182, 15065.774013389808, 13458.461661533527, 14015.229162396065, 14016.369263383172, 14027.78958315626, 14056.578302650936, 10858.185636077325, 10921.453927303683, 11010.174236932262, 11048.337099774039, 11273.830892536618, 3790.0, 3891.8054097295303, 3897.824597893073, 3917.4515567820526, 3930.4890364252465, 50550.7472462639, 52326.47673523254, 53335.733321333144, 53465.1980220297, 53519.73240133814, 10060.949695251476, 10151.340162889388, 10307.948460257801, 10435.530401131815, 10446.477676116163, 20824.1670333188, 20953.580928381347, 21005.264815731374, 21020.684689729667, 21049.684254736192, 7138.214796372395, 7277.7452789153185, 8681.869771953427, 8823.382363234512, 8868.33487488441, 4680.859574212779, 4752.049590081998, 4784.784684689197, 4838.564529192389, 4914.926276105862, 2939.5149800283143, 2977.925551861221, 2982.89559865408, 2983.6419629644583, 2990.7158819912074, 490.8699194713404, 508.18182725811414, 509.1471784760511, 544.4990608640061, 572.8567858035491, 669.5771485593115, 671.7850287907822, 673.5147306439223, 673.6934694713893, 700.6216643012818, 8859.335549833788, 9054.139856713733, 9093.681721139728, 9295.442273463614, 9315.767105822408, 506.22294777516333, 595.6574969392617, 599.937006614308, 693.5457275484565, 708.982275443118, 4406.647468202536, 4466.397036400044, 4497.0, 4502.482214545369, 4523.751193684379, 67123.0, 67235.66382168156, 67327.3267267321, 67476.32523674799, 67517.66241168763, 59995.400045998984, 60756.0, 61110.38889611047, 61868.69065654733, 62073.689631551555, 53709.0, 54457.0, 54528.72735636376, 55026.44973550293, 55116.72441637766, 8965.924089109349, 9132.726018219464, 9323.393979391318, 9486.33595648298, 9854.310198286052, 40123.598764011986, 40739.592604074765, 40934.18131637291, 41178.38232426456, 41505.58494415138, 53225.20162197493, 54561.181582277095, 54797.178042328596, 54915.72542137264, 55502.72248638731, 8387.370947178864, 8470.195331443503, 8644.610992505479, 8736.78158046054, 8761.474311541328, 12094.0, 12588.685282868002, 12774.23354598727, 12800.231986080866, 13333.266670333225, 11763.176577639482, 11950.880491194977, 12065.51737930508, 12097.2136811107, 12339.0, 11331.716707082389, 11549.88450115488, 11898.702532436759, 12113.818292725793, 12121.393930303626, 10912.0, 10995.615153469591, 11391.772164556496, 11443.542258309908, 11607.245529040585, 9722.222222222346, 9732.708018759165, 9795.412275263507, 9849.901500985185, 9946.801063978535, 9647.228221742384, 9902.75243118899, 9955.20358371314, 9993.20054395632, 9996.60013599477], "unit": "ms", "name": "yui.html", "value": 6776.8470868224495}], "name": "dromaeo_css", "value": 7215.26624826594}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 104.0, 108.0, 109.0, 110.0, 97.0, 109.0, 106.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 107.0}, {"lowerIsBetter": true, "replicates": [147.0, 120.0, 147.0, 155.0, 136.0, 147.0, 151.0, 134.0, 157.0, 120.0], "unit": "ms", "name": "audio-beat-detection", "value": 141.4}, {"lowerIsBetter": true, "replicates": [160.0, 159.0, 161.0, 161.0, 159.0, 161.0, 159.0, 164.0, 161.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 160.4}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 71.0, 156.0, 71.0, 71.0, 71.0, 72.0, 153.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 87.8}, {"lowerIsBetter": true, "replicates": [89.0, 89.0, 135.0, 88.0, 90.0, 89.0, 130.0, 88.0, 90.0, 137.0], "unit": "ms", "name": "audio-oscillator", "value": 102.5}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.4}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 97.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.8}, {"lowerIsBetter": true, "replicates": [54.0, 64.0, 59.0, 55.0, 56.0, 55.0, 56.0, 64.0, 52.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 56.8}, {"lowerIsBetter": true, "replicates": [62.0, 61.0, 61.0, 63.0, 62.0, 61.0, 62.0, 61.0, 62.0, 62.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 61.7}, {"lowerIsBetter": true, "replicates": [78.0, 77.0, 80.0, 79.0, 79.0, 77.0, 77.0, 78.0, 77.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.0}, {"lowerIsBetter": true, "replicates": [107.0, 112.0, 107.0, 107.0, 112.0, 110.0, 121.0, 106.0, 113.0, 113.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 110.8}, {"lowerIsBetter": true, "replicates": [148.0, 134.0, 134.0, 135.0, 137.0, 135.0, 136.0, 133.0, 141.0, 135.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 136.8}, {"lowerIsBetter": true, "replicates": [62.0, 62.0, 65.0, 63.0, 60.0, 62.0, 60.0, 62.0, 60.0, 61.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.7}], "name": "kraken", "value": 1455.6999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.371998039536], "unit": "score", "name": "Richards", "value": 216.08354200000005}, {"lowerIsBetter": false, "replicates": [170.06802721088437], "unit": "score", "name": "DeltaBlue", "value": 388.77383999999984}, {"lowerIsBetter": false, "replicates": [279.3296089385475], "unit": "score", "name": "Encrypt", "value": 952.9279799999998}, {"lowerIsBetter": false, "replicates": [5020.0], "unit": "score", "name": "Decrypt", "value": 53.0241035856574}, {"lowerIsBetter": false, "replicates": [938.9671361502348], "unit": "score", "name": "RayTrace", "value": 788.0882850000002}, {"lowerIsBetter": false, "replicates": [552.4861878453039], "unit": "score", "name": "Earley", "value": 1206.29803}, {"lowerIsBetter": false, "replicates": [10443.298969072164], "unit": "score", "name": "Boyer", "value": 63.81728627838104}, {"lowerIsBetter": false, "replicates": [23904.761904761905], "unit": "score", "name": "RegExp", "value": 38.10893426294821}, {"lowerIsBetter": false, "replicates": [517.8663904712585], "unit": "score", "name": "Splay", "value": 157.359121}, {"lowerIsBetter": false, "replicates": [4873.78640776699], "unit": "score", "name": "NavierStokes", "value": 304.48605577689233}], "name": "v8_7", "value": 23380.55798153699}]} 05:10:25 INFO - RETURN: dromaeo_css: 7215.27 05:10:25 INFO - RETURN: kraken: 1455.70 05:10:25 INFO - RETURN: v8_7: 23380.56 05:10:25 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23380.56"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1455.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7215.27"}}} 05:10:25 INFO - Return code: 0 05:10:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:10:25 INFO - # TBPL SUCCESS # 05:10:25 INFO - Running post-action listener: _resource_record_post_action 05:10:25 INFO - Running post-run listener: _resource_record_post_run 05:10:26 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 278528; Write bytes: 509214720; Read time: 1252; Write time: 1186944 05:10:26 INFO - install - Wall time: 15s; CPU: 18.0%; Read bytes: 8192; Write bytes: 250179584; Read time: 556; Write time: 756180 05:10:26 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 270336; Write bytes: 259035136; Read time: 696; Write time: 430764 05:10:26 INFO - Running post-run listener: _upload_blobber_files 05:10:26 INFO - Blob upload gear active. 05:10:26 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:10:26 INFO - Copying logs to upload dir... 05:10:26 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1256.430242 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2015-12-16 05:10:26.380026) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 05:10:26.383809) ========= 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=ccb4611811c763f2fad71465000001de-1450270168.481139-315131454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009899 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450246929/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 05:10:26.435124) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:10:26.435421) ========= 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=ccb4611811c763f2fad71465000001de-1450270168.481139-315131454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004434 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 05:10:26.485028) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 05:10:26.485298) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 05:10:26.485570) ========= ========= Total master_lag: 0.22 =========