builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-054 starttime: 1452731376.31 results: success (0) buildid: 20160113140734 builduid: ffa1cda2ea404d399e57af1486f1ef19 revision: 0fb284f39677 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:36.306200) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:36.306749) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:36.307081) ========= 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=cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006328 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:36.331260) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:36.331784) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:36.346181) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:36.346831) ========= 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=cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004574 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:36.400392) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:36.401057) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:36.401867) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:36.402501) ========= 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=cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-13 16:29:36-- 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% 75.3M=0s 2016-01-13 16:29:36 (75.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.160834 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:36.577009) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:36.577366) ========= 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=cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021473 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:36.630319) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:36.631168) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0fb284f39677 --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 0fb284f39677 --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=cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-13 16:29:36,735 Setting DEBUG logging. 2016-01-13 16:29:36,735 attempt 1/10 2016-01-13 16:29:36,735 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0fb284f39677?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-13 16:29:37,004 unpacking tar archive at: mozilla-beta-0fb284f39677/testing/mozharness/ program finished with exit code 0 elapsedTime=0.564123 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:37.210388) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:37.210791) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:37.221147) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:37.221473) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-13 16:29:37.221958) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-01-13 16:29:37.222290) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:29:37 INFO - MultiFileLogger online at 20160113 16:29:37 in /builds/slave/test-pgo 16:29:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 16:29:37 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 16:29:37 INFO - {'append_to_log': False, 16:29:37 INFO - 'base_work_dir': '/builds/slave/test-pgo', 16:29:37 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:29:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 16:29:37 INFO - 'branch': 'Mozilla-Beta', 16:29:37 INFO - 'buildbot_json_path': 'buildprops.json', 16:29:37 INFO - 'config_files': ('talos/linux_config.py',), 16:29:37 INFO - 'default_actions': ('clobber', 16:29:37 INFO - 'read-buildbot-config', 16:29:37 INFO - 'download-and-extract', 16:29:37 INFO - 'populate-webroot', 16:29:37 INFO - 'create-virtualenv', 16:29:37 INFO - 'install', 16:29:37 INFO - 'run-tests'), 16:29:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:29:37 INFO - 'download_minidump_stackwalk': True, 16:29:37 INFO - 'download_symbols': 'ondemand', 16:29:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:29:37 INFO - 'tooltool.py': '/tools/tooltool.py', 16:29:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:29:37 INFO - '/tools/misc-python/virtualenv.py')}, 16:29:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:29:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:29:37 INFO - 'installer_path': 'installer.exe', 16:29:37 INFO - 'log_level': 'info', 16:29:37 INFO - 'log_name': 'talos', 16:29:37 INFO - 'log_to_console': True, 16:29:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:29:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:29:37 INFO - 'opt_config_files': (), 16:29:37 INFO - 'pip_index': False, 16:29:37 INFO - 'sps_profile': False, 16:29:37 INFO - 'sps_profile_interval': 0, 16:29:37 INFO - 'suite': 'dromaeojs', 16:29:37 INFO - 'system_bits': '32', 16:29:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:29:37 INFO - 'title': 'talos-linux64-ix-054', 16:29:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:29:37 INFO - 'use_talos_json': True, 16:29:37 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 16:29:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:29:37 INFO - 'work_dir': 'build'} 16:29:37 INFO - ##### 16:29:37 INFO - ##### Running clobber step. 16:29:37 INFO - ##### 16:29:37 INFO - Running pre-action listener: _resource_record_pre_action 16:29:37 INFO - Running main action method: clobber 16:29:37 INFO - rmtree: /builds/slave/test-pgo/build 16:29:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 16:29:37 INFO - Running post-action listener: _resource_record_post_action 16:29:37 INFO - ##### 16:29:37 INFO - ##### Running read-buildbot-config step. 16:29:37 INFO - ##### 16:29:37 INFO - Running pre-action listener: _resource_record_pre_action 16:29:37 INFO - Running main action method: read_buildbot_config 16:29:37 INFO - Using buildbot properties: 16:29:37 INFO - { 16:29:37 INFO - "properties": { 16:29:37 INFO - "buildnumber": 74, 16:29:37 INFO - "product": "firefox", 16:29:37 INFO - "script_repo_revision": "production", 16:29:37 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:29:37 INFO - "repository": "", 16:29:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:29:37 INFO - "buildid": "20160113140734", 16:29:37 INFO - "slavename": "talos-linux64-ix-054", 16:29:37 INFO - "pgo_build": "True", 16:29:37 INFO - "basedir": "/builds/slave/test-pgo", 16:29:37 INFO - "project": "", 16:29:37 INFO - "platform": "ubuntu64_hw", 16:29:37 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:29:37 INFO - "slavebuilddir": "test", 16:29:37 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:29:37 INFO - "branch": "mozilla-beta", 16:29:37 INFO - "repo_path": "releases/mozilla-beta", 16:29:37 INFO - "stage_platform": "linux64-pgo", 16:29:37 INFO - "builduid": "ffa1cda2ea404d399e57af1486f1ef19", 16:29:37 INFO - "revision": "0fb284f39677" 16:29:37 INFO - }, 16:29:37 INFO - "sourcestamp": { 16:29:37 INFO - "repository": "", 16:29:37 INFO - "hasPatch": false, 16:29:37 INFO - "project": "", 16:29:37 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 16:29:37 INFO - "changes": [ 16:29:37 INFO - { 16:29:37 INFO - "category": null, 16:29:37 INFO - "files": [ 16:29:37 INFO - { 16:29:37 INFO - "url": null, 16:29:37 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/firefox-44.0.en-US.linux-x86_64.tar.bz2" 16:29:37 INFO - } 16:29:37 INFO - ], 16:29:37 INFO - "repository": "", 16:29:37 INFO - "rev": "0fb284f39677", 16:29:37 INFO - "who": "sendchange", 16:29:37 INFO - "when": 1452731289, 16:29:37 INFO - "number": 7457008, 16:29:37 INFO - "comments": "Back out bug 1236754 now that e10s is off in beta (a=ritu)", 16:29:37 INFO - "project": "", 16:29:37 INFO - "at": "Wed 13 Jan 2016 16:28:09", 16:29:37 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 16:29:37 INFO - "revlink": "", 16:29:37 INFO - "properties": [ 16:29:37 INFO - [ 16:29:37 INFO - "buildid", 16:29:37 INFO - "20160113140734", 16:29:37 INFO - "Change" 16:29:37 INFO - ], 16:29:37 INFO - [ 16:29:37 INFO - "builduid", 16:29:37 INFO - "ffa1cda2ea404d399e57af1486f1ef19", 16:29:37 INFO - "Change" 16:29:37 INFO - ], 16:29:37 INFO - [ 16:29:37 INFO - "pgo_build", 16:29:37 INFO - "True", 16:29:37 INFO - "Change" 16:29:37 INFO - ] 16:29:37 INFO - ], 16:29:37 INFO - "revision": "0fb284f39677" 16:29:37 INFO - } 16:29:37 INFO - ], 16:29:37 INFO - "revision": "0fb284f39677" 16:29:37 INFO - } 16:29:37 INFO - } 16:29:37 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/firefox-44.0.en-US.linux-x86_64.tar.bz2. 16:29:37 INFO - Running post-action listener: _resource_record_post_action 16:29:37 INFO - ##### 16:29:37 INFO - ##### Running download-and-extract step. 16:29:37 INFO - ##### 16:29:37 INFO - Running pre-action listener: _resource_record_pre_action 16:29:37 INFO - Running main action method: download_and_extract 16:29:37 INFO - mkdir: /builds/slave/test-pgo/build/tests 16:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:37 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/test_packages.json 16:29:37 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 16:29:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 16:29:37 INFO - Downloaded 1270 bytes. 16:29:37 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 16:29:37 INFO - Using the following test package requirements: 16:29:37 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 16:29:37 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:29:37 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:29:37 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:29:37 INFO - u'jsshell-linux-x86_64.zip'], 16:29:37 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:29:37 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:29:37 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 16:29:37 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:29:37 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 16:29:37 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:29:37 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 16:29:37 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:29:37 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:29:37 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 16:29:37 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:29:37 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:29:37 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:37 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/firefox-44.0.en-US.linux-x86_64.common.tests.zip 16:29:37 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/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 16:29:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/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 16:29:39 INFO - Downloaded 22572525 bytes. 16:29:39 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 16:29:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 16:29:39 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 16:29:40 INFO - Return code: 0 16:29:40 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 16:29: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')]} 16:29:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/firefox-44.0.en-US.linux-x86_64.common.tests.zip 16:29:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/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 16:29:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/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 16:29:42 INFO - Downloaded 22572525 bytes. 16:29:42 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 16:29:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 16:29:42 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 16:29:43 INFO - Return code: 0 16:29:43 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')]} 16:29:43 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 16:29:43 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/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 16:29:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/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 16:29:45 INFO - Downloaded 11131970 bytes. 16:29:45 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 16:29:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 16:29:45 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 16:29:45 INFO - Return code: 0 16:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:45 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/firefox-44.0.en-US.linux-x86_64.tar.bz2 16:29:45 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/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 16:29:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/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 16:29:49 INFO - Downloaded 56623402 bytes. 16:29:49 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/firefox-44.0.en-US.linux-x86_64.tar.bz2 16:29:49 INFO - mkdir: /builds/slave/test-pgo/properties 16:29:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 16:29:49 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 16:29:49 INFO - Contents: 16:29:49 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/firefox-44.0.en-US.linux-x86_64.tar.bz2 16:29:49 INFO - Running post-action listener: _resource_record_post_action 16:29:49 INFO - Running post-action listener: set_extra_try_arguments 16:29:49 INFO - ##### 16:29:49 INFO - ##### Running populate-webroot step. 16:29:49 INFO - ##### 16:29:49 INFO - Running pre-action listener: _resource_record_pre_action 16:29:49 INFO - Running main action method: populate_webroot 16:29:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:29:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:29:49 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 16:29:49 INFO - '../fennec_ids.txt'], 16:29:49 INFO - 'tests': ['tcheck2']}, 16:29:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:29:49 INFO - '--tppagecycles', 16:29:49 INFO - '7'], 16:29:49 INFO - 'tests': ['tsvgm']}}, 16:29:49 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:29:49 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:29:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:29:49 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:29:49 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:29:49 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:29:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:29:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:49 INFO - 'talos_options': ['--e10s'], 16:29:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:29:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:49 INFO - 'tests': ['damp', 'tps']}, 16:29:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:49 INFO - 'talos_options': ['--e10s'], 16:29:49 INFO - 'tests': ['damp', 'tps']}, 16:29:49 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:29:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:29:49 INFO - 'other': {'tests': ['a11yr', 16:29:49 INFO - 'ts_paint', 16:29:49 INFO - 'tpaint', 16:29:49 INFO - 'sessionrestore', 16:29:49 INFO - 'sessionrestore_no_auto_restore']}, 16:29:49 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:29:49 INFO - 'tests': ['a11yr', 16:29:49 INFO - 'ts_paint', 16:29:49 INFO - 'tpaint', 16:29:49 INFO - 'sessionrestore', 16:29:49 INFO - 'sessionrestore_no_auto_restore']}, 16:29:49 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:29:49 INFO - 'tests': ['a11yr', 16:29:49 INFO - 'ts_paint', 16:29:49 INFO - 'tpaint', 16:29:49 INFO - 'sessionrestore', 16:29:49 INFO - 'sessionrestore_no_auto_restore']}, 16:29:49 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:29:49 INFO - 'tests': ['a11yr', 16:29:49 INFO - 'ts_paint', 16:29:49 INFO - 'tpaint', 16:29:49 INFO - 'sessionrestore', 16:29:49 INFO - 'sessionrestore_no_auto_restore']}, 16:29:49 INFO - 'other_l64': {'tests': ['a11yr', 16:29:49 INFO - 'ts_paint', 16:29:49 INFO - 'tpaint', 16:29:49 INFO - 'sessionrestore', 16:29:49 INFO - 'sessionrestore_no_auto_restore']}, 16:29:49 INFO - 'other_nol64': {'tests': ['a11yr', 16:29:49 INFO - 'ts_paint', 16:29:49 INFO - 'tpaint', 16:29:49 INFO - 'sessionrestore', 16:29:49 INFO - 'sessionrestore_no_auto_restore']}, 16:29:49 INFO - 'svgr': {'tests': ['tsvgx', 16:29:49 INFO - 'tsvgr_opacity', 16:29:49 INFO - 'tart', 16:29:49 INFO - 'tscrollx', 16:29:49 INFO - 'cart']}, 16:29:49 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:29:49 INFO - 'tests': ['tsvgx', 16:29:49 INFO - 'tsvgr_opacity', 16:29:49 INFO - 'tart', 16:29:49 INFO - 'tscrollx', 16:29:49 INFO - 'cart']}, 16:29:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:49 INFO - 'tests': ['tp5o']}, 16:29:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:49 INFO - 'talos_options': ['--e10s'], 16:29:49 INFO - 'tests': ['tp5o']}, 16:29:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:49 INFO - 'talos_options': ['--xperf_path', 16:29:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:29:49 INFO - 'tests': ['tp5n']}, 16:29:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:49 INFO - 'talos_options': ['--e10s', 16:29:49 INFO - '--xperf_path', 16:29:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:29:49 INFO - 'tests': ['tp5n']}}, 16:29:49 INFO - 'talos.zip': {'path': '', 16:29:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:29:49 INFO - Running post-action listener: _resource_record_post_action 16:29:49 INFO - ##### 16:29:49 INFO - ##### Running create-virtualenv step. 16:29:49 INFO - ##### 16:29:49 INFO - Running pre-action listener: _resource_record_pre_action 16:29:49 INFO - Running main action method: create_virtualenv 16:29:49 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 16:29:49 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 16:29:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 16:29:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:29:49 INFO - Using real prefix '/usr' 16:29:49 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 16:29:50 INFO - Installing distribute.............................................................................................................................................................................................done. 16:29:52 INFO - Installing pip.................done. 16:29:52 INFO - Return code: 0 16:29:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 16:29:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc82055f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x189b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1968600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f7350>, '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 0x19f8250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f86e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', '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 16:29:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 16:29:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:29:52 INFO - Using env: {'DISPLAY': ':0', 16:29:52 INFO - 'HOME': '/home/cltbld', 16:29:52 INFO - 'LANG': 'en_US.UTF-8', 16:29:52 INFO - 'LANGUAGE': 'en_US:en', 16:29:52 INFO - 'LOGNAME': 'cltbld', 16:29:52 INFO - 'MAIL': '/var/mail/cltbld', 16:29:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:52 INFO - 'MOZ_NO_REMOTE': '1', 16:29:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:52 INFO - 'NO_EM_RESTART': '1', 16:29:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:29:52 INFO - 'PWD': '/builds/slave/test-pgo', 16:29:52 INFO - 'SHELL': '/bin/bash', 16:29:52 INFO - 'SHLVL': '1', 16:29:52 INFO - 'TERM': 'linux', 16:29:52 INFO - 'TMOUT': '86400', 16:29:52 INFO - 'USER': 'cltbld', 16:29:52 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', 16:29:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:52 INFO - '_': '/tools/buildbot/bin/python'} 16:29:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:52 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 16:29:52 INFO - Cleaning up... 16:29:52 INFO - Return code: 0 16:29:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 16:29:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fcc82055f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x189b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1968600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f7350>, '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 0x19f8250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f86e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', '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 16:29:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 16:29:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 16:29:52 INFO - Using env: {'DISPLAY': ':0', 16:29:52 INFO - 'HOME': '/home/cltbld', 16:29:52 INFO - 'LANG': 'en_US.UTF-8', 16:29:52 INFO - 'LANGUAGE': 'en_US:en', 16:29:52 INFO - 'LOGNAME': 'cltbld', 16:29:52 INFO - 'MAIL': '/var/mail/cltbld', 16:29:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:52 INFO - 'MOZ_NO_REMOTE': '1', 16:29:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:52 INFO - 'NO_EM_RESTART': '1', 16:29:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:29:52 INFO - 'PWD': '/builds/slave/test-pgo', 16:29:52 INFO - 'SHELL': '/bin/bash', 16:29:52 INFO - 'SHLVL': '1', 16:29:52 INFO - 'TERM': 'linux', 16:29:52 INFO - 'TMOUT': '86400', 16:29:52 INFO - 'USER': 'cltbld', 16:29:52 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', 16:29:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:52 INFO - '_': '/tools/buildbot/bin/python'} 16:29:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:52 INFO - Downloading/unpacking psutil>=0.7.1 16:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:54 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 16:29:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 16:29:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 16:29:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:54 INFO - Installing collected packages: psutil 16:29:54 INFO - Running setup.py install for psutil 16:29:54 INFO - building 'psutil._psutil_linux' extension 16:29:54 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 16:29:54 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 16:29:54 INFO - building 'psutil._psutil_posix' extension 16:29:54 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 16:29:54 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 16:29:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:55 INFO - Successfully installed psutil 16:29:55 INFO - Cleaning up... 16:29:55 INFO - Return code: 0 16:29:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 16:29: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')]} 16:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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')]} 16:29:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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', '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 0x7fcc82055f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x189b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1968600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f7350>, '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 0x19f8250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f86e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', '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 16:29: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 16:29: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 mozsystemmonitor==0.0.0 16:29:55 INFO - Using env: {'DISPLAY': ':0', 16:29:55 INFO - 'HOME': '/home/cltbld', 16:29:55 INFO - 'LANG': 'en_US.UTF-8', 16:29:55 INFO - 'LANGUAGE': 'en_US:en', 16:29:55 INFO - 'LOGNAME': 'cltbld', 16:29:55 INFO - 'MAIL': '/var/mail/cltbld', 16:29:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:55 INFO - 'MOZ_NO_REMOTE': '1', 16:29:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:55 INFO - 'NO_EM_RESTART': '1', 16:29:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:29:55 INFO - 'PWD': '/builds/slave/test-pgo', 16:29:55 INFO - 'SHELL': '/bin/bash', 16:29:55 INFO - 'SHLVL': '1', 16:29:55 INFO - 'TERM': 'linux', 16:29:55 INFO - 'TMOUT': '86400', 16:29:55 INFO - 'USER': 'cltbld', 16:29:55 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', 16:29:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:55 INFO - '_': '/tools/buildbot/bin/python'} 16:29:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:29: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 16:29: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 16:29: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 16:29: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 16:29: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 16:29: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 16:29:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:29:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 16:29:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:29:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:29:57 INFO - Installing collected packages: mozsystemmonitor 16:29:57 INFO - Running setup.py install for mozsystemmonitor 16:29:57 INFO - Successfully installed mozsystemmonitor 16:29:57 INFO - Cleaning up... 16:29:57 INFO - Return code: 0 16:29:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 16:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:58 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fcc82055f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x189b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1968600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f7350>, '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 0x19f8250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f86e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', '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 16:29:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 16:29:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:29:58 INFO - Using env: {'DISPLAY': ':0', 16:29:58 INFO - 'HOME': '/home/cltbld', 16:29:58 INFO - 'LANG': 'en_US.UTF-8', 16:29:58 INFO - 'LANGUAGE': 'en_US:en', 16:29:58 INFO - 'LOGNAME': 'cltbld', 16:29:58 INFO - 'MAIL': '/var/mail/cltbld', 16:29:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:58 INFO - 'MOZ_NO_REMOTE': '1', 16:29:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:58 INFO - 'NO_EM_RESTART': '1', 16:29:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:29:58 INFO - 'PWD': '/builds/slave/test-pgo', 16:29:58 INFO - 'SHELL': '/bin/bash', 16:29:58 INFO - 'SHLVL': '1', 16:29:58 INFO - 'TERM': 'linux', 16:29:58 INFO - 'TMOUT': '86400', 16:29:58 INFO - 'USER': 'cltbld', 16:29:58 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', 16:29:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:58 INFO - '_': '/tools/buildbot/bin/python'} 16:29:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:59 INFO - Downloading/unpacking blobuploader==1.2.4 16:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:00 INFO - Downloading blobuploader-1.2.4.tar.gz 16:30:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 16:30:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:30:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 16:30:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 16:30:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:01 INFO - Downloading docopt-0.6.1.tar.gz 16:30:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 16:30:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 16:30:01 INFO - Installing collected packages: blobuploader, requests, docopt 16:30:01 INFO - Running setup.py install for blobuploader 16:30:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:30:01 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 16:30:01 INFO - Running setup.py install for requests 16:30:02 INFO - Running setup.py install for docopt 16:30:02 INFO - Successfully installed blobuploader requests docopt 16:30:02 INFO - Cleaning up... 16:30:02 INFO - Return code: 0 16:30:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc82055f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x189b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1968600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f7350>, '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 0x19f8250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f86e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', '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 16:30:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 16:30:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:30:02 INFO - Using env: {'DISPLAY': ':0', 16:30:02 INFO - 'HOME': '/home/cltbld', 16:30:02 INFO - 'LANG': 'en_US.UTF-8', 16:30:02 INFO - 'LANGUAGE': 'en_US:en', 16:30:02 INFO - 'LOGNAME': 'cltbld', 16:30:02 INFO - 'MAIL': '/var/mail/cltbld', 16:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:02 INFO - 'MOZ_NO_REMOTE': '1', 16:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:02 INFO - 'NO_EM_RESTART': '1', 16:30:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:30:02 INFO - 'PWD': '/builds/slave/test-pgo', 16:30:02 INFO - 'SHELL': '/bin/bash', 16:30:02 INFO - 'SHLVL': '1', 16:30:02 INFO - 'TERM': 'linux', 16:30:02 INFO - 'TMOUT': '86400', 16:30:02 INFO - 'USER': 'cltbld', 16:30:02 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', 16:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:02 INFO - '_': '/tools/buildbot/bin/python'} 16:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:30:02 INFO - Running setup.py (path:/tmp/pip-ti76Q7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:30:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:30:02 INFO - Running setup.py (path:/tmp/pip-tvIRr1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:30:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:30:02 INFO - Running setup.py (path:/tmp/pip-fqS6fU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:30:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:30:02 INFO - Running setup.py (path:/tmp/pip-sDzf4C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:30:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:30:02 INFO - Running setup.py (path:/tmp/pip-wm7ER5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:30:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:30:02 INFO - Running setup.py (path:/tmp/pip-9vEhzZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:30:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:30:02 INFO - Running setup.py (path:/tmp/pip-0Mkw3I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:30:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:30:02 INFO - Running setup.py (path:/tmp/pip-3BaBP8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:30:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:30:02 INFO - Running setup.py (path:/tmp/pip-Ypz6xd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:30:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:30:03 INFO - Running setup.py (path:/tmp/pip-GyxRgL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:30:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:30:03 INFO - Running setup.py (path:/tmp/pip-Vyk5_q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:30:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:30:03 INFO - Running setup.py (path:/tmp/pip-tYacuU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:30:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:30:03 INFO - Running setup.py (path:/tmp/pip-LEjss_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:30:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:30:03 INFO - Running setup.py (path:/tmp/pip-urSs9W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:30:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:30:03 INFO - Running setup.py (path:/tmp/pip-AdzLjv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:30:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:30:03 INFO - Running setup.py (path:/tmp/pip-7f9AlN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:30:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:30:03 INFO - Running setup.py (path:/tmp/pip-kWL7E5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:30:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:30:03 INFO - Running setup.py install for manifestparser 16:30:03 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 16:30:03 INFO - Running setup.py install for mozcrash 16:30:03 INFO - Running setup.py install for mozdebug 16:30:03 INFO - Running setup.py install for mozdevice 16:30:04 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 16:30:04 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 16:30:04 INFO - Running setup.py install for mozfile 16:30:04 INFO - Running setup.py install for mozhttpd 16:30:04 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 16:30:04 INFO - Running setup.py install for mozinfo 16:30:04 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 16:30:04 INFO - Running setup.py install for mozInstall 16:30:04 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 16:30:04 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 16:30:04 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 16:30:04 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 16:30:04 INFO - Running setup.py install for mozleak 16:30:04 INFO - Running setup.py install for mozlog 16:30:04 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 16:30:04 INFO - Running setup.py install for moznetwork 16:30:04 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 16:30:04 INFO - Running setup.py install for mozprocess 16:30:04 INFO - Running setup.py install for mozprofile 16:30:04 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 16:30:04 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 16:30:04 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 16:30:04 INFO - Running setup.py install for mozrunner 16:30:05 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 16:30:05 INFO - Running setup.py install for mozscreenshot 16:30:05 INFO - Running setup.py install for moztest 16:30:05 INFO - Running setup.py install for mozversion 16:30:05 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 16:30:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:30:05 INFO - Cleaning up... 16:30:05 INFO - Return code: 0 16:30:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc82055f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x189b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1968600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f7350>, '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 0x19f8250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f86e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', '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 16:30:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 16:30:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:30:05 INFO - Using env: {'DISPLAY': ':0', 16:30:05 INFO - 'HOME': '/home/cltbld', 16:30:05 INFO - 'LANG': 'en_US.UTF-8', 16:30:05 INFO - 'LANGUAGE': 'en_US:en', 16:30:05 INFO - 'LOGNAME': 'cltbld', 16:30:05 INFO - 'MAIL': '/var/mail/cltbld', 16:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:05 INFO - 'MOZ_NO_REMOTE': '1', 16:30:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:05 INFO - 'NO_EM_RESTART': '1', 16:30:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:30:05 INFO - 'PWD': '/builds/slave/test-pgo', 16:30:05 INFO - 'SHELL': '/bin/bash', 16:30:05 INFO - 'SHLVL': '1', 16:30:05 INFO - 'TERM': 'linux', 16:30:05 INFO - 'TMOUT': '86400', 16:30:05 INFO - 'USER': 'cltbld', 16:30:05 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', 16:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:05 INFO - '_': '/tools/buildbot/bin/python'} 16:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:30:05 INFO - Running setup.py (path:/tmp/pip-nO7G1R-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:30:05 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)) 16:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:30:05 INFO - Running setup.py (path:/tmp/pip-3uVAxj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:30:05 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)) 16:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:30:05 INFO - Running setup.py (path:/tmp/pip-BZ4lCw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:30:05 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)) 16:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:30:05 INFO - Running setup.py (path:/tmp/pip-ckMSRH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:30:05 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)) 16:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:30:05 INFO - Running setup.py (path:/tmp/pip-vL0Bgj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:30:05 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)) 16:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:30:05 INFO - Running setup.py (path:/tmp/pip-MxdrGE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 16:30:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:30:06 INFO - Running setup.py (path:/tmp/pip-BbEfYW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 16:30:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:30:06 INFO - Running setup.py (path:/tmp/pip-iVUOew-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 16:30:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:30:06 INFO - Running setup.py (path:/tmp/pip-BTMd3b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 16:30:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:30:06 INFO - Running setup.py (path:/tmp/pip-qEAuLn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 16:30:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:30:06 INFO - Running setup.py (path:/tmp/pip-B4Lz0o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 16:30:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:30:06 INFO - Running setup.py (path:/tmp/pip-rXOPw_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 16:30:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:30:06 INFO - Running setup.py (path:/tmp/pip-JiOVPd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 16:30:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:30:06 INFO - Running setup.py (path:/tmp/pip-n7Bzqi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:30:06 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)) 16:30:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:30:06 INFO - Running setup.py (path:/tmp/pip-DEEjer-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:30:06 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)) 16:30:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:30:06 INFO - Running setup.py (path:/tmp/pip-oBBE1O-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:30:06 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)) 16:30:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:30:06 INFO - Running setup.py (path:/tmp/pip-vqaZWu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:30:06 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)) 16:30:06 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)) 16:30:06 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)) 16:30:06 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)) 16:30:06 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)) 16:30:06 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)) 16:30:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 16:30:06 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 16:30:06 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 16:30:06 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 16:30:06 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 16:30:06 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 16:30:06 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 16:30:08 INFO - Downloading blessings-1.5.1.tar.gz 16:30:08 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 16:30:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 16:30:08 INFO - Installing collected packages: blessings 16:30:08 INFO - Running setup.py install for blessings 16:30:08 INFO - Successfully installed blessings 16:30:08 INFO - Cleaning up... 16:30:08 INFO - Return code: 0 16:30:08 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 16:30:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:30:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:30:09 INFO - Reading from file tmpfile_stdout 16:30:09 INFO - Current package versions: 16:30:09 INFO - argparse == 1.2.1 16:30:09 INFO - blessings == 1.5.1 16:30:09 INFO - blobuploader == 1.2.4 16:30:09 INFO - docopt == 0.6.1 16:30:09 INFO - manifestparser == 1.1 16:30:09 INFO - mozInstall == 1.12 16:30:09 INFO - mozcrash == 0.16 16:30:09 INFO - mozdebug == 0.1 16:30:09 INFO - mozdevice == 0.46 16:30:09 INFO - mozfile == 1.2 16:30:09 INFO - mozhttpd == 0.7 16:30:09 INFO - mozinfo == 0.8 16:30:09 INFO - mozleak == 0.1 16:30:09 INFO - mozlog == 3.0 16:30:09 INFO - moznetwork == 0.27 16:30:09 INFO - mozprocess == 0.22 16:30:09 INFO - mozprofile == 0.27 16:30:09 INFO - mozrunner == 6.11 16:30:09 INFO - mozscreenshot == 0.1 16:30:09 INFO - mozsystemmonitor == 0.0 16:30:09 INFO - moztest == 0.7 16:30:09 INFO - mozversion == 1.4 16:30:09 INFO - psutil == 3.1.1 16:30:09 INFO - requests == 1.2.3 16:30:09 INFO - wsgiref == 0.1.2 16:30:09 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:30:09 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')]} 16:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:09 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')]} 16:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:09 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 0x7fcc82055f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x189b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1968600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f7350>, '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 0x19f8250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f86e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', '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 16:30:09 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 16:30:09 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 16:30:09 INFO - Using env: {'DISPLAY': ':0', 16:30:09 INFO - 'HOME': '/home/cltbld', 16:30:09 INFO - 'LANG': 'en_US.UTF-8', 16:30:09 INFO - 'LANGUAGE': 'en_US:en', 16:30:09 INFO - 'LOGNAME': 'cltbld', 16:30:09 INFO - 'MAIL': '/var/mail/cltbld', 16:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:09 INFO - 'MOZ_NO_REMOTE': '1', 16:30:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:09 INFO - 'NO_EM_RESTART': '1', 16:30:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:30:09 INFO - 'PWD': '/builds/slave/test-pgo', 16:30:09 INFO - 'SHELL': '/bin/bash', 16:30:09 INFO - 'SHLVL': '1', 16:30:09 INFO - 'TERM': 'linux', 16:30:09 INFO - 'TMOUT': '86400', 16:30:09 INFO - 'USER': 'cltbld', 16:30:09 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', 16:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:09 INFO - '_': '/tools/buildbot/bin/python'} 16:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:09 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)) 16:30:09 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)) 16:30:09 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)) 16:30:09 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)) 16:30:09 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)) 16:30:09 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)) 16:30:09 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)) 16:30:09 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)) 16:30:09 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)) 16:30:09 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)) 16:30:09 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)) 16:30:09 INFO - Cleaning up... 16:30:09 INFO - Return code: 0 16:30:09 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 16:30:09 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')]} 16:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:09 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')]} 16:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:09 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 0x7fcc82055f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x189b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1968600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f7350>, '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 0x19f8250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f86e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', '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 16:30:09 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 16:30:09 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 16:30:09 INFO - Using env: {'DISPLAY': ':0', 16:30:09 INFO - 'HOME': '/home/cltbld', 16:30:09 INFO - 'LANG': 'en_US.UTF-8', 16:30:09 INFO - 'LANGUAGE': 'en_US:en', 16:30:09 INFO - 'LOGNAME': 'cltbld', 16:30:09 INFO - 'MAIL': '/var/mail/cltbld', 16:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:09 INFO - 'MOZ_NO_REMOTE': '1', 16:30:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:09 INFO - 'NO_EM_RESTART': '1', 16:30:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:30:09 INFO - 'PWD': '/builds/slave/test-pgo', 16:30:09 INFO - 'SHELL': '/bin/bash', 16:30:09 INFO - 'SHLVL': '1', 16:30:09 INFO - 'TERM': 'linux', 16:30:09 INFO - 'TMOUT': '86400', 16:30:09 INFO - 'USER': 'cltbld', 16:30:09 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', 16:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:09 INFO - '_': '/tools/buildbot/bin/python'} 16:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:09 INFO - Downloading/unpacking jsonschema 16:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:30:11 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 16:30:11 INFO - Downloading/unpacking functools32 (from jsonschema) 16:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:11 INFO - Downloading functools32-3.2.3-2.tar.gz 16:30:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 16:30:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 16:30:11 INFO - warning: no files found matching '*.txt' 16:30:11 INFO - no previously-included directories found matching 'build' 16:30:11 INFO - no previously-included directories found matching 'dist' 16:30:11 INFO - no previously-included directories found matching '.git*' 16:30:11 INFO - Installing collected packages: jsonschema, functools32 16:30:11 INFO - Running setup.py install for functools32 16:30:11 INFO - warning: no files found matching '*.txt' 16:30:11 INFO - no previously-included directories found matching 'build' 16:30:11 INFO - no previously-included directories found matching 'dist' 16:30:11 INFO - no previously-included directories found matching '.git*' 16:30:11 INFO - Successfully installed jsonschema functools32 16:30:11 INFO - Cleaning up... 16:30:11 INFO - Return code: 0 16:30:11 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 16:30:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:11 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc82055f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x189b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1968600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f7350>, '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 0x19f8250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f86e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', '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 16:30:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 16:30:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:30:11 INFO - Using env: {'DISPLAY': ':0', 16:30:11 INFO - 'HOME': '/home/cltbld', 16:30:11 INFO - 'LANG': 'en_US.UTF-8', 16:30:11 INFO - 'LANGUAGE': 'en_US:en', 16:30:11 INFO - 'LOGNAME': 'cltbld', 16:30:11 INFO - 'MAIL': '/var/mail/cltbld', 16:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:11 INFO - 'MOZ_NO_REMOTE': '1', 16:30:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:11 INFO - 'NO_EM_RESTART': '1', 16:30:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:30:11 INFO - 'PWD': '/builds/slave/test-pgo', 16:30:11 INFO - 'SHELL': '/bin/bash', 16:30:11 INFO - 'SHLVL': '1', 16:30:11 INFO - 'TERM': 'linux', 16:30:11 INFO - 'TMOUT': '86400', 16:30:11 INFO - 'USER': 'cltbld', 16:30:11 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', 16:30:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:11 INFO - '_': '/tools/buildbot/bin/python'} 16:30:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:11 INFO - Downloading/unpacking flake8 16:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:30:13 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 16:30:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:30:13 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 16:30:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:13 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 16:30:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 16:30:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 16:30:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:30:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:30:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:30:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:30:14 INFO - no previously-included directories found matching 'docs/_build' 16:30:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:30:14 INFO - Running setup.py install for pep8 16:30:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:30:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:30:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:30:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:30:14 INFO - no previously-included directories found matching 'docs/_build' 16:30:14 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 16:30:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:30:14 INFO - Cleaning up... 16:30:14 INFO - Return code: 0 16:30:14 INFO - Running post-action listener: _resource_record_post_action 16:30:14 INFO - Running post-action listener: _start_resource_monitoring 16:30:14 INFO - Starting resource monitoring. 16:30:14 INFO - ##### 16:30:14 INFO - ##### Running install step. 16:30:14 INFO - ##### 16:30:14 INFO - Running pre-action listener: _resource_record_pre_action 16:30:14 INFO - Running main action method: install 16:30:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:30:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:30:14 INFO - Reading from file tmpfile_stdout 16:30:14 INFO - Detecting whether we're running mozinstall >=1.0... 16:30:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 16:30:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 16:30:14 INFO - Reading from file tmpfile_stdout 16:30:14 INFO - Output received: 16:30:14 INFO - Usage: mozinstall [options] installer 16:30:14 INFO - Options: 16:30:14 INFO - -h, --help show this help message and exit 16:30:14 INFO - -d DEST, --destination=DEST 16:30:14 INFO - Directory to install application into. [default: 16:30:14 INFO - "/builds/slave/test-pgo"] 16:30:14 INFO - --app=APP Application being installed. [default: firefox] 16:30:14 INFO - mkdir: /builds/slave/test-pgo/build/application 16:30:14 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'] 16:30:14 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 16:30:28 INFO - Reading from file tmpfile_stdout 16:30:28 INFO - Output received: 16:30:28 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 16:30:28 INFO - Running post-action listener: _resource_record_post_action 16:30:28 INFO - ##### 16:30:28 INFO - ##### Running run-tests step. 16:30:28 INFO - ##### 16:30:28 INFO - Running pre-action listener: _resource_record_pre_action 16:30:28 INFO - Running main action method: run_tests 16:30:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 16:30:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 16:30:28 INFO - Python 2.7.3 16:30:28 INFO - Return code: 0 16:30:28 INFO - grabbing minidump binary from tooltool 16:30:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:28 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 0x19f7350>, '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 0x19f8250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f86e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 16:30:28 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 16:30:28 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 16:30:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:30:28 INFO - Return code: 0 16:30:28 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 16:30:28 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 16:30:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 16:30:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 16:30:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 16:30:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 16:30:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 16:30:29 INFO - Return code: 0 16:30:29 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/1452722854/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 16:30:29 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/1452722854/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --webServer localhost 16:30:29 INFO - Using env: {'DISPLAY': ':0', 16:30:29 INFO - 'HOME': '/home/cltbld', 16:30:29 INFO - 'LANG': 'en_US.UTF-8', 16:30:29 INFO - 'LANGUAGE': 'en_US:en', 16:30:29 INFO - 'LOGNAME': 'cltbld', 16:30:29 INFO - 'MAIL': '/var/mail/cltbld', 16:30:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:30:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 16:30:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:29 INFO - 'MOZ_NO_REMOTE': '1', 16:30:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:30:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:29 INFO - 'NO_EM_RESTART': '1', 16:30:29 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:30:29 INFO - 'PWD': '/builds/slave/test-pgo', 16:30:29 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 16:30:29 INFO - 'SHELL': '/bin/bash', 16:30:29 INFO - 'SHLVL': '1', 16:30:29 INFO - 'TERM': 'linux', 16:30:29 INFO - 'TMOUT': '86400', 16:30:29 INFO - 'USER': 'cltbld', 16:30:29 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827', 16:30:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:29 INFO - '_': '/tools/buildbot/bin/python'} 16:30:29 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/1452722854/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost'] with output_timeout 3600 16:30:30 INFO - mozversion INFO | application_buildid: 20160113140734 16:30:30 INFO - mozversion INFO | application_changeset: 0fb284f396774d8553e02bc064822b3459361c20 16:30:30 INFO - mozversion INFO | application_display_name: Nightly 16:30:30 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:30:30 INFO - mozversion INFO | application_name: Firefox 16:30:30 INFO - mozversion INFO | application_remotingname: firefox 16:30:30 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:30:30 INFO - mozversion INFO | application_vendor: Mozilla 16:30:30 INFO - mozversion INFO | application_version: 44.0 16:30:30 INFO - mozversion INFO | platform_buildid: 20160113140734 16:30:30 INFO - mozversion INFO | platform_changeset: 0fb284f396774d8553e02bc064822b3459361c20 16:30:30 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:30:30 INFO - mozversion INFO | platform_version: 44.0 16:30:30 INFO - 2016-01-13 16:30:30,121 DEBUG : using testdate: 1452731430 16:30:30 INFO - 2016-01-13 16:30:30,121 DEBUG : actual date: 1452731430 16:30:30 INFO - 2016-01-13 16:30:30,215 INFO : starting webserver on 'localhost:49324' 16:30:30 INFO - 2016-01-13 16:30:30,216 INFO : Starting test suite talos-linux64-ix-054 16:30:30 INFO - 2016-01-13 16:30:30,216 INFO : Starting test dromaeo_css 16:30:30 INFO - 2016-01-13 16:30:30,217 DEBUG : operating with platform_type : linux_ 16:30:30 INFO - 2016-01-13 16:30:30,217 INFO : Initialising browser for dromaeo_css test... 16:30:30 INFO - 2016-01-13 16:30:30,232 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpU8Jf13/profile http://localhost:49324/getInfo.html 16:30:34 INFO - 2016-01-13 16:30:34,250 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:30:34 INFO - 2016-01-13 16:30:34,250 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:30:34 INFO - 2016-01-13 16:30:34,250 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:30:34 INFO - 2016-01-13 16:30:34,250 DEBUG : BROWSER_OUTPUT: __metrics 16:30:34 INFO - 2016-01-13 16:30:34,412 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] 16:30:35 INFO - 2016-01-13 16:30:35,631 INFO : Browser initialized. 16:30:35 INFO - 2016-01-13 16:30:35,632 INFO : Running cycle 1/1 for dromaeo_css test... 16:30:35 INFO - 2016-01-13 16:30:35,632 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpU8Jf13/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:30:47 INFO - 2016-01-13 16:30:47,080 DEBUG : BROWSER_OUTPUT: RSS: Main: 128860160 16:30:47 INFO - 2016-01-13 16:30:47,081 DEBUG : BROWSER_OUTPUT: 16:31:38 INFO - 2016-01-13 16:31:38,684 DEBUG : BROWSER_OUTPUT: 1452731498681 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] 16:33:45 INFO - 2016-01-13 16:33:45,675 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49324/tests/dromaeo/cssquery-ext.html) 16:33:46 INFO - 2016-01-13 16:33:46,720 DEBUG : BROWSER_OUTPUT: RSS: Main: 235442176 16:33:46 INFO - 2016-01-13 16:33:46,720 DEBUG : BROWSER_OUTPUT: 16:36:48 INFO - 2016-01-13 16:36:48,797 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/dromaeo/cssquery-ext.html (next: http://localhost:49324/tests/dromaeo/cssquery-jquery.html) 16:36:49 INFO - 2016-01-13 16:36:49,358 DEBUG : BROWSER_OUTPUT: RSS: Main: 211734528 16:36:49 INFO - 2016-01-13 16:36:49,359 DEBUG : BROWSER_OUTPUT: 16:39:51 INFO - 2016-01-13 16:39:51,034 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49324/tests/dromaeo/cssquery-mootools.html) 16:39:51 INFO - 2016-01-13 16:39:51,595 DEBUG : BROWSER_OUTPUT: RSS: Main: 210923520 16:39:51 INFO - 2016-01-13 16:39:51,595 DEBUG : BROWSER_OUTPUT: 16:42:53 INFO - 2016-01-13 16:42:53,255 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49324/tests/dromaeo/cssquery-prototype.html) 16:42:53 INFO - 2016-01-13 16:42:53,810 DEBUG : BROWSER_OUTPUT: RSS: Main: 191340544 16:42:53 INFO - 2016-01-13 16:42:53,810 DEBUG : BROWSER_OUTPUT: 16:45:56 INFO - 2016-01-13 16:45:56,183 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49324/tests/dromaeo/cssquery-yui.html) 16:45:56 INFO - 2016-01-13 16:45:56,745 DEBUG : BROWSER_OUTPUT: RSS: Main: 192610304 16:45:56 INFO - 2016-01-13 16:45:56,745 DEBUG : BROWSER_OUTPUT: 16:48:58 INFO - 2016-01-13 16:48:58,762 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/dromaeo/cssquery-yui.html (next: http://localhost:49324/tests/dromaeo/cssquery-dojo.html) 16:48:59 INFO - 2016-01-13 16:48:59,020 DEBUG : BROWSER_OUTPUT: RSS: Main: 236285952 16:48:59 INFO - 2016-01-13 16:48:59,021 DEBUG : BROWSER_OUTPUT: 16:48:59 INFO - 2016-01-13 16:48:59,021 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:48:59 INFO - 2016-01-13 16:48:59,021 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:48:59 INFO - 2016-01-13 16:48:59,021 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:48:59 INFO - 2016-01-13 16:48:59,021 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:48:59 INFO - 2016-01-13 16:48:59,021 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3198.648148703643;3261.7390608751302;3262.4290749118927;3273.067175854882;3280.261941063261;5417.593680473966;5442.374126975399;5458.7270636468165;5479.369872464667;5483.862903427412;5516.7517461714215;5523.917141242885;5534.142207957763;5558.722063896799;5573.721313934308;4906.1414252505665;5022.17134172861;5081.8475445736685;5123.4108077571;5149.536541711245;4983.202687569989;5042;5066.290719299301;5155.046316431472;5163.096458119833;5068.797248110071;5084.542391184793;5102.1836506159025;5113.079645663797;5125.974805039007;5079.466656001104;5082.415522214956;5091.872703182432;5135.871603209931;5161.61287903405;4803.9039219215765;4861.586765124934;4888.0957022950515;4915.90168196634;4940.925886111711;5340.332458442695;5355.518003379714;5356.464353564651;5375.543078838265;5384.865378365533;5636.520895723868;5921.407859214087;5930.644161350333;5951.375105613892;5955.6128851624935;5722.97138514309;5802.622829516068;5802.97098514509;5803.854903627401;5846.590738648296;5502.80740174092;5534.529564986981;5537.861553461155;5540.085885828874;5541.473560011793;5864.266966629171;5954.970225148933;6004.82905833367;6009.399060094043;6009.828083523671;5712.80005199816;5782.942170578241;5786.305643322744;5793.8841223175305;5818.040023396177;5972.074328479092;6007.36922623127;6009.308929473079;6010.519158467312;6028.2766068072115;5642.802501912495;5787.160861675117;5792.247007888947;5976.103584462278;6022.1870047543025;5929.703514824241;6028.788992385245;6029.336772954972;6032.155498230251;6037.335893051761;4613.5847773700525;4627.8843028923875;4631.722096674143;4635.258358662598;4649.604783593365;3995.7402768819934;4035.9798201009394;4063.959360406417;4101.671866250693;4135;5629.043062679354;5631.1271752878365;5633.267675202197;5634.830955071355;5654.37801841797;5919;5935.821925342247;5942.673152976541;5942.851428714317;5960.403959604092;5520.144377621395;5569.053260945649;5627.634203776742;5633.042382794934;5667.858303542362;4978.975105124451;5063.139266324733;5101.183810590288;5109.591232701375;5114.820981265712;5904.5571582131515;6196.938897524737;6263.874722505524;6266.185395898595;6273.52948528853;5831.241938548046;5931.406859314034;6070.726817293321;6099.603525770811;6101.145839582373;6125.448709616156;6129.35641757609;6133.7546498141355;6134.570580059353;6139.324674285915;5824.708764561839;5871.706414679335;5880.882382352414;5885.852853678693;5938.703064846827;5857.238558987305;5942.851428714317;5944.108383742473;5957.612755171073;5974.044152935509;5900.144479050427;5905.232319798399;5905.291365036223;5907.232059832194;5909.172715819703;5658.236138121301;5713.8285851424525;5768.653880767168;5890.057590785454;5903.49820265265;5715.114157305624;5783.739731712168;5794.971025144847;5801.274840644919;5807.303123625192;5839.766409343579;5870.5010074144175;5879.529637628894;5883.294004719461;5886.882262354815;5560.749766260447;5616.747246374005;5657.038303488334;5736.598438109292;5764.711764411846 16:48:59 INFO - 2016-01-13 16:48:59,021 DEBUG : BROWSER_OUTPUT: |1;ext.html;597.4025974025974;627.0875875601001;644.7678835619176;658.8385845467858;659.3406593406594;36098.45852312214;45210.54789452104;45906;45932.54067459324;45955.5404445955;48937;49548.25677614839;49565;49593.75203123979;49601.751991239995;8346.374021948348;8435.536045517494;9139.543022848864;9349.626014959393;10608.78782424351;10706.785864282749;10799.730006749855;10817.134629229642;10820.350778953289;10820.567177312898;10914.23600347976;11002.614908478205;11022;11030.779384412306;11032.779344413108;10804.405757683324;10901.400422976732;10956;10962.616308429167;10973.945130274338;10760.3543787373;10908.836367454494;10924.126069914388;10926.180536459797;10927.344359338465;44652;45275.773621132015;45307.320390194174;45428.3185752214;45555.544444555795;3788.7158463115375;4418.911621767546;4441.866743997685;4447.933281000787;4449.443819522559;33357.3328533428;33694.49458258128;33913.152171195914;35270.64729352699;35833;3768.9623103768886;3796.772193668389;3801.391777315644;3837.347650899326;3948.4077388391684;335306;336942;337665;448146;456761;77415;79195.60402197952;79553.20446795574;79611.20388796038;79711;72470.27529724635;72915.63542182255;72980;72996;73086.269137309;18952.14715337813;19167.616647666968;19226.71159932602;19265.036748162816;19271.421857344303;14576.63558411027;14577.125372477685;14637.853621463863;14655.706885862222;14678.926605366905;22894.42763930882;23072.53854922926;23143.652845207336;23193.188238411574;23193.304200874;8788.384813063025;9037.502937338379;9049.366544341965;9062.81874362509;9156.679516217033;931.7391130483476;951.7715748220377;997.5012493753123;1000.8949060348705;1032.7418145463635;28623.856880715462;29033.419331613673;29486.557701634494;29497.410051798845;30980.53529197064;1105.5633024955098;1116.2242241641982;1135;1136.5226604825993;1158.7334912970064;14489.347979340955;15631.921840390725;15640;16035.43875964359;16555.91722041382;376747;379626;381974;382061;383686;60154.69922650446;60602;60695.75129740876;60738.39261607327;60764;1383.9654008649663;1404.227674778888;1461.5250043735828;1524.3521503361028;1542.6143464133966;33369.33261334808;33603.08516165183;33855;33971;33991;54493.45506544884;54933;55113;55136.172957406474;55140;320111;322620;322986;323633;324330;36725.55164313369;37399.813000934824;37442.063948401505;37634.43548346722;37757.433638496;50035;50672.49327506678;51103;51302.74348628233;51471.48528514667;41815.7909210452;42050;42399.57600423956;43137.56862431335;43637.12725745531;41570.79214603908;41654.37518437287;42217.577824221364;42794.78602606967;42806.35790463208;39888.20503077427;41236.38145427761;41912.79043604885;41981.580184197766;42006.36990445207;37274.813625931696;37738.245235095696;37929.4310585347;38199;38332;28536.714632853407;28701.425971480036;28817.85591072031;28839;28914.27714307159 16:48:59 INFO - 2016-01-13 16:48:59,022 DEBUG : BROWSER_OUTPUT: |2;jquery.html;388.99805500972474;391.97256192066567;397.72755662371276;402.3843519415295;403.2237941961723;13893.374798134082;14130.22283774392;17921.910390448047;19248.90375548124;19369.322073727424;13251.867481325184;13585.728285434285;13628.386722597506;18779.62440751184;19321.516962075955;4846.466888642247;4964.230544265626;4966.950330496703;4966.9751651241695;4969.602431805447;4890.3642526471685;4900.240462728283;4913.091078150535;4913.950860491385;4914.434839993411;3829.7702137871815;4855;4868.05073010763;4868.707877527359;5060.9493905060845;5346.91979620306;5352.170413585901;5388.488093631099;5391.245225668409;5400.162974738902;4674.252119660872;4737.2420412734145;4742.19382704941;4752.833650822205;4796.37647105874;14601.342939567745;15032.624184395369;15889;15932.840671593252;16077.91961040199;2226.821854251672;2229.8550594211324;2262.5248697773486;2333.1134169015836;2371.4782747795457;10609.416482093482;10701.464926753632;10726.249162558624;10848.511816968256;10948.890511094867;1755.920983555743;1769.5664562182349;1814.6733587954163;1848.787389450217;1876.1182244345136;66378;66850.66574667144;67394;68624;68772;8049.114597394282;8079.717209897743;8115.79710507242;8141;8167.550784706779;9823.901760982299;9831.508424578742;9878.950605247072;9882.851757223647;9890.357126786736;6430.7106180222;6494.318096599884;6560.376764207393;6567.376099270562;6570.671466426659;6460.483161347081;6479.935200648028;6487.740490380332;6746.123004009447;6769.458443324522;3708.962910370916;3733.9066523337133;3740.233252183296;3763.0592351912023;3765.1528406108405;1274.1526884621624;1285.3059347952;1286.4018231522386;1329.3021163888843;1342.147736187528;512.64627407089;522.1671434062664;538.020802140109;540.3704684043046;545.1631745271069;12812.359382030862;13282;13459.730805384033;13572.524961626494;13579.321033948461;507.84003039043324;538.0288579114736;544.3060098374604;565.4260925160964;567.3277166557642;7068.646567671596;7101.2898710128575;7424.703011879572;7485.850282994418;7526.870969354641;601834;626262;627551;631054;637764;85359.14640853512;85684;86213.13786862176;86702.56648716716;87502.56248718844;644.0950464597248;648.1412128929094;648.4844548583793;648.967551622417;648.9935100649028;12506.687332816753;12551.273990239215;12618.116731828684;12745.426455809325;12775;38138.80930595329;38399;38435;39466;39757.20485590214;50163.74918125532;50183.74908125582;50737.492625073275;50969;51509.7424512875;7730.574818385043;7749.41879359039;7783.805404864924;7825.4130940178575;7870.409719270963;13314.800277995644;13738.450461981409;13837.169769813843;13874.722505549631;14119.082259653089;11853.76292474163;12150.453229604866;12335.938320308342;12505.624831255076;12544.74910501803;10976.835347469954;11252.324860508395;11512.079033677119;12043.81934270969;12428.875711242772;9797.951010244904;10660.62687805923;11318.26431281964;11759.176857620227;12252.693682658004;7642.312191902533;7642.388608911385;7647.38820894316;7665.3101220890385;7745.148033716399;11424.543018279175;11454.885451145716;11716.765664686489;11805.704857378634;12069.396530173632 16:48:59 INFO - 2016-01-13 16:48:59,022 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1590.6739118480705;1617.1752406272797;1833.1109411935213;1848.5378655336167;1861.692820684587;7178.605176715291;7305.780826575198;7741.845163096735;7915.881261781078;7926.1281259061525;7689.730859419921;7758.612069396521;7842.64708088136;7861.056673199224;7910.920890791091;2385.7852793248603;2415.613501839706;2418.2745176447083;2421.673074134987;2422.0553983946274;2081.9791802082013;2189.4964158243615;2331.5919714049974;2385.0817435287454;2422.624493203556;2320.373499155227;2339.730930942938;2345.835791494596;2348.5655153796515;2351.776581224781;2388.9283321500384;2426.4783071639504;2426.502566973766;2426.551088048717;2427.1383658801046;2357.233898982837;2428.91498797543;2432.440538676097;2432.9513409731708;2434.476587533671;7932.405069619744;8022.318102961255;8046.839063218761;8056.355491560661;8070.878936815952;4917.795140190676;4987.177115775893;4991.6256280778725;4996.300517927493;4998.875028124289;3248.2853772170083;3286.7699261051735;3366.276250606107;3390.8982730518123;3406.931861362759;1082.664374044041;1096.7641956979287;1098.1708809848512;1121.9495122719497;1152.216492784906;10995.615153469591;11019.228653994143;11026.228164028522;11038.392888391136;11042.392668403234;4490.820367185342;4501.3022981437925;4548.340490628906;4556.931646025312;4568.497465278817;9379.765505862408;9404.482753448488;9422.104900034486;9427.104425079606;9427.104425079606;5050.04049230645;5057.949420505822;5058.696478211319;5059.8988020239385;5061;4571.177188106106;4625.32932724753;4633.606143477775;4642.0715856828765;4644.767761611906;3726.5155529781496;3845.538535375773;3867.5939026402384;3918.0988372674447;3986.342253528135;1185.7924863148914;1198.9280643161437;1209.8850609192114;1234.370471059748;1237.0907383073434;1200.5977997370803;1234.0497816681104;1340.9664758381118;1473.0204414064742;1491.209658880795;7841.098273698483;7906.802329941797;7908.090569584457;7912.4856884302335;7914.2481464261955;1208.468273959455;1209.449700386322;1213.696575856036;1226.8711785262597;1233.4881024374963;6020.157177995085;6036.909446358308;6093.421124993119;6106.694665266719;6115.357887421846;9511.762205944906;9799.11807937289;9829.115379615869;9833.754156146153;9842.75393115178;9471.19494842946;9648.469334186688;9653.372530785617;9661.855072173923;9664.275179361575;7375.741849035366;7417.517861338995;7443.776686699516;7443.962780186064;7453.366463850633;7388.593627350548;7444;7460;7488.176300607038;7488.213737557478;8570.14298570163;8667.783305417415;8712.21590056898;8753.737387878375;8766.298696104168;8646.697365592174;8769.824603508021;8780.078091800524;8786.560671966505;8910.153535414125;7087.362137407658;7134.607596582237;7144.535605185646;7148.749793757193;7150.856982860418;7881.763547093597;7932.524048557105;7970.641321140447;7983.960080199562;7990.600469976362;7563.773086807405;7583.521213363341;7642.465027448247;7675.2324767522805;7699.615019249127;7305.342519173088;7351.081114860643;7404.518706284074;7410.184879663125;7458.440616953859;7229.855402892018;7310.561366318038;7557.168711441627;7591.316781489692;7592.544447333178;6800.387965083166;6818.18181818185;6833.282505337067;6840.144981877265;6859.245482996766;6735.326467353226;6945.930540694529;6953;6969.7909062728195;6970.477214208853 16:48:59 INFO - 2016-01-13 16:48:59,022 DEBUG : BROWSER_OUTPUT: |4;prototype.html;372.46923134533284;373.24976796638754;374.56924536782697;376.5425008614533;382.3232877806282;5796.391378905218;5812.883742325151;5874.530037596993;5900.232969713937;5920.259967504062;5676.063449530833;5682.090865461517;5707;5739.799107031255;5744.281964754406;4585.335126406669;4597.747123908183;4599.724016559009;4606.746628935407;4608.792604332813;4476.261416866229;4541.477730061036;4571.1771881061395;4602.884927876797;4628.352030715702;4561.247394179956;4575.702579332333;4577.267637178053;4582.495925448198;4582.7250364978045;4569.817207311704;4571.2457444521615;4573.222552166139;4583.518730533296;4584.7936842842155;4109.0959988802715;4109.4246805447265;4110.753354798691;4111.773852438115;4118.814653340577;5480.91778623321;5580.051391263495;5606.55147588192;5662.546996240291;5664.603477756558;5396.892062158735;5397.514223719884;5398.595105367113;5400.162974738922;5404.270423492818;5970.6716130612795;6220.720067596969;6223.533235007392;6224.3464436234;6225.968870155621;4451.221036318664;4513.413256276696;4564.360989461445;4569.977150114228;4575.794089265991;6288.874222515524;6315.431611154972;6316.842078948017;6321.74713011479;6327.050942358683;4660.39414876064;4664.743439110882;4666.463356714022;4666.743329116931;4668.043051174501;4635.304704294383;4727.810887564459;4735.195016847144;4737.265723812815;4754.191787396151;5345.9732701337025;5347;5347.117725575315;5347.86630334237;5347.946520534822;6206.968965155145;6238.282597501255;6239.126522286883;6245.96877015612;6251.12484252205;1711.3582406597525;1733.0208432235747;1736.2273788164146;1737.122753009722;1738.4350086222028;1189.5658084799159;1191.3149938785234;1195.3306148556835;1203.5967950736601;1229.1396022784088;6033.336333003366;6061.515078793686;6062.120992456069;6067.514598832083;6074;6422.068800024063;6422.357764223634;6422.45409140219;6423.164988551458;6427.807165785034;5931.614445061057;6033;6035.30593981689;6040.456358927718;6042.274927008787;3522.5068490411363;3543.964560354415;3560.2345495718546;3561.786292822439;3563.964360356415;6629.900551491732;6632.336766323329;6649.202095748541;6656.068150458939;6660.134182556237;6520.0871877937125;6563.606183628902;6566.934330656728;6574.80275591733;6575.112359831457;6511.73953041873;6629.5359324846795;6643.534952553275;6654.83362915931;6658.60048397088;6237.875242495215;6329.620222786648;6400.327965563735;6415.1981002374705;6423.357664233539;6238.719257633326;6287.308396076336;6312.147860038836;6332.3034466207755;6345.143405640364;6591.143151390289;6612.702428390638;6615.768448104293;6619.205695316593;6624.867502650017;4542.295944128666;4672.159011377985;4674.672772905864;4678.508756580646;4679.017406344706;6371.331010244043;6376.171097757262;6382.074599183;6384.776532821329;6388;6157.261128664589;6180.81457556274;6218.595791273553;6226.128342032029;6229.657368844757;6164.568480206342;6200.906986395117;6207.193064901534;6207.534434917489;6213.689315534115;6111.266648002268;6152.630842149485;6158.815235542941;6166.537509686702;6166.876662466815;6257.812265632038;6279;6286.654234017173;6291.056341548621;6293.5909165905905;6054.212952316347;6120.418560236771;6130;6136.907946380898;6141.293751218667 16:48:59 INFO - 2016-01-13 16:48:59,023 DEBUG : BROWSER_OUTPUT: |5;yui.html;863.9438436501626;893.6782758207045;932.9486878221695;946.6308139825464;957.3059531839413;14378.209198494078;14826.925865370686;14828.258587070657;14839.480618178366;14859.257037148154;14092.43630254791;14242;14243.28783560823;14247.71504569908;14256.928715356435;762.3520007993217;774.543019618425;914.0265617117765;917.8439665256923;932.3194068330123;923.6813299411726;924.9815003699922;929.3958926697633;929.9860502092474;932.1005229953093;794.1105961323298;976.0532283684814;978.2467500024976;980.5832521178506;981.1218959031662;794.6185830801219;963.051394376542;969.6266937229148;971.2327261463436;972.9805403891918;724.1491247783886;724.3046675191822;725.5030304241611;726.0561270348578;727.3635568877232;14378.137311761328;14746.04150730199;14778.187199704013;14789.482368117171;14805.555833325017;13307.201567905957;13893.513727019606;13926.867727191118;13967.580972570839;14282.143071415749;11210.663680089528;11346.375949322784;11348.205625606211;11534.884651153548;11624.767504649859;3835.8657446989223;3838.1172330363893;3863.0535518798074;3874.2251549689895;3928.666063384616;52846.20730689043;52863.999999999614;54995;55025.44974550243;55069.724651376884;11326.15053870963;11374.715632109099;11441;11473.311601303782;11530.538778448936;21284.36146915595;21318.893405532872;21350.893245533673;21351.893240533696;21378.465538361666;8579.399442039123;8618.698345558001;8664.306855451548;8803.911960880438;8853.247473964657;4654.511276316006;4840.782164802592;4881.267809828483;4883.95116048835;4916.041371932439;3253.365593709204;3282.622498412665;3290.2267967028206;3293.390722716305;3293.8682452701623;514.9897002059938;521.0256819747098;524.3314773663533;531.4632221456357;541.5829811045473;514.3776031002529;642.1042645509488;693.2235895796639;698.833462838587;712.3909057755648;9442.90557094434;9755.902440975642;9872.746184127385;9923.057309555581;9995.100440960348;652.3020368205974;671.701093013614;696.4428457234294;748.8726916424221;784.5645666655062;4935.90128197434;5074.492550744896;5115.514026167508;5184.403793563789;5232.136697444879;71865.28134718585;72000;72238;72668.63665681638;73167.79578396508;61846.36152288596;62256.377436225965;63432.36567634265;64405;65056.02415963764;57314.42685573091;58376.41623583795;58559.414405856245;58917.410825891195;59215;9856.884192772393;9986.650467233612;10031.704253155574;10210.540525676512;10253.744695951096;38081.619183808914;38787.6121238784;38979.610203897595;42453;42603;54044.18933715919;54345.45654543562;54435.45564544304;55210.447895522135;55464;8642.697505587274;8937.73186804397;8978.820423591622;9189.632414703337;9198.596519287907;13938.79091813644;14001.719965600834;14015.159090454605;14126.576202713935;14311.57065288043;12064.698382540506;12691.93654031724;12965.611031669065;13075.280859552817;13192.340382981005;11487.885121148682;12281.078919081283;12610.180338277982;12806.295653739135;13315.46738130464;12193.634190974284;12290.692732681397;12592.370381481072;12925.612231633066;13006.154599950973;10242.334248273839;10408.843867341844;10536.420496872746;11194.104471642411;11249.100071994384;10495.484985529045;10907.890921090688;10949.616763413242;10990.670279891616;10994.945025274823 16:48:59 INFO - 2016-01-13 16:48:59,023 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:48:59 INFO - 2016-01-13 16:48:59,023 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:48:59 INFO - 2016-01-13 16:48:59,023 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,779 16:48:59 INFO - 2016-01-13 16:48:59,023 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:48:59 INFO - 2016-01-13 16:48:59,023 DEBUG : BROWSER_OUTPUT: __startTimestamp1452732539013__endTimestamp 16:48:59 INFO - 2016-01-13 16:48:59,023 DEBUG : BROWSER_OUTPUT: 16:48:59 INFO - 2016-01-13 16:48:59,024 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:48:59 INFO - 2016-01-13 16:48:59,024 DEBUG : BROWSER_OUTPUT: Number of tests: 6 16:48:59 INFO - 2016-01-13 16:48:59,024 DEBUG : BROWSER_OUTPUT: 16:48:59 INFO - 2016-01-13 16:48:59,024 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5507.87 Median:5713.83 stddev:619.06 (10.8%) stddev-sans-first:593.87 16:48:59 INFO - 2016-01-13 16:48:59,024 DEBUG : BROWSER_OUTPUT: Values: 3198.6 3261.7 3262.4 3273.1 3280.3 5417.6 5442.4 5458.7 5479.4 5483.9 5516.8 5523.9 5534.1 5558.7 5573.7 4906.1 5022.2 5081.8 5123.4 5149.5 4983.2 5042.0 5066.3 5155.0 5163.1 5068.8 5084.5 5102.2 5113.1 5126.0 5079.5 5082.4 5091.9 5135.9 5161.6 4803.9 4861.6 4888.1 4915.9 4940.9 5340.3 5355.5 5356.5 5375.5 5384.9 5636.5 5921.4 5930.6 5951.4 5955.6 5723.0 5802.6 5803.0 5803.9 5846.6 5502.8 5534.5 5537.9 5540.1 5541.5 5864.3 5955.0 6004.8 6009.4 6009.8 5712.8 5782.9 5786.3 5793.9 5818.0 5972.1 6007.4 6009.3 6010.5 6028.3 5642.8 5787.2 5792.2 5976.1 6022.2 5929.7 6028.8 6029.3 6032.2 6037.3 4613.6 4627.9 4631.7 4635.3 4649.6 3995.7 4036.0 4064.0 4101.7 4135.0 5629.0 5631.1 5633.3 5634.8 5654.4 5919.0 5935.8 5942.7 5942.9 5960.4 5520.1 5569.1 5627.6 5633.0 5667.9 4979.0 5063.1 5101.2 5109.6 5114.8 5904.6 6196.9 6263.9 6266.2 6273.5 5831.2 5931.4 6070.7 6099.6 6101.1 6125.4 6129.4 6133.8 6134.6 6139.3 5824.7 5871.7 5880.9 5885.9 5938.7 5857.2 5942.9 5944.1 5957.6 5974.0 5900.1 5905.2 5905.3 5907.2 5909.2 5658.2 5713.8 5768.7 5890.1 5903.5 5715.1 5783.7 5795.0 5801.3 5807.3 5839.8 5870.5 5879.5 5883.3 5886.9 5560.7 5616.7 5657.0 5736.6 5764.7 16:48:59 INFO - 2016-01-13 16:48:59,024 DEBUG : BROWSER_OUTPUT: 16:48:59 INFO - 2016-01-13 16:48:59,024 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:56060.55 Median:20773.07 stddev:95635.65 (460.4%) stddev-sans-first:95813.27 16:48:59 INFO - 2016-01-13 16:48:59,025 DEBUG : BROWSER_OUTPUT: Values: 597.4 627.1 644.8 658.8 659.3 36098.5 45210.5 45906.0 45932.5 45955.5 48937.0 49548.3 49565.0 49593.8 49601.8 8346.4 8435.5 9139.5 9349.6 10608.8 10706.8 10799.7 10817.1 10820.4 10820.6 10914.2 11002.6 11022.0 11030.8 11032.8 10804.4 10901.4 10956.0 10962.6 10973.9 10760.4 10908.8 10924.1 10926.2 10927.3 44652.0 45275.8 45307.3 45428.3 45555.5 3788.7 4418.9 4441.9 4447.9 4449.4 33357.3 33694.5 33913.2 35270.6 35833.0 3769.0 3796.8 3801.4 3837.3 3948.4 335306.0 336942.0 337665.0 448146.0 456761.0 77415.0 79195.6 79553.2 79611.2 79711.0 72470.3 72915.6 72980.0 72996.0 73086.3 18952.1 19167.6 19226.7 19265.0 19271.4 14576.6 14577.1 14637.9 14655.7 14678.9 22894.4 23072.5 23143.7 23193.2 23193.3 8788.4 9037.5 9049.4 9062.8 9156.7 931.7 951.8 997.5 1000.9 1032.7 28623.9 29033.4 29486.6 29497.4 30980.5 1105.6 1116.2 1135.0 1136.5 1158.7 14489.3 15631.9 15640.0 16035.4 16555.9 376747.0 379626.0 381974.0 382061.0 383686.0 60154.7 60602.0 60695.8 60738.4 60764.0 1384.0 1404.2 1461.5 1524.4 1542.6 33369.3 33603.1 33855.0 33971.0 33991.0 54493.5 54933.0 55113.0 55136.2 55140.0 320111.0 322620.0 322986.0 323633.0 324330.0 36725.6 37399.8 37442.1 37634.4 37757.4 50035.0 50672.5 51103.0 51302.7 51471.5 41815.8 42050.0 42399.6 43137.6 43637.1 41570.8 41654.4 42217.6 42794.8 42806.4 39888.2 41236.4 41912.8 41981.6 42006.4 37274.8 37738.2 37929.4 38199.0 38332.0 28536.7 28701.4 28817.9 28839.0 28914.3 16:48:59 INFO - 2016-01-13 16:48:59,025 DEBUG : BROWSER_OUTPUT: 16:48:59 INFO - 2016-01-13 16:48:59,025 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30654.58 Median:4850.73 stddev:102409.38 (2111.2%) stddev-sans-first:102671.45 16:48:59 INFO - 2016-01-13 16:48:59,025 DEBUG : BROWSER_OUTPUT: Values: 389.0 392.0 397.7 402.4 403.2 13893.4 14130.2 17921.9 19248.9 19369.3 13251.9 13585.7 13628.4 18779.6 19321.5 4846.5 4964.2 4967.0 4967.0 4969.6 4890.4 4900.2 4913.1 4914.0 4914.4 3829.8 4855.0 4868.1 4868.7 5060.9 5346.9 5352.2 5388.5 5391.2 5400.2 4674.3 4737.2 4742.2 4752.8 4796.4 14601.3 15032.6 15889.0 15932.8 16077.9 2226.8 2229.9 2262.5 2333.1 2371.5 10609.4 10701.5 10726.2 10848.5 10948.9 1755.9 1769.6 1814.7 1848.8 1876.1 66378.0 66850.7 67394.0 68624.0 68772.0 8049.1 8079.7 8115.8 8141.0 8167.6 9823.9 9831.5 9879.0 9882.9 9890.4 6430.7 6494.3 6560.4 6567.4 6570.7 6460.5 6479.9 6487.7 6746.1 6769.5 3709.0 3733.9 3740.2 3763.1 3765.2 1274.2 1285.3 1286.4 1329.3 1342.1 512.6 522.2 538.0 540.4 545.2 12812.4 13282.0 13459.7 13572.5 13579.3 507.8 538.0 544.3 565.4 567.3 7068.6 7101.3 7424.7 7485.9 7526.9 601834.0 626262.0 627551.0 631054.0 637764.0 85359.1 85684.0 86213.1 86702.6 87502.6 644.1 648.1 648.5 649.0 649.0 12506.7 12551.3 12618.1 12745.4 12775.0 38138.8 38399.0 38435.0 39466.0 39757.2 50163.7 50183.7 50737.5 50969.0 51509.7 7730.6 7749.4 7783.8 7825.4 7870.4 13314.8 13738.5 13837.2 13874.7 14119.1 11853.8 12150.5 12335.9 12505.6 12544.7 10976.8 11252.3 11512.1 12043.8 12428.9 9798.0 10660.6 11318.3 11759.2 12252.7 7642.3 7642.4 7647.4 7665.3 7745.1 11424.5 11454.9 11716.8 11805.7 12069.4 16:48:59 INFO - 2016-01-13 16:48:59,025 DEBUG : BROWSER_OUTPUT: 16:48:59 INFO - 2016-01-13 16:48:59,026 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5717.09 Median:6767.86 stddev:2896.21 (42.8%) stddev-sans-first:2887.72 16:48:59 INFO - 2016-01-13 16:48:59,026 DEBUG : BROWSER_OUTPUT: Values: 1590.7 1617.2 1833.1 1848.5 1861.7 7178.6 7305.8 7741.8 7915.9 7926.1 7689.7 7758.6 7842.6 7861.1 7910.9 2385.8 2415.6 2418.3 2421.7 2422.1 2082.0 2189.5 2331.6 2385.1 2422.6 2320.4 2339.7 2345.8 2348.6 2351.8 2388.9 2426.5 2426.5 2426.6 2427.1 2357.2 2428.9 2432.4 2433.0 2434.5 7932.4 8022.3 8046.8 8056.4 8070.9 4917.8 4987.2 4991.6 4996.3 4998.9 3248.3 3286.8 3366.3 3390.9 3406.9 1082.7 1096.8 1098.2 1121.9 1152.2 10995.6 11019.2 11026.2 11038.4 11042.4 4490.8 4501.3 4548.3 4556.9 4568.5 9379.8 9404.5 9422.1 9427.1 9427.1 5050.0 5057.9 5058.7 5059.9 5061.0 4571.2 4625.3 4633.6 4642.1 4644.8 3726.5 3845.5 3867.6 3918.1 3986.3 1185.8 1198.9 1209.9 1234.4 1237.1 1200.6 1234.0 1341.0 1473.0 1491.2 7841.1 7906.8 7908.1 7912.5 7914.2 1208.5 1209.4 1213.7 1226.9 1233.5 6020.2 6036.9 6093.4 6106.7 6115.4 9511.8 9799.1 9829.1 9833.8 9842.8 9471.2 9648.5 9653.4 9661.9 9664.3 7375.7 7417.5 7443.8 7444.0 7453.4 7388.6 7444.0 7460.0 7488.2 7488.2 8570.1 8667.8 8712.2 8753.7 8766.3 8646.7 8769.8 8780.1 8786.6 8910.2 7087.4 7134.6 7144.5 7148.7 7150.9 7881.8 7932.5 7970.6 7984.0 7990.6 7563.8 7583.5 7642.5 7675.2 7699.6 7305.3 7351.1 7404.5 7410.2 7458.4 7229.9 7310.6 7557.2 7591.3 7592.5 6800.4 6818.2 6833.3 6840.1 6859.2 6735.3 6945.9 6953.0 6969.8 6970.5 16:48:59 INFO - 2016-01-13 16:48:59,026 DEBUG : BROWSER_OUTPUT: 16:48:59 INFO - 2016-01-13 16:48:59,027 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5261.89 Median:5951.14 stddev:1511.75 (25.4%) stddev-sans-first:1470.77 16:48:59 INFO - 2016-01-13 16:48:59,027 DEBUG : BROWSER_OUTPUT: Values: 372.5 373.2 374.6 376.5 382.3 5796.4 5812.9 5874.5 5900.2 5920.3 5676.1 5682.1 5707.0 5739.8 5744.3 4585.3 4597.7 4599.7 4606.7 4608.8 4476.3 4541.5 4571.2 4602.9 4628.4 4561.2 4575.7 4577.3 4582.5 4582.7 4569.8 4571.2 4573.2 4583.5 4584.8 4109.1 4109.4 4110.8 4111.8 4118.8 5480.9 5580.1 5606.6 5662.5 5664.6 5396.9 5397.5 5398.6 5400.2 5404.3 5970.7 6220.7 6223.5 6224.3 6226.0 4451.2 4513.4 4564.4 4570.0 4575.8 6288.9 6315.4 6316.8 6321.7 6327.1 4660.4 4664.7 4666.5 4666.7 4668.0 4635.3 4727.8 4735.2 4737.3 4754.2 5346.0 5347.0 5347.1 5347.9 5347.9 6207.0 6238.3 6239.1 6246.0 6251.1 1711.4 1733.0 1736.2 1737.1 1738.4 1189.6 1191.3 1195.3 1203.6 1229.1 6033.3 6061.5 6062.1 6067.5 6074.0 6422.1 6422.4 6422.5 6423.2 6427.8 5931.6 6033.0 6035.3 6040.5 6042.3 3522.5 3544.0 3560.2 3561.8 3564.0 6629.9 6632.3 6649.2 6656.1 6660.1 6520.1 6563.6 6566.9 6574.8 6575.1 6511.7 6629.5 6643.5 6654.8 6658.6 6237.9 6329.6 6400.3 6415.2 6423.4 6238.7 6287.3 6312.1 6332.3 6345.1 6591.1 6612.7 6615.8 6619.2 6624.9 4542.3 4672.2 4674.7 4678.5 4679.0 6371.3 6376.2 6382.1 6384.8 6388.0 6157.3 6180.8 6218.6 6226.1 6229.7 6164.6 6200.9 6207.2 6207.5 6213.7 6111.3 6152.6 6158.8 6166.5 6166.9 6257.8 6279.0 6286.7 6291.1 6293.6 6054.2 6120.4 6130.0 6136.9 6141.3 16:48:59 INFO - 2016-01-13 16:48:59,027 DEBUG : BROWSER_OUTPUT: 16:48:59 INFO - 2016-01-13 16:48:59,027 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:16123.51 Median:2814.95 stddev:19603.77 (696.4%) stddev-sans-first:19625.27 16:48:59 INFO - 2016-01-13 16:48:59,027 DEBUG : BROWSER_OUTPUT: Values: 863.9 893.7 932.9 946.6 957.3 14378.2 14826.9 14828.3 14839.5 14859.3 14092.4 14242.0 14243.3 14247.7 14256.9 762.4 774.5 914.0 917.8 932.3 923.7 925.0 929.4 930.0 932.1 794.1 976.1 978.2 980.6 981.1 794.6 963.1 969.6 971.2 973.0 724.1 724.3 725.5 726.1 727.4 14378.1 14746.0 14778.2 14789.5 14805.6 13307.2 13893.5 13926.9 13967.6 14282.1 11210.7 11346.4 11348.2 11534.9 11624.8 3835.9 3838.1 3863.1 3874.2 3928.7 52846.2 52864.0 54995.0 55025.4 55069.7 11326.2 11374.7 11441.0 11473.3 11530.5 21284.4 21318.9 21350.9 21351.9 21378.5 8579.4 8618.7 8664.3 8803.9 8853.2 4654.5 4840.8 4881.3 4884.0 4916.0 3253.4 3282.6 3290.2 3293.4 3293.9 515.0 521.0 524.3 531.5 541.6 514.4 642.1 693.2 698.8 712.4 9442.9 9755.9 9872.7 9923.1 9995.1 652.3 671.7 696.4 748.9 784.6 4935.9 5074.5 5115.5 5184.4 5232.1 71865.3 72000.0 72238.0 72668.6 73167.8 61846.4 62256.4 63432.4 64405.0 65056.0 57314.4 58376.4 58559.4 58917.4 59215.0 9856.9 9986.7 10031.7 10210.5 10253.7 38081.6 38787.6 38979.6 42453.0 42603.0 54044.2 54345.5 54435.5 55210.4 55464.0 8642.7 8937.7 8978.8 9189.6 9198.6 13938.8 14001.7 14015.2 14126.6 14311.6 12064.7 12691.9 12965.6 13075.3 13192.3 11487.9 12281.1 12610.2 12806.3 13315.5 12193.6 12290.7 12592.4 12925.6 13006.2 10242.3 10408.8 10536.4 11194.1 11249.1 10495.5 10907.9 10949.6 10990.7 10994.9 16:48:59 INFO - 2016-01-13 16:48:59,027 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:48:59 INFO - 2016-01-13 16:48:59,027 DEBUG : BROWSER_OUTPUT: 16:48:59 INFO - 2016-01-13 16:48:59,635 INFO : Browser exited with error code: 0 16:48:59 INFO - 2016-01-13 16:48:59,659 INFO : Completed test dromaeo_css (00:18:29) 16:48:59 INFO - 2016-01-13 16:48:59,659 INFO : Starting test kraken 16:48:59 INFO - 2016-01-13 16:48:59,659 DEBUG : operating with platform_type : linux_ 16:48:59 INFO - 2016-01-13 16:48:59,659 INFO : Initialising browser for kraken test... 16:48:59 INFO - 2016-01-13 16:48:59,666 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBThO9o/profile http://localhost:49324/getInfo.html 16:49:03 INFO - 2016-01-13 16:49:03,183 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:49:03 INFO - 2016-01-13 16:49:03,183 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:49:03 INFO - 2016-01-13 16:49:03,183 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:49:03 INFO - 2016-01-13 16:49:03,183 DEBUG : BROWSER_OUTPUT: __metrics 16:49:03 INFO - 2016-01-13 16:49:03,260 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] 16:49:04 INFO - 2016-01-13 16:49:04,547 INFO : Browser initialized. 16:49:04 INFO - 2016-01-13 16:49:04,548 INFO : Running cycle 1/1 for kraken test... 16:49:04 INFO - 2016-01-13 16:49:04,548 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBThO9o/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:49:16 INFO - 2016-01-13 16:49:16,093 DEBUG : BROWSER_OUTPUT: RSS: Main: 128626688 16:49:16 INFO - 2016-01-13 16:49:16,093 DEBUG : BROWSER_OUTPUT: 16:49:18 INFO - 2016-01-13 16:49:18,528 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49324/tests/kraken/driver.html?testName=audio-beat-detection) 16:49:19 INFO - 2016-01-13 16:49:19,094 DEBUG : BROWSER_OUTPUT: RSS: Main: 162775040 16:49:19 INFO - 2016-01-13 16:49:19,094 DEBUG : BROWSER_OUTPUT: 16:49:21 INFO - 2016-01-13 16:49:21,631 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49324/tests/kraken/driver.html?testName=audio-dft) 16:49:22 INFO - 2016-01-13 16:49:22,197 DEBUG : BROWSER_OUTPUT: RSS: Main: 160755712 16:49:22 INFO - 2016-01-13 16:49:22,197 DEBUG : BROWSER_OUTPUT: 16:49:24 INFO - 2016-01-13 16:49:24,980 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49324/tests/kraken/driver.html?testName=audio-fft) 16:49:25 INFO - 2016-01-13 16:49:25,549 DEBUG : BROWSER_OUTPUT: RSS: Main: 157347840 16:49:25 INFO - 2016-01-13 16:49:25,549 DEBUG : BROWSER_OUTPUT: 16:49:27 INFO - 2016-01-13 16:49:27,503 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49324/tests/kraken/driver.html?testName=audio-oscillator) 16:49:28 INFO - 2016-01-13 16:49:28,064 DEBUG : BROWSER_OUTPUT: RSS: Main: 157052928 16:49:28 INFO - 2016-01-13 16:49:28,064 DEBUG : BROWSER_OUTPUT: 16:49:30 INFO - 2016-01-13 16:49:30,211 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49324/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:49:30 INFO - 2016-01-13 16:49:30,772 DEBUG : BROWSER_OUTPUT: RSS: Main: 154529792 16:49:30 INFO - 2016-01-13 16:49:30,772 DEBUG : BROWSER_OUTPUT: 16:49:33 INFO - 2016-01-13 16:49:33,629 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49324/tests/kraken/driver.html?testName=imaging-darkroom) 16:49:34 INFO - 2016-01-13 16:49:34,195 DEBUG : BROWSER_OUTPUT: RSS: Main: 156188672 16:49:34 INFO - 2016-01-13 16:49:34,195 DEBUG : BROWSER_OUTPUT: 16:49:37 INFO - 2016-01-13 16:49:37,516 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49324/tests/kraken/driver.html?testName=imaging-desaturate) 16:49:38 INFO - 2016-01-13 16:49:38,086 DEBUG : BROWSER_OUTPUT: RSS: Main: 157077504 16:49:38 INFO - 2016-01-13 16:49:38,086 DEBUG : BROWSER_OUTPUT: 16:49:40 INFO - 2016-01-13 16:49:40,775 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49324/tests/kraken/driver.html?testName=json-parse-financial) 16:49:41 INFO - 2016-01-13 16:49:41,341 DEBUG : BROWSER_OUTPUT: RSS: Main: 154456064 16:49:41 INFO - 2016-01-13 16:49:41,341 DEBUG : BROWSER_OUTPUT: 16:49:42 INFO - 2016-01-13 16:49:42,862 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49324/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:49:43 INFO - 2016-01-13 16:49:43,414 DEBUG : BROWSER_OUTPUT: RSS: Main: 162275328 16:49:43 INFO - 2016-01-13 16:49:43,415 DEBUG : BROWSER_OUTPUT: 16:49:45 INFO - 2016-01-13 16:49:45,136 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49324/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:49:45 INFO - 2016-01-13 16:49:45,721 DEBUG : BROWSER_OUTPUT: RSS: Main: 161947648 16:49:45 INFO - 2016-01-13 16:49:45,722 DEBUG : BROWSER_OUTPUT: 16:49:47 INFO - 2016-01-13 16:49:47,653 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49324/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:49:48 INFO - 2016-01-13 16:49:48,214 DEBUG : BROWSER_OUTPUT: RSS: Main: 167391232 16:49:48 INFO - 2016-01-13 16:49:48,215 DEBUG : BROWSER_OUTPUT: 16:49:50 INFO - 2016-01-13 16:49:50,553 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49324/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:49:51 INFO - 2016-01-13 16:49:51,128 DEBUG : BROWSER_OUTPUT: RSS: Main: 171696128 16:49:51 INFO - 2016-01-13 16:49:51,128 DEBUG : BROWSER_OUTPUT: 16:49:53 INFO - 2016-01-13 16:49:53,525 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49324/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:49:54 INFO - 2016-01-13 16:49:54,095 DEBUG : BROWSER_OUTPUT: RSS: Main: 171257856 16:49:54 INFO - 2016-01-13 16:49:54,095 DEBUG : BROWSER_OUTPUT: 16:49:55 INFO - 2016-01-13 16:49:55,718 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49324/tests/kraken/driver.html?testName=ai-astar) 16:49:55 INFO - 2016-01-13 16:49:55,968 DEBUG : BROWSER_OUTPUT: RSS: Main: 229408768 16:49:55 INFO - 2016-01-13 16:49:55,968 DEBUG : BROWSER_OUTPUT: 16:49:55 INFO - 2016-01-13 16:49:55,968 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:49:55 INFO - 2016-01-13 16:49:55,968 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:49:55 INFO - 2016-01-13 16:49:55,968 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:49:55 INFO - 2016-01-13 16:49:55,968 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:49:55 INFO - 2016-01-13 16:49:55,969 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;109;104;109;111;111;99;110;107;108 16:49:55 INFO - 2016-01-13 16:49:55,969 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;150;125;144;161;128;145;144;121;155;126 16:49:55 INFO - 2016-01-13 16:49:55,969 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;160;159;161;162;159;158;161;159;160 16:49:55 INFO - 2016-01-13 16:49:55,969 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;72;140;71;72;71;71;149;71 16:49:55 INFO - 2016-01-13 16:49:55,969 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;89;141;89;90;90;131;90;90;137 16:49:55 INFO - 2016-01-13 16:49:55,969 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;110;110;110;111;110;109;109 16:49:55 INFO - 2016-01-13 16:49:55,969 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;146;146;145;145;146;146;146;145 16:49:55 INFO - 2016-01-13 16:49:55,969 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;95;96;95;96;95;96;95 16:49:55 INFO - 2016-01-13 16:49:55,969 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;56;55;54;56;55;65;54;58;54 16:49:55 INFO - 2016-01-13 16:49:55,969 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;62;63;62;64;63;64;63;63;64 16:49:55 INFO - 2016-01-13 16:49:55,970 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;75;78;77;86;78;76;78;77;79 16:49:55 INFO - 2016-01-13 16:49:55,970 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;117;106;107;113;112;113;115;122;116;110 16:49:55 INFO - 2016-01-13 16:49:55,970 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;134;130;140;133;134;134;132;137;134 16:49:55 INFO - 2016-01-13 16:49:55,970 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;65;62;60;60;63;61;61;61;60 16:49:55 INFO - 2016-01-13 16:49:55,970 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:49:55 INFO - 2016-01-13 16:49:55,970 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:49:55 INFO - 2016-01-13 16:49:55,970 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,857 16:49:55 INFO - 2016-01-13 16:49:55,970 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:49:55 INFO - 2016-01-13 16:49:55,970 DEBUG : BROWSER_OUTPUT: __startTimestamp1452732595967__endTimestamp 16:49:55 INFO - 2016-01-13 16:49:55,970 DEBUG : BROWSER_OUTPUT: 16:49:55 INFO - 2016-01-13 16:49:55,973 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:49:55 INFO - 2016-01-13 16:49:55,973 DEBUG : BROWSER_OUTPUT: Number of tests: 14 16:49:55 INFO - 2016-01-13 16:49:55,974 DEBUG : BROWSER_OUTPUT: 16:49:55 INFO - 2016-01-13 16:49:55,974 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.70 Median:109.50 stddev:3.68 (3.4%) stddev-sans-first:3.88 16:49:55 INFO - 2016-01-13 16:49:55,975 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 104.0 109.0 111.0 111.0 99.0 110.0 107.0 108.0 16:49:55 INFO - 2016-01-13 16:49:55,975 DEBUG : BROWSER_OUTPUT: 16:49:55 INFO - 2016-01-13 16:49:55,975 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:139.90 Median:144.50 stddev:13.94 (9.6%) stddev-sans-first:14.30 16:49:55 INFO - 2016-01-13 16:49:55,975 DEBUG : BROWSER_OUTPUT: Values: 150.0 125.0 144.0 161.0 128.0 145.0 144.0 121.0 155.0 126.0 16:49:55 INFO - 2016-01-13 16:49:55,975 DEBUG : BROWSER_OUTPUT: 16:49:55 INFO - 2016-01-13 16:49:55,975 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.80 Median:160.00 stddev:1.23 (0.8%) stddev-sans-first:1.27 16:49:55 INFO - 2016-01-13 16:49:55,975 DEBUG : BROWSER_OUTPUT: Values: 159.0 160.0 159.0 161.0 162.0 159.0 158.0 161.0 159.0 160.0 16:49:55 INFO - 2016-01-13 16:49:55,975 DEBUG : BROWSER_OUTPUT: 16:49:55 INFO - 2016-01-13 16:49:55,975 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:86.00 Median:71.00 stddev:30.91 (43.5%) stddev-sans-first:32.30 16:49:55 INFO - 2016-01-13 16:49:55,975 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 72.0 140.0 71.0 72.0 71.0 71.0 149.0 71.0 16:49:55 INFO - 2016-01-13 16:49:55,975 DEBUG : BROWSER_OUTPUT: 16:49:55 INFO - 2016-01-13 16:49:55,976 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.60 Median:89.50 stddev:22.72 (25.4%) stddev-sans-first:23.47 16:49:55 INFO - 2016-01-13 16:49:55,976 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 141.0 89.0 90.0 90.0 131.0 90.0 90.0 137.0 16:49:55 INFO - 2016-01-13 16:49:55,976 DEBUG : BROWSER_OUTPUT: 16:49:55 INFO - 2016-01-13 16:49:55,976 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.70 Median:110.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 16:49:55 INFO - 2016-01-13 16:49:55,976 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 110.0 110.0 110.0 111.0 110.0 109.0 109.0 16:49:55 INFO - 2016-01-13 16:49:55,976 DEBUG : BROWSER_OUTPUT: 16:49:55 INFO - 2016-01-13 16:49:55,976 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 16:49:55 INFO - 2016-01-13 16:49:55,976 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 146.0 146.0 145.0 145.0 146.0 146.0 146.0 145.0 16:49:55 INFO - 2016-01-13 16:49:55,976 DEBUG : BROWSER_OUTPUT: 16:49:55 INFO - 2016-01-13 16:49:55,976 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 16:49:55 INFO - 2016-01-13 16:49:55,976 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 95.0 96.0 95.0 96.0 95.0 96.0 95.0 16:49:55 INFO - 2016-01-13 16:49:55,976 DEBUG : BROWSER_OUTPUT: 16:49:55 INFO - 2016-01-13 16:49:55,976 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.30 Median:56.00 stddev:3.30 (5.9%) stddev-sans-first:3.50 16:49:55 INFO - 2016-01-13 16:49:55,976 DEBUG : BROWSER_OUTPUT: Values: 56.0 56.0 55.0 54.0 56.0 55.0 65.0 54.0 58.0 54.0 16:49:55 INFO - 2016-01-13 16:49:55,976 DEBUG : BROWSER_OUTPUT: 16:49:55 INFO - 2016-01-13 16:49:55,976 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:63.50 stddev:0.79 (1.2%) stddev-sans-first:0.78 16:49:55 INFO - 2016-01-13 16:49:55,977 DEBUG : BROWSER_OUTPUT: Values: 64.0 62.0 63.0 62.0 64.0 63.0 64.0 63.0 63.0 64.0 16:49:55 INFO - 2016-01-13 16:49:55,977 DEBUG : BROWSER_OUTPUT: 16:49:55 INFO - 2016-01-13 16:49:55,977 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:78.00 stddev:3.06 (3.9%) stddev-sans-first:3.15 16:49:55 INFO - 2016-01-13 16:49:55,977 DEBUG : BROWSER_OUTPUT: Values: 76.0 75.0 78.0 77.0 86.0 78.0 76.0 78.0 77.0 79.0 16:49:55 INFO - 2016-01-13 16:49:55,977 DEBUG : BROWSER_OUTPUT: 16:49:55 INFO - 2016-01-13 16:49:55,977 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:113.10 Median:114.00 stddev:4.77 (4.2%) stddev-sans-first:4.85 16:49:55 INFO - 2016-01-13 16:49:55,977 DEBUG : BROWSER_OUTPUT: Values: 117.0 106.0 107.0 113.0 112.0 113.0 115.0 122.0 116.0 110.0 16:49:55 INFO - 2016-01-13 16:49:55,977 DEBUG : BROWSER_OUTPUT: 16:49:55 INFO - 2016-01-13 16:49:55,977 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:134.80 Median:134.00 stddev:3.26 (2.4%) stddev-sans-first:2.86 16:49:55 INFO - 2016-01-13 16:49:55,977 DEBUG : BROWSER_OUTPUT: Values: 140.0 134.0 130.0 140.0 133.0 134.0 134.0 132.0 137.0 134.0 16:49:55 INFO - 2016-01-13 16:49:55,977 DEBUG : BROWSER_OUTPUT: 16:49:55 INFO - 2016-01-13 16:49:55,977 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:61.50 stddev:1.65 (2.7%) stddev-sans-first:1.67 16:49:55 INFO - 2016-01-13 16:49:55,977 DEBUG : BROWSER_OUTPUT: Values: 63.0 65.0 62.0 60.0 60.0 63.0 61.0 61.0 61.0 60.0 16:49:55 INFO - 2016-01-13 16:49:55,977 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:49:55 INFO - 2016-01-13 16:49:55,977 DEBUG : BROWSER_OUTPUT: 16:49:56 INFO - 2016-01-13 16:49:56,691 INFO : Browser exited with error code: 0 16:49:56 INFO - 2016-01-13 16:49:56,742 INFO : Completed test kraken (00:00:57) 16:49:56 INFO - 2016-01-13 16:49:56,742 INFO : Starting test v8_7 16:49:56 INFO - 2016-01-13 16:49:56,742 DEBUG : operating with platform_type : linux_ 16:49:56 INFO - 2016-01-13 16:49:56,742 INFO : Initialising browser for v8_7 test... 16:49:56 INFO - 2016-01-13 16:49:56,749 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpntpYQi/profile http://localhost:49324/getInfo.html 16:50:00 INFO - 2016-01-13 16:50:00,192 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:50:00 INFO - 2016-01-13 16:50:00,193 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:50:00 INFO - 2016-01-13 16:50:00,193 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:50:00 INFO - 2016-01-13 16:50:00,193 DEBUG : BROWSER_OUTPUT: __metrics 16:50:00 INFO - 2016-01-13 16:50:00,371 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] 16:50:01 INFO - 2016-01-13 16:50:01,611 INFO : Browser initialized. 16:50:01 INFO - 2016-01-13 16:50:01,612 INFO : Running cycle 1/1 for v8_7 test... 16:50:01 INFO - 2016-01-13 16:50:01,612 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpntpYQi/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:50:13 INFO - 2016-01-13 16:50:13,190 DEBUG : BROWSER_OUTPUT: RSS: Main: 129273856 16:50:13 INFO - 2016-01-13 16:50:13,190 DEBUG : BROWSER_OUTPUT: 16:50:34 INFO - 2016-01-13 16:50:34,573 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49324/tests/v8_7/run.html (next: http://localhost:49324/tests/v8_7/run.html) 16:50:34 INFO - 2016-01-13 16:50:34,823 DEBUG : BROWSER_OUTPUT: RSS: Main: 428556288 16:50:34 INFO - 2016-01-13 16:50:34,824 DEBUG : BROWSER_OUTPUT: 16:50:34 INFO - 2016-01-13 16:50:34,824 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:50:34 INFO - 2016-01-13 16:50:34,824 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:50:34 INFO - 2016-01-13 16:50:34,825 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:50:34 INFO - 2016-01-13 16:50:34,825 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:50:34 INFO - 2016-01-13 16:50:34,826 DEBUG : BROWSER_OUTPUT: |0;Richards;166.5001665001665 16:50:34 INFO - 2016-01-13 16:50:34,826 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.47391749062393 16:50:34 INFO - 2016-01-13 16:50:34,826 DEBUG : BROWSER_OUTPUT: |2;Encrypt;281.45229383619477 16:50:34 INFO - 2016-01-13 16:50:34,826 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5010 16:50:34 INFO - 2016-01-13 16:50:34,826 DEBUG : BROWSER_OUTPUT: |4;RayTrace;937.207122774133 16:50:34 INFO - 2016-01-13 16:50:34,826 DEBUG : BROWSER_OUTPUT: |5;Earley;554.9389567147614 16:50:34 INFO - 2016-01-13 16:50:34,826 DEBUG : BROWSER_OUTPUT: |6;Boyer;10479.166666666666 16:50:34 INFO - 2016-01-13 16:50:34,827 DEBUG : BROWSER_OUTPUT: |7;RegExp;23604.6511627907 16:50:34 INFO - 2016-01-13 16:50:34,827 DEBUG : BROWSER_OUTPUT: |8;Splay;539.3743257820928 16:50:34 INFO - 2016-01-13 16:50:34,827 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4812.5 16:50:34 INFO - 2016-01-13 16:50:34,827 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:50:34 INFO - 2016-01-13 16:50:34,827 DEBUG : BROWSER_OUTPUT: __startTimestamp1452732634823__endTimestamp 16:50:34 INFO - 2016-01-13 16:50:34,827 DEBUG : BROWSER_OUTPUT: 16:50:34 INFO - 2016-01-13 16:50:34,827 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:50:34 INFO - 2016-01-13 16:50:34,828 DEBUG : BROWSER_OUTPUT: Number of tests: 10 16:50:34 INFO - 2016-01-13 16:50:34,829 DEBUG : BROWSER_OUTPUT: 16:50:34 INFO - 2016-01-13 16:50:34,829 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.50 Median:166.50 stddev:0.00 (0.0%) 16:50:34 INFO - 2016-01-13 16:50:34,829 DEBUG : BROWSER_OUTPUT: Values: 166.5 16:50:34 INFO - 2016-01-13 16:50:34,829 DEBUG : BROWSER_OUTPUT: 16:50:34 INFO - 2016-01-13 16:50:34,829 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.47 Median:170.47 stddev:0.00 (0.0%) 16:50:34 INFO - 2016-01-13 16:50:34,829 DEBUG : BROWSER_OUTPUT: Values: 170.5 16:50:34 INFO - 2016-01-13 16:50:34,829 DEBUG : BROWSER_OUTPUT: 16:50:34 INFO - 2016-01-13 16:50:34,829 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:281.45 Median:281.45 stddev:0.00 (0.0%) 16:50:34 INFO - 2016-01-13 16:50:34,829 DEBUG : BROWSER_OUTPUT: Values: 281.5 16:50:34 INFO - 2016-01-13 16:50:34,829 DEBUG : BROWSER_OUTPUT: 16:50:34 INFO - 2016-01-13 16:50:34,829 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5010.00 Median:5010.00 stddev:0.00 (0.0%) 16:50:34 INFO - 2016-01-13 16:50:34,829 DEBUG : BROWSER_OUTPUT: Values: 5010.0 16:50:34 INFO - 2016-01-13 16:50:34,829 DEBUG : BROWSER_OUTPUT: 16:50:34 INFO - 2016-01-13 16:50:34,829 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:937.21 Median:937.21 stddev:0.00 (0.0%) 16:50:34 INFO - 2016-01-13 16:50:34,829 DEBUG : BROWSER_OUTPUT: Values: 937.2 16:50:34 INFO - 2016-01-13 16:50:34,829 DEBUG : BROWSER_OUTPUT: 16:50:34 INFO - 2016-01-13 16:50:34,830 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:554.94 Median:554.94 stddev:0.00 (0.0%) 16:50:34 INFO - 2016-01-13 16:50:34,830 DEBUG : BROWSER_OUTPUT: Values: 554.9 16:50:34 INFO - 2016-01-13 16:50:34,830 DEBUG : BROWSER_OUTPUT: 16:50:34 INFO - 2016-01-13 16:50:34,830 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10479.17 Median:10479.17 stddev:0.00 (0.0%) 16:50:34 INFO - 2016-01-13 16:50:34,830 DEBUG : BROWSER_OUTPUT: Values: 10479.2 16:50:34 INFO - 2016-01-13 16:50:34,830 DEBUG : BROWSER_OUTPUT: 16:50:34 INFO - 2016-01-13 16:50:34,830 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23604.65 Median:23604.65 stddev:0.00 (0.0%) 16:50:34 INFO - 2016-01-13 16:50:34,830 DEBUG : BROWSER_OUTPUT: Values: 23604.7 16:50:34 INFO - 2016-01-13 16:50:34,830 DEBUG : BROWSER_OUTPUT: 16:50:34 INFO - 2016-01-13 16:50:34,830 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:539.37 Median:539.37 stddev:0.00 (0.0%) 16:50:34 INFO - 2016-01-13 16:50:34,830 DEBUG : BROWSER_OUTPUT: Values: 539.4 16:50:34 INFO - 2016-01-13 16:50:34,830 DEBUG : BROWSER_OUTPUT: 16:50:34 INFO - 2016-01-13 16:50:34,830 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4812.50 Median:4812.50 stddev:0.00 (0.0%) 16:50:34 INFO - 2016-01-13 16:50:34,830 DEBUG : BROWSER_OUTPUT: Values: 4812.5 16:50:34 INFO - 2016-01-13 16:50:34,830 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:50:34 INFO - 2016-01-13 16:50:34,830 DEBUG : BROWSER_OUTPUT: 16:50:35 INFO - 2016-01-13 16:50:35,468 INFO : Browser exited with error code: 0 16:50:35 INFO - 2016-01-13 16:50:35,485 INFO : Completed test v8_7 (00:00:38) 16:50:35 INFO - 2016-01-13 16:50:35,850 INFO : Completed test suite (00:20:05) 16:50:35 INFO - 2016-01-13 16:50:35,851 DEBUG : Working with test: dromaeo_css 16:50:35 INFO - 2016-01-13 16:50:35,851 DEBUG : Generating results file: dromaeo_css 16:50:35 INFO - 2016-01-13 16:50:35,853 DEBUG : Working with test: kraken 16:50:35 INFO - 2016-01-13 16:50:35,853 DEBUG : Generating results file: kraken 16:50:35 INFO - 2016-01-13 16:50:35,853 INFO : javascript benchmark 16:50:35 INFO - 2016-01-13 16:50:35,853 DEBUG : Working with test: v8_7 16:50:35 INFO - 2016-01-13 16:50:35,854 DEBUG : Generating results file: v8_7 16:50:35 INFO - 2016-01-13 16:50:35,854 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:50:35 INFO - 2016-01-13 16:50:35,912 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 16:50:35 INFO - 2016-01-13 16:50:35,912 DEBUG : process_Request line: dromaeo_css 7253.45 graph.html#tests=[[72,53,35]] 16:50:35 INFO - 2016-01-13 16:50:35,912 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:50:35 INFO - 2016-01-13 16:50:35,942 DEBUG : process_Request line: kraken 1454.80 graph.html#tests=[[232,53,35]] 16:50:35 INFO - 2016-01-13 16:50:35,942 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:50:35 INFO - 2016-01-13 16:50:35,971 DEBUG : process_Request line: v8_7 23267.44 graph.html#tests=[[230,53,35]] 16:50:35 INFO - 2016-01-13 16:50:35,973 INFO : javascript benchmark 16:50:36 INFO - 2016-01-13 16:50:35,976 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3198.648148703643, 3261.7390608751302, 3262.4290749118927, 3273.067175854882, 3280.261941063261, 5417.593680473966, 5442.374126975399, 5458.7270636468165, 5479.369872464667, 5483.862903427412, 5516.7517461714215, 5523.917141242885, 5534.142207957763, 5558.722063896799, 5573.721313934308, 4906.1414252505665, 5022.17134172861, 5081.8475445736685, 5123.4108077571, 5149.536541711245, 4983.202687569989, 5042.0, 5066.290719299301, 5155.046316431472, 5163.096458119833, 5068.797248110071, 5084.542391184793, 5102.1836506159025, 5113.079645663797, 5125.974805039007, 5079.466656001104, 5082.415522214956, 5091.872703182432, 5135.871603209931, 5161.61287903405, 4803.9039219215765, 4861.586765124934, 4888.0957022950515, 4915.90168196634, 4940.925886111711, 5340.332458442695, 5355.518003379714, 5356.464353564651, 5375.543078838265, 5384.865378365533, 5636.520895723868, 5921.407859214087, 5930.644161350333, 5951.375105613892, 5955.6128851624935, 5722.97138514309, 5802.622829516068, 5802.97098514509, 5803.854903627401, 5846.590738648296, 5502.80740174092, 5534.529564986981, 5537.861553461155, 5540.085885828874, 5541.473560011793, 5864.266966629171, 5954.970225148933, 6004.82905833367, 6009.399060094043, 6009.828083523671, 5712.80005199816, 5782.942170578241, 5786.305643322744, 5793.8841223175305, 5818.040023396177, 5972.074328479092, 6007.36922623127, 6009.308929473079, 6010.519158467312, 6028.2766068072115, 5642.802501912495, 5787.160861675117, 5792.247007888947, 5976.103584462278, 6022.1870047543025, 5929.703514824241, 6028.788992385245, 6029.336772954972, 6032.155498230251, 6037.335893051761, 4613.5847773700525, 4627.8843028923875, 4631.722096674143, 4635.258358662598, 4649.604783593365, 3995.7402768819934, 4035.9798201009394, 4063.959360406417, 4101.671866250693, 4135.0, 5629.043062679354, 5631.1271752878365, 5633.267675202197, 5634.830955071355, 5654.37801841797, 5919.0, 5935.821925342247, 5942.673152976541, 5942.851428714317, 5960.403959604092, 5520.144377621395, 5569.053260945649, 5627.634203776742, 5633.042382794934, 5667.858303542362, 4978.975105124451, 5063.139266324733, 5101.183810590288, 5109.591232701375, 5114.820981265712, 5904.5571582131515, 6196.938897524737, 6263.874722505524, 6266.185395898595, 6273.52948528853, 5831.241938548046, 5931.406859314034, 6070.726817293321, 6099.603525770811, 6101.145839582373, 6125.448709616156, 6129.35641757609, 6133.7546498141355, 6134.570580059353, 6139.324674285915, 5824.708764561839, 5871.706414679335, 5880.882382352414, 5885.852853678693, 5938.703064846827, 5857.238558987305, 5942.851428714317, 5944.108383742473, 5957.612755171073, 5974.044152935509, 5900.144479050427, 5905.232319798399, 5905.291365036223, 5907.232059832194, 5909.172715819703, 5658.236138121301, 5713.8285851424525, 5768.653880767168, 5890.057590785454, 5903.49820265265, 5715.114157305624, 5783.739731712168, 5794.971025144847, 5801.274840644919, 5807.303123625192, 5839.766409343579, 5870.5010074144175, 5879.529637628894, 5883.294004719461, 5886.882262354815, 5560.749766260447, 5616.747246374005, 5657.038303488334, 5736.598438109292, 5764.711764411846], "unit": "ms", "name": "dojo.html", "value": 5466.97686640508}, {"lowerIsBetter": false, "replicates": [597.4025974025974, 627.0875875601001, 644.7678835619176, 658.8385845467858, 659.3406593406594, 36098.45852312214, 45210.54789452104, 45906.0, 45932.54067459324, 45955.5404445955, 48937.0, 49548.25677614839, 49565.0, 49593.75203123979, 49601.751991239995, 8346.374021948348, 8435.536045517494, 9139.543022848864, 9349.626014959393, 10608.78782424351, 10706.785864282749, 10799.730006749855, 10817.134629229642, 10820.350778953289, 10820.567177312898, 10914.23600347976, 11002.614908478205, 11022.0, 11030.779384412306, 11032.779344413108, 10804.405757683324, 10901.400422976732, 10956.0, 10962.616308429167, 10973.945130274338, 10760.3543787373, 10908.836367454494, 10924.126069914388, 10926.180536459797, 10927.344359338465, 44652.0, 45275.773621132015, 45307.320390194174, 45428.3185752214, 45555.544444555795, 3788.7158463115375, 4418.911621767546, 4441.866743997685, 4447.933281000787, 4449.443819522559, 33357.3328533428, 33694.49458258128, 33913.152171195914, 35270.64729352699, 35833.0, 3768.9623103768886, 3796.772193668389, 3801.391777315644, 3837.347650899326, 3948.4077388391684, 335306.0, 336942.0, 337665.0, 448146.0, 456761.0, 77415.0, 79195.60402197952, 79553.20446795574, 79611.20388796038, 79711.0, 72470.27529724635, 72915.63542182255, 72980.0, 72996.0, 73086.269137309, 18952.14715337813, 19167.616647666968, 19226.71159932602, 19265.036748162816, 19271.421857344303, 14576.63558411027, 14577.125372477685, 14637.853621463863, 14655.706885862222, 14678.926605366905, 22894.42763930882, 23072.53854922926, 23143.652845207336, 23193.188238411574, 23193.304200874, 8788.384813063025, 9037.502937338379, 9049.366544341965, 9062.81874362509, 9156.679516217033, 931.7391130483476, 951.7715748220377, 997.5012493753123, 1000.8949060348705, 1032.7418145463635, 28623.856880715462, 29033.419331613673, 29486.557701634494, 29497.410051798845, 30980.53529197064, 1105.5633024955098, 1116.2242241641982, 1135.0, 1136.5226604825993, 1158.7334912970064, 14489.347979340955, 15631.921840390725, 15640.0, 16035.43875964359, 16555.91722041382, 376747.0, 379626.0, 381974.0, 382061.0, 383686.0, 60154.69922650446, 60602.0, 60695.75129740876, 60738.39261607327, 60764.0, 1383.9654008649663, 1404.227674778888, 1461.5250043735828, 1524.3521503361028, 1542.6143464133966, 33369.33261334808, 33603.08516165183, 33855.0, 33971.0, 33991.0, 54493.45506544884, 54933.0, 55113.0, 55136.172957406474, 55140.0, 320111.0, 322620.0, 322986.0, 323633.0, 324330.0, 36725.55164313369, 37399.813000934824, 37442.063948401505, 37634.43548346722, 37757.433638496, 50035.0, 50672.49327506678, 51103.0, 51302.74348628233, 51471.48528514667, 41815.7909210452, 42050.0, 42399.57600423956, 43137.56862431335, 43637.12725745531, 41570.79214603908, 41654.37518437287, 42217.577824221364, 42794.78602606967, 42806.35790463208, 39888.20503077427, 41236.38145427761, 41912.79043604885, 41981.580184197766, 42006.36990445207, 37274.813625931696, 37738.245235095696, 37929.4310585347, 38199.0, 38332.0, 28536.714632853407, 28701.425971480036, 28817.85591072031, 28839.0, 28914.27714307159], "unit": "ms", "name": "ext.html", "value": 21518.04190266242}, {"lowerIsBetter": false, "replicates": [388.99805500972474, 391.97256192066567, 397.72755662371276, 402.3843519415295, 403.2237941961723, 13893.374798134082, 14130.22283774392, 17921.910390448047, 19248.90375548124, 19369.322073727424, 13251.867481325184, 13585.728285434285, 13628.386722597506, 18779.62440751184, 19321.516962075955, 4846.466888642247, 4964.230544265626, 4966.950330496703, 4966.9751651241695, 4969.602431805447, 4890.3642526471685, 4900.240462728283, 4913.091078150535, 4913.950860491385, 4914.434839993411, 3829.7702137871815, 4855.0, 4868.05073010763, 4868.707877527359, 5060.9493905060845, 5346.91979620306, 5352.170413585901, 5388.488093631099, 5391.245225668409, 5400.162974738902, 4674.252119660872, 4737.2420412734145, 4742.19382704941, 4752.833650822205, 4796.37647105874, 14601.342939567745, 15032.624184395369, 15889.0, 15932.840671593252, 16077.91961040199, 2226.821854251672, 2229.8550594211324, 2262.5248697773486, 2333.1134169015836, 2371.4782747795457, 10609.416482093482, 10701.464926753632, 10726.249162558624, 10848.511816968256, 10948.890511094867, 1755.920983555743, 1769.5664562182349, 1814.6733587954163, 1848.787389450217, 1876.1182244345136, 66378.0, 66850.66574667144, 67394.0, 68624.0, 68772.0, 8049.114597394282, 8079.717209897743, 8115.79710507242, 8141.0, 8167.550784706779, 9823.901760982299, 9831.508424578742, 9878.950605247072, 9882.851757223647, 9890.357126786736, 6430.7106180222, 6494.318096599884, 6560.376764207393, 6567.376099270562, 6570.671466426659, 6460.483161347081, 6479.935200648028, 6487.740490380332, 6746.123004009447, 6769.458443324522, 3708.962910370916, 3733.9066523337133, 3740.233252183296, 3763.0592351912023, 3765.1528406108405, 1274.1526884621624, 1285.3059347952, 1286.4018231522386, 1329.3021163888843, 1342.147736187528, 512.64627407089, 522.1671434062664, 538.020802140109, 540.3704684043046, 545.1631745271069, 12812.359382030862, 13282.0, 13459.730805384033, 13572.524961626494, 13579.321033948461, 507.84003039043324, 538.0288579114736, 544.3060098374604, 565.4260925160964, 567.3277166557642, 7068.646567671596, 7101.2898710128575, 7424.703011879572, 7485.850282994418, 7526.870969354641, 601834.0, 626262.0, 627551.0, 631054.0, 637764.0, 85359.14640853512, 85684.0, 86213.13786862176, 86702.56648716716, 87502.56248718844, 644.0950464597248, 648.1412128929094, 648.4844548583793, 648.967551622417, 648.9935100649028, 12506.687332816753, 12551.273990239215, 12618.116731828684, 12745.426455809325, 12775.0, 38138.80930595329, 38399.0, 38435.0, 39466.0, 39757.20485590214, 50163.74918125532, 50183.74908125582, 50737.492625073275, 50969.0, 51509.7424512875, 7730.574818385043, 7749.41879359039, 7783.805404864924, 7825.4130940178575, 7870.409719270963, 13314.800277995644, 13738.450461981409, 13837.169769813843, 13874.722505549631, 14119.082259653089, 11853.76292474163, 12150.453229604866, 12335.938320308342, 12505.624831255076, 12544.74910501803, 10976.835347469954, 11252.324860508395, 11512.079033677119, 12043.81934270969, 12428.875711242772, 9797.951010244904, 10660.62687805923, 11318.26431281964, 11759.176857620227, 12252.693682658004, 7642.312191902533, 7642.388608911385, 7647.38820894316, 7665.3101220890385, 7745.148033716399, 11424.543018279175, 11454.885451145716, 11716.765664686489, 11805.704857378634, 12069.396530173632], "unit": "ms", "name": "jquery.html", "value": 7723.267209384297}, {"lowerIsBetter": false, "replicates": [1590.6739118480705, 1617.1752406272797, 1833.1109411935213, 1848.5378655336167, 1861.692820684587, 7178.605176715291, 7305.780826575198, 7741.845163096735, 7915.881261781078, 7926.1281259061525, 7689.730859419921, 7758.612069396521, 7842.64708088136, 7861.056673199224, 7910.920890791091, 2385.7852793248603, 2415.613501839706, 2418.2745176447083, 2421.673074134987, 2422.0553983946274, 2081.9791802082013, 2189.4964158243615, 2331.5919714049974, 2385.0817435287454, 2422.624493203556, 2320.373499155227, 2339.730930942938, 2345.835791494596, 2348.5655153796515, 2351.776581224781, 2388.9283321500384, 2426.4783071639504, 2426.502566973766, 2426.551088048717, 2427.1383658801046, 2357.233898982837, 2428.91498797543, 2432.440538676097, 2432.9513409731708, 2434.476587533671, 7932.405069619744, 8022.318102961255, 8046.839063218761, 8056.355491560661, 8070.878936815952, 4917.795140190676, 4987.177115775893, 4991.6256280778725, 4996.300517927493, 4998.875028124289, 3248.2853772170083, 3286.7699261051735, 3366.276250606107, 3390.8982730518123, 3406.931861362759, 1082.664374044041, 1096.7641956979287, 1098.1708809848512, 1121.9495122719497, 1152.216492784906, 10995.615153469591, 11019.228653994143, 11026.228164028522, 11038.392888391136, 11042.392668403234, 4490.820367185342, 4501.3022981437925, 4548.340490628906, 4556.931646025312, 4568.497465278817, 9379.765505862408, 9404.482753448488, 9422.104900034486, 9427.104425079606, 9427.104425079606, 5050.04049230645, 5057.949420505822, 5058.696478211319, 5059.8988020239385, 5061.0, 4571.177188106106, 4625.32932724753, 4633.606143477775, 4642.0715856828765, 4644.767761611906, 3726.5155529781496, 3845.538535375773, 3867.5939026402384, 3918.0988372674447, 3986.342253528135, 1185.7924863148914, 1198.9280643161437, 1209.8850609192114, 1234.370471059748, 1237.0907383073434, 1200.5977997370803, 1234.0497816681104, 1340.9664758381118, 1473.0204414064742, 1491.209658880795, 7841.098273698483, 7906.802329941797, 7908.090569584457, 7912.4856884302335, 7914.2481464261955, 1208.468273959455, 1209.449700386322, 1213.696575856036, 1226.8711785262597, 1233.4881024374963, 6020.157177995085, 6036.909446358308, 6093.421124993119, 6106.694665266719, 6115.357887421846, 9511.762205944906, 9799.11807937289, 9829.115379615869, 9833.754156146153, 9842.75393115178, 9471.19494842946, 9648.469334186688, 9653.372530785617, 9661.855072173923, 9664.275179361575, 7375.741849035366, 7417.517861338995, 7443.776686699516, 7443.962780186064, 7453.366463850633, 7388.593627350548, 7444.0, 7460.0, 7488.176300607038, 7488.213737557478, 8570.14298570163, 8667.783305417415, 8712.21590056898, 8753.737387878375, 8766.298696104168, 8646.697365592174, 8769.824603508021, 8780.078091800524, 8786.560671966505, 8910.153535414125, 7087.362137407658, 7134.607596582237, 7144.535605185646, 7148.749793757193, 7150.856982860418, 7881.763547093597, 7932.524048557105, 7970.641321140447, 7983.960080199562, 7990.600469976362, 7563.773086807405, 7583.521213363341, 7642.465027448247, 7675.2324767522805, 7699.615019249127, 7305.342519173088, 7351.081114860643, 7404.518706284074, 7410.184879663125, 7458.440616953859, 7229.855402892018, 7310.561366318038, 7557.168711441627, 7591.316781489692, 7592.544447333178, 6800.387965083166, 6818.18181818185, 6833.282505337067, 6840.144981877265, 6859.245482996766, 6735.326467353226, 6945.930540694529, 6953.0, 6969.7909062728195, 6970.477214208853], "unit": "ms", "name": "mootools.html", "value": 4739.379077914019}, {"lowerIsBetter": false, "replicates": [372.46923134533284, 373.24976796638754, 374.56924536782697, 376.5425008614533, 382.3232877806282, 5796.391378905218, 5812.883742325151, 5874.530037596993, 5900.232969713937, 5920.259967504062, 5676.063449530833, 5682.090865461517, 5707.0, 5739.799107031255, 5744.281964754406, 4585.335126406669, 4597.747123908183, 4599.724016559009, 4606.746628935407, 4608.792604332813, 4476.261416866229, 4541.477730061036, 4571.1771881061395, 4602.884927876797, 4628.352030715702, 4561.247394179956, 4575.702579332333, 4577.267637178053, 4582.495925448198, 4582.7250364978045, 4569.817207311704, 4571.2457444521615, 4573.222552166139, 4583.518730533296, 4584.7936842842155, 4109.0959988802715, 4109.4246805447265, 4110.753354798691, 4111.773852438115, 4118.814653340577, 5480.91778623321, 5580.051391263495, 5606.55147588192, 5662.546996240291, 5664.603477756558, 5396.892062158735, 5397.514223719884, 5398.595105367113, 5400.162974738922, 5404.270423492818, 5970.6716130612795, 6220.720067596969, 6223.533235007392, 6224.3464436234, 6225.968870155621, 4451.221036318664, 4513.413256276696, 4564.360989461445, 4569.977150114228, 4575.794089265991, 6288.874222515524, 6315.431611154972, 6316.842078948017, 6321.74713011479, 6327.050942358683, 4660.39414876064, 4664.743439110882, 4666.463356714022, 4666.743329116931, 4668.043051174501, 4635.304704294383, 4727.810887564459, 4735.195016847144, 4737.265723812815, 4754.191787396151, 5345.9732701337025, 5347.0, 5347.117725575315, 5347.86630334237, 5347.946520534822, 6206.968965155145, 6238.282597501255, 6239.126522286883, 6245.96877015612, 6251.12484252205, 1711.3582406597525, 1733.0208432235747, 1736.2273788164146, 1737.122753009722, 1738.4350086222028, 1189.5658084799159, 1191.3149938785234, 1195.3306148556835, 1203.5967950736601, 1229.1396022784088, 6033.336333003366, 6061.515078793686, 6062.120992456069, 6067.514598832083, 6074.0, 6422.068800024063, 6422.357764223634, 6422.45409140219, 6423.164988551458, 6427.807165785034, 5931.614445061057, 6033.0, 6035.30593981689, 6040.456358927718, 6042.274927008787, 3522.5068490411363, 3543.964560354415, 3560.2345495718546, 3561.786292822439, 3563.964360356415, 6629.900551491732, 6632.336766323329, 6649.202095748541, 6656.068150458939, 6660.134182556237, 6520.0871877937125, 6563.606183628902, 6566.934330656728, 6574.80275591733, 6575.112359831457, 6511.73953041873, 6629.5359324846795, 6643.534952553275, 6654.83362915931, 6658.60048397088, 6237.875242495215, 6329.620222786648, 6400.327965563735, 6415.1981002374705, 6423.357664233539, 6238.719257633326, 6287.308396076336, 6312.147860038836, 6332.3034466207755, 6345.143405640364, 6591.143151390289, 6612.702428390638, 6615.768448104293, 6619.205695316593, 6624.867502650017, 4542.295944128666, 4672.159011377985, 4674.672772905864, 4678.508756580646, 4679.017406344706, 6371.331010244043, 6376.171097757262, 6382.074599183, 6384.776532821329, 6388.0, 6157.261128664589, 6180.81457556274, 6218.595791273553, 6226.128342032029, 6229.657368844757, 6164.568480206342, 6200.906986395117, 6207.193064901534, 6207.534434917489, 6213.689315534115, 6111.266648002268, 6152.630842149485, 6158.815235542941, 6166.537509686702, 6166.876662466815, 6257.812265632038, 6279.0, 6286.654234017173, 6291.056341548621, 6293.5909165905905, 6054.212952316347, 6120.418560236771, 6130.0, 6136.907946380898, 6141.293751218667], "unit": "ms", "name": "prototype.html", "value": 4793.5940718579095}, {"lowerIsBetter": false, "replicates": [863.9438436501626, 893.6782758207045, 932.9486878221695, 946.6308139825464, 957.3059531839413, 14378.209198494078, 14826.925865370686, 14828.258587070657, 14839.480618178366, 14859.257037148154, 14092.43630254791, 14242.0, 14243.28783560823, 14247.71504569908, 14256.928715356435, 762.3520007993217, 774.543019618425, 914.0265617117765, 917.8439665256923, 932.3194068330123, 923.6813299411726, 924.9815003699922, 929.3958926697633, 929.9860502092474, 932.1005229953093, 794.1105961323298, 976.0532283684814, 978.2467500024976, 980.5832521178506, 981.1218959031662, 794.6185830801219, 963.051394376542, 969.6266937229148, 971.2327261463436, 972.9805403891918, 724.1491247783886, 724.3046675191822, 725.5030304241611, 726.0561270348578, 727.3635568877232, 14378.137311761328, 14746.04150730199, 14778.187199704013, 14789.482368117171, 14805.555833325017, 13307.201567905957, 13893.513727019606, 13926.867727191118, 13967.580972570839, 14282.143071415749, 11210.663680089528, 11346.375949322784, 11348.205625606211, 11534.884651153548, 11624.767504649859, 3835.8657446989223, 3838.1172330363893, 3863.0535518798074, 3874.2251549689895, 3928.666063384616, 52846.20730689043, 52863.999999999614, 54995.0, 55025.44974550243, 55069.724651376884, 11326.15053870963, 11374.715632109099, 11441.0, 11473.311601303782, 11530.538778448936, 21284.36146915595, 21318.893405532872, 21350.893245533673, 21351.893240533696, 21378.465538361666, 8579.399442039123, 8618.698345558001, 8664.306855451548, 8803.911960880438, 8853.247473964657, 4654.511276316006, 4840.782164802592, 4881.267809828483, 4883.95116048835, 4916.041371932439, 3253.365593709204, 3282.622498412665, 3290.2267967028206, 3293.390722716305, 3293.8682452701623, 514.9897002059938, 521.0256819747098, 524.3314773663533, 531.4632221456357, 541.5829811045473, 514.3776031002529, 642.1042645509488, 693.2235895796639, 698.833462838587, 712.3909057755648, 9442.90557094434, 9755.902440975642, 9872.746184127385, 9923.057309555581, 9995.100440960348, 652.3020368205974, 671.701093013614, 696.4428457234294, 748.8726916424221, 784.5645666655062, 4935.90128197434, 5074.492550744896, 5115.514026167508, 5184.403793563789, 5232.136697444879, 71865.28134718585, 72000.0, 72238.0, 72668.63665681638, 73167.79578396508, 61846.36152288596, 62256.377436225965, 63432.36567634265, 64405.0, 65056.02415963764, 57314.42685573091, 58376.41623583795, 58559.414405856245, 58917.410825891195, 59215.0, 9856.884192772393, 9986.650467233612, 10031.704253155574, 10210.540525676512, 10253.744695951096, 38081.619183808914, 38787.6121238784, 38979.610203897595, 42453.0, 42603.0, 54044.18933715919, 54345.45654543562, 54435.45564544304, 55210.447895522135, 55464.0, 8642.697505587274, 8937.73186804397, 8978.820423591622, 9189.632414703337, 9198.596519287907, 13938.79091813644, 14001.719965600834, 14015.159090454605, 14126.576202713935, 14311.57065288043, 12064.698382540506, 12691.93654031724, 12965.611031669065, 13075.280859552817, 13192.340382981005, 11487.885121148682, 12281.078919081283, 12610.180338277982, 12806.295653739135, 13315.46738130464, 12193.634190974284, 12290.692732681397, 12592.370381481072, 12925.612231633066, 13006.154599950973, 10242.334248273839, 10408.843867341844, 10536.420496872746, 11194.104471642411, 11249.100071994384, 10495.484985529045, 10907.890921090688, 10949.616763413242, 10990.670279891616, 10994.945025274823], "unit": "ms", "name": "yui.html", "value": 7054.947451796141}], "name": "dromaeo_css", "value": 7253.450536959922}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 109.0, 104.0, 109.0, 111.0, 111.0, 99.0, 110.0, 107.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 107.7}, {"lowerIsBetter": true, "replicates": [150.0, 125.0, 144.0, 161.0, 128.0, 145.0, 144.0, 121.0, 155.0, 126.0], "unit": "ms", "name": "audio-beat-detection", "value": 139.9}, {"lowerIsBetter": true, "replicates": [159.0, 160.0, 159.0, 161.0, 162.0, 159.0, 158.0, 161.0, 159.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 159.8}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 72.0, 140.0, 71.0, 72.0, 71.0, 71.0, 149.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 86.0}, {"lowerIsBetter": true, "replicates": [89.0, 89.0, 141.0, 89.0, 90.0, 90.0, 131.0, 90.0, 90.0, 137.0], "unit": "ms", "name": "audio-oscillator", "value": 103.6}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 110.0, 110.0, 110.0, 111.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.7}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.5}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [56.0, 56.0, 55.0, 54.0, 56.0, 55.0, 65.0, 54.0, 58.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 56.3}, {"lowerIsBetter": true, "replicates": [64.0, 62.0, 63.0, 62.0, 64.0, 63.0, 64.0, 63.0, 63.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.2}, {"lowerIsBetter": true, "replicates": [76.0, 75.0, 78.0, 77.0, 86.0, 78.0, 76.0, 78.0, 77.0, 79.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.0}, {"lowerIsBetter": true, "replicates": [117.0, 106.0, 107.0, 113.0, 112.0, 113.0, 115.0, 122.0, 116.0, 110.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 113.1}, {"lowerIsBetter": true, "replicates": [140.0, 134.0, 130.0, 140.0, 133.0, 134.0, 134.0, 132.0, 137.0, 134.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 134.8}, {"lowerIsBetter": true, "replicates": [63.0, 65.0, 62.0, 60.0, 60.0, 63.0, 61.0, 61.0, 61.0, 60.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.6}], "name": "kraken", "value": 1454.7999999999997}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.5001665001665], "unit": "score", "name": "Richards", "value": 212.02381199999994}, {"lowerIsBetter": false, "replicates": [170.47391749062393], "unit": "score", "name": "DeltaBlue", "value": 387.84818800000005}, {"lowerIsBetter": false, "replicates": [281.45229383619477], "unit": "score", "name": "Encrypt", "value": 945.7410929999998}, {"lowerIsBetter": false, "replicates": [5010.0], "unit": "score", "name": "Decrypt", "value": 53.12994011976049}, {"lowerIsBetter": false, "replicates": [937.207122774133], "unit": "score", "name": "RayTrace", "value": 789.568263}, {"lowerIsBetter": false, "replicates": [554.9389567147614], "unit": "score", "name": "Earley", "value": 1200.966326}, {"lowerIsBetter": false, "replicates": [10479.166666666666], "unit": "score", "name": "Boyer", "value": 63.59885487077532}, {"lowerIsBetter": false, "replicates": [23604.6511627907], "unit": "score", "name": "RegExp", "value": 38.593453201970455}, {"lowerIsBetter": false, "replicates": [539.3743257820928], "unit": "score", "name": "Splay", "value": 151.08431400000006}, {"lowerIsBetter": false, "replicates": [4812.5], "unit": "score", "name": "NavierStokes", "value": 308.3636363636364}], "name": "v8_7", "value": 23267.43573793594}]} 16:50:36 INFO - RETURN: dromaeo_css: 7253.45 16:50:36 INFO - RETURN: kraken: 1454.80 16:50:36 INFO - RETURN: v8_7: 23267.44 16:50:36 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23267.44"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1454.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7253.45"}}} 16:50:36 INFO - Return code: 0 16:50:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 16:50:36 INFO - # TBPL SUCCESS # 16:50:36 INFO - Running post-action listener: _resource_record_post_action 16:50:36 INFO - Running post-run listener: _resource_record_post_run 16:50:37 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 602112; Write bytes: 260673536; Read time: 120; Write time: 159060 16:50:37 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 1548288; Read time: 0; Write time: 92 16:50:37 INFO - run-tests - Wall time: 1207s; CPU: 13.0%; Read bytes: 602112; Write bytes: 259125248; Read time: 120; Write time: 158968 16:50:37 INFO - Running post-run listener: _upload_blobber_files 16:50:37 INFO - Blob upload gear active. 16:50:37 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 16:50:37 INFO - Copying logs to upload dir... 16:50:37 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1259.929341 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-01-13 16:50:37.172654) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-13 16:50:37.175996) ========= 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=cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009871 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452722854/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-13 16:50:37.231741) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:50:37.232493) ========= 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=cbe409c6de289db6ac707de3000001d4-1452731375.533227-1914684827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004464 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:50:37.286358) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-13 16:50:37.287160) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-13 16:50:37.287824) ========= ========= Total master_lag: 0.24 =========