builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1451456063.54 results: success (0) buildid: 20151229120438 builduid: 176f5e9233cb4dc0a01d3db20c9f99d7 revision: 078948864076 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:23.538550) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:23.538992) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:23.539295) ========= 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=0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766 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.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:23.579877) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:23.580333) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:23.635571) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:23.635867) ========= 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=0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004519 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:23.685057) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:23.685359) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:23.685744) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:23.686050) ========= 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=0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-29 22:14:23-- 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% 220M=0s 2015-12-29 22:14:24 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.195015 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:23.895046) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:23.895588) ========= 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=0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015462 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:23.945116) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:23.945509) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 078948864076 --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 078948864076 --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=0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-29 22:14:24,206 Setting DEBUG logging. 2015-12-29 22:14:24,206 attempt 1/10 2015-12-29 22:14:24,207 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/078948864076?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-29 22:14:24,620 unpacking tar archive at: mozilla-beta-078948864076/testing/mozharness/ program finished with exit code 0 elapsedTime=0.695988 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:24.654500) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:24.654823) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:24.664168) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:24.664532) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-29 22:14:24.664992) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2015-12-29 22:14:24.665283) ========= /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=0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:14:25 INFO - MultiFileLogger online at 20151229 22:14:25 in /builds/slave/test-pgo 22:14:25 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 22:14:25 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 22:14:25 INFO - {'append_to_log': False, 22:14:25 INFO - 'base_work_dir': '/builds/slave/test-pgo', 22:14:25 INFO - 'blob_upload_branch': 'Mozilla-Beta', 22:14:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 22:14:25 INFO - 'branch': 'Mozilla-Beta', 22:14:25 INFO - 'buildbot_json_path': 'buildprops.json', 22:14:25 INFO - 'config_files': ('talos/linux_config.py',), 22:14:25 INFO - 'default_actions': ('clobber', 22:14:25 INFO - 'read-buildbot-config', 22:14:25 INFO - 'download-and-extract', 22:14:25 INFO - 'populate-webroot', 22:14:25 INFO - 'create-virtualenv', 22:14:25 INFO - 'install', 22:14:25 INFO - 'run-tests'), 22:14:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:14:25 INFO - 'download_minidump_stackwalk': True, 22:14:25 INFO - 'download_symbols': 'ondemand', 22:14:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:14:25 INFO - 'tooltool.py': '/tools/tooltool.py', 22:14:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:14:25 INFO - '/tools/misc-python/virtualenv.py')}, 22:14:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:14:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:14:25 INFO - 'installer_path': 'installer.exe', 22:14:25 INFO - 'log_level': 'info', 22:14:25 INFO - 'log_name': 'talos', 22:14:25 INFO - 'log_to_console': True, 22:14:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:14:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:14:25 INFO - 'opt_config_files': (), 22:14:25 INFO - 'pip_index': False, 22:14:25 INFO - 'sps_profile': False, 22:14:25 INFO - 'sps_profile_interval': 0, 22:14:25 INFO - 'suite': 'dromaeojs', 22:14:25 INFO - 'system_bits': '32', 22:14:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:14:25 INFO - 'title': 'talos-linux64-ix-056', 22:14:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:14:25 INFO - 'use_talos_json': True, 22:14:25 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 22:14:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:14:25 INFO - 'work_dir': 'build'} 22:14:25 INFO - ##### 22:14:25 INFO - ##### Running clobber step. 22:14:25 INFO - ##### 22:14:25 INFO - Running pre-action listener: _resource_record_pre_action 22:14:25 INFO - Running main action method: clobber 22:14:25 INFO - rmtree: /builds/slave/test-pgo/build 22:14:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 22:14:25 INFO - Running post-action listener: _resource_record_post_action 22:14:25 INFO - ##### 22:14:25 INFO - ##### Running read-buildbot-config step. 22:14:25 INFO - ##### 22:14:25 INFO - Running pre-action listener: _resource_record_pre_action 22:14:25 INFO - Running main action method: read_buildbot_config 22:14:25 INFO - Using buildbot properties: 22:14:25 INFO - { 22:14:25 INFO - "properties": { 22:14:25 INFO - "buildnumber": 54, 22:14:25 INFO - "product": "firefox", 22:14:25 INFO - "script_repo_revision": "production", 22:14:25 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 22:14:25 INFO - "repository": "", 22:14:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 22:14:25 INFO - "buildid": "20151229120438", 22:14:25 INFO - "slavename": "talos-linux64-ix-056", 22:14:25 INFO - "pgo_build": "True", 22:14:25 INFO - "basedir": "/builds/slave/test-pgo", 22:14:25 INFO - "project": "", 22:14:25 INFO - "platform": "ubuntu64_hw", 22:14:25 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:14:25 INFO - "slavebuilddir": "test", 22:14:25 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 22:14:25 INFO - "branch": "mozilla-beta", 22:14:25 INFO - "repo_path": "releases/mozilla-beta", 22:14:25 INFO - "stage_platform": "linux64-pgo", 22:14:25 INFO - "builduid": "176f5e9233cb4dc0a01d3db20c9f99d7", 22:14:25 INFO - "revision": "078948864076" 22:14:25 INFO - }, 22:14:25 INFO - "sourcestamp": { 22:14:25 INFO - "repository": "", 22:14:25 INFO - "hasPatch": false, 22:14:25 INFO - "project": "", 22:14:25 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 22:14:25 INFO - "changes": [ 22:14:25 INFO - { 22:14:25 INFO - "category": null, 22:14:25 INFO - "files": [ 22:14:25 INFO - { 22:14:25 INFO - "url": null, 22:14:25 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/firefox-44.0.en-US.linux-x86_64.tar.bz2" 22:14:25 INFO - } 22:14:25 INFO - ], 22:14:25 INFO - "repository": "", 22:14:25 INFO - "rev": "078948864076", 22:14:25 INFO - "who": "sendchange", 22:14:25 INFO - "when": 1451456063, 22:14:25 INFO - "number": 7401791, 22:14:25 INFO - "comments": "Post Beta 4: disable EARLY_BETA_OR_EARLIER a=ritu", 22:14:25 INFO - "project": "", 22:14:25 INFO - "at": "Tue 29 Dec 2015 22:14:23", 22:14:25 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 22:14:25 INFO - "revlink": "", 22:14:25 INFO - "properties": [ 22:14:25 INFO - [ 22:14:25 INFO - "buildid", 22:14:25 INFO - "20151229120438", 22:14:25 INFO - "Change" 22:14:25 INFO - ], 22:14:25 INFO - [ 22:14:25 INFO - "builduid", 22:14:25 INFO - "176f5e9233cb4dc0a01d3db20c9f99d7", 22:14:25 INFO - "Change" 22:14:25 INFO - ], 22:14:25 INFO - [ 22:14:25 INFO - "pgo_build", 22:14:25 INFO - "True", 22:14:25 INFO - "Change" 22:14:25 INFO - ] 22:14:25 INFO - ], 22:14:25 INFO - "revision": "078948864076" 22:14:25 INFO - } 22:14:25 INFO - ], 22:14:25 INFO - "revision": "078948864076" 22:14:25 INFO - } 22:14:25 INFO - } 22:14:25 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/firefox-44.0.en-US.linux-x86_64.tar.bz2. 22:14:25 INFO - Running post-action listener: _resource_record_post_action 22:14:25 INFO - ##### 22:14:25 INFO - ##### Running download-and-extract step. 22:14:25 INFO - ##### 22:14:25 INFO - Running pre-action listener: _resource_record_pre_action 22:14:25 INFO - Running main action method: download_and_extract 22:14:25 INFO - mkdir: /builds/slave/test-pgo/build/tests 22:14:25 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')]} 22:14:25 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/test_packages.json 22:14:25 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 22:14:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 22:14:25 INFO - Downloaded 1270 bytes. 22:14:25 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 22:14:25 INFO - Using the following test package requirements: 22:14:25 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 22:14:25 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 22:14:25 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 22:14:25 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 22:14:25 INFO - u'jsshell-linux-x86_64.zip'], 22:14:25 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 22:14:25 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 22:14:25 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 22:14:25 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 22:14:25 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 22:14:25 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 22:14:25 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 22:14:25 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 22:14:25 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 22:14:25 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 22:14:25 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 22:14:25 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 22:14:25 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:14:25 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')]} 22:14:25 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/firefox-44.0.en-US.linux-x86_64.common.tests.zip 22:14:25 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/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 22:14:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/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 22:14:25 INFO - Downloaded 22571852 bytes. 22:14:25 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 22:14:25 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 22:14:25 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 22:14:26 INFO - Return code: 0 22:14:26 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 22:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:26 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/firefox-44.0.en-US.linux-x86_64.common.tests.zip 22:14:26 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/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 22:14:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/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 22:14:27 INFO - Downloaded 22571852 bytes. 22:14:27 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 22:14:27 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 22:14:27 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 22:14:28 INFO - Return code: 0 22:14: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')]} 22:14:28 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 22:14:28 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/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 22:14:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/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 22:14:28 INFO - Downloaded 11131970 bytes. 22:14:28 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 22:14:28 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 22:14:28 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 22:14:29 INFO - Return code: 0 22:14:29 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')]} 22:14:29 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/firefox-44.0.en-US.linux-x86_64.tar.bz2 22:14:29 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/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 22:14:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/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 22:14:29 INFO - Downloaded 56619486 bytes. 22:14:29 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/firefox-44.0.en-US.linux-x86_64.tar.bz2 22:14:29 INFO - mkdir: /builds/slave/test-pgo/properties 22:14:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 22:14:29 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 22:14:29 INFO - Contents: 22:14:29 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/firefox-44.0.en-US.linux-x86_64.tar.bz2 22:14:29 INFO - Running post-action listener: _resource_record_post_action 22:14:29 INFO - Running post-action listener: set_extra_try_arguments 22:14:29 INFO - ##### 22:14:29 INFO - ##### Running populate-webroot step. 22:14:29 INFO - ##### 22:14:29 INFO - Running pre-action listener: _resource_record_pre_action 22:14:29 INFO - Running main action method: populate_webroot 22:14:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:14:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:14:29 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 22:14:29 INFO - '../fennec_ids.txt'], 22:14:29 INFO - 'tests': ['tcheck2']}, 22:14:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:14:29 INFO - '--tppagecycles', 22:14:29 INFO - '7'], 22:14:29 INFO - 'tests': ['tsvgm']}}, 22:14:29 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:14:29 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:14:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:14:29 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:14:29 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:14:29 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:14:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:14:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:14:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:14:29 INFO - 'talos_options': ['--e10s'], 22:14:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:14:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:14:29 INFO - 'tests': ['damp', 'tps']}, 22:14:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:14:29 INFO - 'talos_options': ['--e10s'], 22:14:29 INFO - 'tests': ['damp', 'tps']}, 22:14:29 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:14:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:14:29 INFO - 'other': {'tests': ['a11yr', 22:14:29 INFO - 'ts_paint', 22:14:29 INFO - 'tpaint', 22:14:29 INFO - 'sessionrestore', 22:14:29 INFO - 'sessionrestore_no_auto_restore']}, 22:14:29 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:14:29 INFO - 'tests': ['a11yr', 22:14:29 INFO - 'ts_paint', 22:14:29 INFO - 'tpaint', 22:14:29 INFO - 'sessionrestore', 22:14:29 INFO - 'sessionrestore_no_auto_restore']}, 22:14:29 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:14:29 INFO - 'tests': ['a11yr', 22:14:29 INFO - 'ts_paint', 22:14:29 INFO - 'tpaint', 22:14:29 INFO - 'sessionrestore', 22:14:29 INFO - 'sessionrestore_no_auto_restore']}, 22:14:29 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:14:29 INFO - 'tests': ['a11yr', 22:14:29 INFO - 'ts_paint', 22:14:29 INFO - 'tpaint', 22:14:29 INFO - 'sessionrestore', 22:14:29 INFO - 'sessionrestore_no_auto_restore']}, 22:14:29 INFO - 'other_l64': {'tests': ['a11yr', 22:14:29 INFO - 'ts_paint', 22:14:29 INFO - 'tpaint', 22:14:29 INFO - 'sessionrestore', 22:14:29 INFO - 'sessionrestore_no_auto_restore']}, 22:14:29 INFO - 'other_nol64': {'tests': ['a11yr', 22:14:29 INFO - 'ts_paint', 22:14:29 INFO - 'tpaint', 22:14:29 INFO - 'sessionrestore', 22:14:29 INFO - 'sessionrestore_no_auto_restore']}, 22:14:29 INFO - 'svgr': {'tests': ['tsvgx', 22:14:29 INFO - 'tsvgr_opacity', 22:14:29 INFO - 'tart', 22:14:29 INFO - 'tscrollx', 22:14:29 INFO - 'cart']}, 22:14:29 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:14:29 INFO - 'tests': ['tsvgx', 22:14:29 INFO - 'tsvgr_opacity', 22:14:29 INFO - 'tart', 22:14:29 INFO - 'tscrollx', 22:14:29 INFO - 'cart']}, 22:14:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:14:29 INFO - 'tests': ['tp5o']}, 22:14:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:14:29 INFO - 'talos_options': ['--e10s'], 22:14:29 INFO - 'tests': ['tp5o']}, 22:14:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:14:29 INFO - 'talos_options': ['--xperf_path', 22:14:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:14:29 INFO - 'tests': ['tp5n']}, 22:14:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:14:29 INFO - 'talos_options': ['--e10s', 22:14:29 INFO - '--xperf_path', 22:14:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:14:29 INFO - 'tests': ['tp5n']}}, 22:14:29 INFO - 'talos.zip': {'path': '', 22:14:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:14:29 INFO - Running post-action listener: _resource_record_post_action 22:14:29 INFO - ##### 22:14:29 INFO - ##### Running create-virtualenv step. 22:14:29 INFO - ##### 22:14:29 INFO - Running pre-action listener: _resource_record_pre_action 22:14:29 INFO - Running main action method: create_virtualenv 22:14:29 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 22:14:29 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 22:14:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 22:14:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:14:29 INFO - Using real prefix '/usr' 22:14:29 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 22:14:30 INFO - Installing distribute.............................................................................................................................................................................................done. 22:14:32 INFO - Installing pip.................done. 22:14:32 INFO - Return code: 0 22:14:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 22:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:32 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 0x7fd09342af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1151350>, '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 0x1152250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11526e0>, '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': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', '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 22:14:32 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 22:14:32 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 22:14:32 INFO - Using env: {'DISPLAY': ':0', 22:14:32 INFO - 'HOME': '/home/cltbld', 22:14:32 INFO - 'LANG': 'en_US.UTF-8', 22:14:32 INFO - 'LANGUAGE': 'en_US:en', 22:14:32 INFO - 'LOGNAME': 'cltbld', 22:14:32 INFO - 'MAIL': '/var/mail/cltbld', 22:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:32 INFO - 'MOZ_NO_REMOTE': '1', 22:14:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:32 INFO - 'NO_EM_RESTART': '1', 22:14:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:14:32 INFO - 'PWD': '/builds/slave/test-pgo', 22:14:32 INFO - 'SHELL': '/bin/bash', 22:14:32 INFO - 'SHLVL': '1', 22:14:32 INFO - 'TERM': 'linux', 22:14:32 INFO - 'TMOUT': '86400', 22:14:32 INFO - 'USER': 'cltbld', 22:14:32 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', 22:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:32 INFO - '_': '/tools/buildbot/bin/python'} 22:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:32 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 22:14:32 INFO - Cleaning up... 22:14:32 INFO - Return code: 0 22:14:32 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 22:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:32 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 0x7fd09342af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1151350>, '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 0x1152250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11526e0>, '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': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', '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 22:14:32 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 22:14:32 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 22:14:32 INFO - Using env: {'DISPLAY': ':0', 22:14:32 INFO - 'HOME': '/home/cltbld', 22:14:32 INFO - 'LANG': 'en_US.UTF-8', 22:14:32 INFO - 'LANGUAGE': 'en_US:en', 22:14:32 INFO - 'LOGNAME': 'cltbld', 22:14:32 INFO - 'MAIL': '/var/mail/cltbld', 22:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:32 INFO - 'MOZ_NO_REMOTE': '1', 22:14:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:32 INFO - 'NO_EM_RESTART': '1', 22:14:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:14:32 INFO - 'PWD': '/builds/slave/test-pgo', 22:14:32 INFO - 'SHELL': '/bin/bash', 22:14:32 INFO - 'SHLVL': '1', 22:14:32 INFO - 'TERM': 'linux', 22:14:32 INFO - 'TMOUT': '86400', 22:14:32 INFO - 'USER': 'cltbld', 22:14:32 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', 22:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:32 INFO - '_': '/tools/buildbot/bin/python'} 22:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:32 INFO - Downloading/unpacking psutil>=0.7.1 22:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:34 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 22:14:34 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 22:14:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 22:14:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:14:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:14:34 INFO - Installing collected packages: psutil 22:14:34 INFO - Running setup.py install for psutil 22:14:34 INFO - building 'psutil._psutil_linux' extension 22:14:34 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 22:14:34 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 22:14:34 INFO - building 'psutil._psutil_posix' extension 22:14:34 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 22:14:34 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 22:14:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:14:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:14:35 INFO - Successfully installed psutil 22:14:35 INFO - Cleaning up... 22:14:35 INFO - Return code: 0 22:14:35 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 22:14:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:35 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 0x7fd09342af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1151350>, '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 0x1152250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11526e0>, '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': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', '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 22:14:35 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 22:14:35 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 22:14:35 INFO - Using env: {'DISPLAY': ':0', 22:14:35 INFO - 'HOME': '/home/cltbld', 22:14:35 INFO - 'LANG': 'en_US.UTF-8', 22:14:35 INFO - 'LANGUAGE': 'en_US:en', 22:14:35 INFO - 'LOGNAME': 'cltbld', 22:14:35 INFO - 'MAIL': '/var/mail/cltbld', 22:14:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:35 INFO - 'MOZ_NO_REMOTE': '1', 22:14:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:35 INFO - 'NO_EM_RESTART': '1', 22:14:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:14:35 INFO - 'PWD': '/builds/slave/test-pgo', 22:14:35 INFO - 'SHELL': '/bin/bash', 22:14:35 INFO - 'SHLVL': '1', 22:14:35 INFO - 'TERM': 'linux', 22:14:35 INFO - 'TMOUT': '86400', 22:14:35 INFO - 'USER': 'cltbld', 22:14:35 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', 22:14:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:35 INFO - '_': '/tools/buildbot/bin/python'} 22:14:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:37 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:14:37 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 22:14:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:14:37 INFO - Installing collected packages: mozsystemmonitor 22:14:37 INFO - Running setup.py install for mozsystemmonitor 22:14:37 INFO - Successfully installed mozsystemmonitor 22:14:37 INFO - Cleaning up... 22:14:37 INFO - Return code: 0 22:14:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 22:14: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')]} 22:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14: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')]} 22:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:37 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 0x7fd09342af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1151350>, '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 0x1152250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11526e0>, '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': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', '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 22:14:37 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 22:14:37 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 22:14:37 INFO - Using env: {'DISPLAY': ':0', 22:14:37 INFO - 'HOME': '/home/cltbld', 22:14:37 INFO - 'LANG': 'en_US.UTF-8', 22:14:37 INFO - 'LANGUAGE': 'en_US:en', 22:14:37 INFO - 'LOGNAME': 'cltbld', 22:14:37 INFO - 'MAIL': '/var/mail/cltbld', 22:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:37 INFO - 'MOZ_NO_REMOTE': '1', 22:14:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:37 INFO - 'NO_EM_RESTART': '1', 22:14:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:14:37 INFO - 'PWD': '/builds/slave/test-pgo', 22:14:37 INFO - 'SHELL': '/bin/bash', 22:14:37 INFO - 'SHLVL': '1', 22:14:37 INFO - 'TERM': 'linux', 22:14:37 INFO - 'TMOUT': '86400', 22:14:37 INFO - 'USER': 'cltbld', 22:14:37 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', 22:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:37 INFO - '_': '/tools/buildbot/bin/python'} 22:14:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:37 INFO - Downloading/unpacking blobuploader==1.2.4 22:14:37 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 22:14:37 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 22:14:37 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 22:14:37 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 22:14:37 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 22:14:37 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 22:14:39 INFO - Downloading blobuploader-1.2.4.tar.gz 22:14:39 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 22:14:39 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:14:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:14:39 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 22:14:39 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 22:14:39 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 22:14:39 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 22:14:39 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 22:14:39 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 22:14:39 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 22:14:39 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 22:14:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:14:39 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 22:14:39 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 22:14:39 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 22:14:39 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 22:14:39 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 22:14:39 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 22:14:39 INFO - Downloading docopt-0.6.1.tar.gz 22:14:39 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 22:14:39 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 22:14:40 INFO - Installing collected packages: blobuploader, requests, docopt 22:14:40 INFO - Running setup.py install for blobuploader 22:14:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:14:40 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 22:14:40 INFO - Running setup.py install for requests 22:14:40 INFO - Running setup.py install for docopt 22:14:40 INFO - Successfully installed blobuploader requests docopt 22:14:40 INFO - Cleaning up... 22:14:40 INFO - Return code: 0 22:14:40 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:14: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')]} 22:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14: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')]} 22:14:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:40 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 0x7fd09342af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1151350>, '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 0x1152250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11526e0>, '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': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', '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 22:14:40 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 22:14:40 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 22:14:40 INFO - Using env: {'DISPLAY': ':0', 22:14:40 INFO - 'HOME': '/home/cltbld', 22:14:40 INFO - 'LANG': 'en_US.UTF-8', 22:14:40 INFO - 'LANGUAGE': 'en_US:en', 22:14:40 INFO - 'LOGNAME': 'cltbld', 22:14:40 INFO - 'MAIL': '/var/mail/cltbld', 22:14:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:40 INFO - 'MOZ_NO_REMOTE': '1', 22:14:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:40 INFO - 'NO_EM_RESTART': '1', 22:14:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:14:40 INFO - 'PWD': '/builds/slave/test-pgo', 22:14:40 INFO - 'SHELL': '/bin/bash', 22:14:40 INFO - 'SHLVL': '1', 22:14:40 INFO - 'TERM': 'linux', 22:14:40 INFO - 'TMOUT': '86400', 22:14:40 INFO - 'USER': 'cltbld', 22:14:40 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', 22:14:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:40 INFO - '_': '/tools/buildbot/bin/python'} 22:14:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:14:40 INFO - Running setup.py (path:/tmp/pip-ayLTGW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:14:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:14:40 INFO - Running setup.py (path:/tmp/pip-CbmAqU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:14:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:14:41 INFO - Running setup.py (path:/tmp/pip-ii9wCK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:14:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:14:41 INFO - Running setup.py (path:/tmp/pip-1G3F7R-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:14:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:14:41 INFO - Running setup.py (path:/tmp/pip-5FmYeU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:14:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:14:41 INFO - Running setup.py (path:/tmp/pip-Gdpuzx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:14:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:14:41 INFO - Running setup.py (path:/tmp/pip-_B7ym0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:14:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:14:41 INFO - Running setup.py (path:/tmp/pip-DcaxZ6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:14:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:14:41 INFO - Running setup.py (path:/tmp/pip-AOOVaI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:14:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:14:41 INFO - Running setup.py (path:/tmp/pip-eLPjH5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:14:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:14:41 INFO - Running setup.py (path:/tmp/pip-nwE5np-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:14:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:14:41 INFO - Running setup.py (path:/tmp/pip-KPOMmM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:14:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:14:41 INFO - Running setup.py (path:/tmp/pip-AOJm9P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:14:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:14:41 INFO - Running setup.py (path:/tmp/pip-0dnCuW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:14:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:14:41 INFO - Running setup.py (path:/tmp/pip-TQ4H65-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:14:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:14:41 INFO - Running setup.py (path:/tmp/pip-YxzZfh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:14:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:14:41 INFO - Running setup.py (path:/tmp/pip-FYAT4i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:14:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:14:42 INFO - Running setup.py install for manifestparser 22:14:42 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 22:14:42 INFO - Running setup.py install for mozcrash 22:14:42 INFO - Running setup.py install for mozdebug 22:14:42 INFO - Running setup.py install for mozdevice 22:14:42 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 22:14:42 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 22:14:42 INFO - Running setup.py install for mozfile 22:14:42 INFO - Running setup.py install for mozhttpd 22:14:42 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 22:14:42 INFO - Running setup.py install for mozinfo 22:14:42 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 22:14:42 INFO - Running setup.py install for mozInstall 22:14:42 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 22:14:42 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 22:14:42 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 22:14:42 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 22:14:42 INFO - Running setup.py install for mozleak 22:14:42 INFO - Running setup.py install for mozlog 22:14:43 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 22:14:43 INFO - Running setup.py install for moznetwork 22:14:43 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 22:14:43 INFO - Running setup.py install for mozprocess 22:14:43 INFO - Running setup.py install for mozprofile 22:14:43 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 22:14:43 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 22:14:43 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 22:14:43 INFO - Running setup.py install for mozrunner 22:14:43 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 22:14:43 INFO - Running setup.py install for mozscreenshot 22:14:43 INFO - Running setup.py install for moztest 22:14:43 INFO - Running setup.py install for mozversion 22:14:43 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 22:14:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:14:43 INFO - Cleaning up... 22:14:43 INFO - Return code: 0 22:14:43 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:14: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')]} 22:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14: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')]} 22:14:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:43 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 0x7fd09342af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1151350>, '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 0x1152250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11526e0>, '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': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', '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 22:14:43 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 22:14:43 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 22:14:43 INFO - Using env: {'DISPLAY': ':0', 22:14:43 INFO - 'HOME': '/home/cltbld', 22:14:43 INFO - 'LANG': 'en_US.UTF-8', 22:14:43 INFO - 'LANGUAGE': 'en_US:en', 22:14:43 INFO - 'LOGNAME': 'cltbld', 22:14:43 INFO - 'MAIL': '/var/mail/cltbld', 22:14:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:43 INFO - 'MOZ_NO_REMOTE': '1', 22:14:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:43 INFO - 'NO_EM_RESTART': '1', 22:14:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:14:43 INFO - 'PWD': '/builds/slave/test-pgo', 22:14:43 INFO - 'SHELL': '/bin/bash', 22:14:43 INFO - 'SHLVL': '1', 22:14:43 INFO - 'TERM': 'linux', 22:14:43 INFO - 'TMOUT': '86400', 22:14:43 INFO - 'USER': 'cltbld', 22:14:43 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', 22:14:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:43 INFO - '_': '/tools/buildbot/bin/python'} 22:14:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:14:44 INFO - Running setup.py (path:/tmp/pip-ZzgTpe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:14:44 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)) 22:14:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:14:44 INFO - Running setup.py (path:/tmp/pip-OB_0Yi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:14:44 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)) 22:14:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:14:44 INFO - Running setup.py (path:/tmp/pip-zEzBiF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:14:44 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)) 22:14:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:14:44 INFO - Running setup.py (path:/tmp/pip-YnvmJI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:14:44 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)) 22:14:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:14:44 INFO - Running setup.py (path:/tmp/pip-kDFayq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:14:44 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)) 22:14:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:14:44 INFO - Running setup.py (path:/tmp/pip-IH7PTo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:14:44 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)) 22:14:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:14:44 INFO - Running setup.py (path:/tmp/pip-ON_xa3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:14:44 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)) 22:14:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:14:44 INFO - Running setup.py (path:/tmp/pip-hQsmn_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:14:44 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)) 22:14:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:14:44 INFO - Running setup.py (path:/tmp/pip-SiDF0k-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:14:44 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)) 22:14:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:14:44 INFO - Running setup.py (path:/tmp/pip-3n1MB3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:14:44 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)) 22:14:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:14:44 INFO - Running setup.py (path:/tmp/pip-W_Eyi5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:14:44 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)) 22:14:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:14:44 INFO - Running setup.py (path:/tmp/pip-8ANDsS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:14:44 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)) 22:14:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:14:44 INFO - Running setup.py (path:/tmp/pip-JskXRf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:14:44 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)) 22:14:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:14:44 INFO - Running setup.py (path:/tmp/pip-IpZf_h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:14:45 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)) 22:14:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:14:45 INFO - Running setup.py (path:/tmp/pip-3GaZEE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:14:45 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)) 22:14:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:14:45 INFO - Running setup.py (path:/tmp/pip-0L26Lu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:14:45 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)) 22:14:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:14:45 INFO - Running setup.py (path:/tmp/pip-edi6ko-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:14:45 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)) 22:14:45 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)) 22:14:45 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)) 22:14:45 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)) 22:14:45 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)) 22:14:45 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)) 22:14:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 22:14:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:47 INFO - Downloading blessings-1.5.1.tar.gz 22:14:47 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 22:14:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 22:14:47 INFO - Installing collected packages: blessings 22:14:47 INFO - Running setup.py install for blessings 22:14:47 INFO - Successfully installed blessings 22:14:47 INFO - Cleaning up... 22:14:47 INFO - Return code: 0 22:14:47 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 22:14:47 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:14:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:14:47 INFO - Reading from file tmpfile_stdout 22:14:47 INFO - Current package versions: 22:14:47 INFO - argparse == 1.2.1 22:14:47 INFO - blessings == 1.5.1 22:14:47 INFO - blobuploader == 1.2.4 22:14:47 INFO - docopt == 0.6.1 22:14:47 INFO - manifestparser == 1.1 22:14:47 INFO - mozInstall == 1.12 22:14:47 INFO - mozcrash == 0.16 22:14:47 INFO - mozdebug == 0.1 22:14:47 INFO - mozdevice == 0.46 22:14:47 INFO - mozfile == 1.2 22:14:47 INFO - mozhttpd == 0.7 22:14:47 INFO - mozinfo == 0.8 22:14:47 INFO - mozleak == 0.1 22:14:47 INFO - mozlog == 3.0 22:14:47 INFO - moznetwork == 0.27 22:14:47 INFO - mozprocess == 0.22 22:14:47 INFO - mozprofile == 0.27 22:14:47 INFO - mozrunner == 6.11 22:14:47 INFO - mozscreenshot == 0.1 22:14:47 INFO - mozsystemmonitor == 0.0 22:14:47 INFO - moztest == 0.7 22:14:47 INFO - mozversion == 1.4 22:14:47 INFO - psutil == 3.1.1 22:14:47 INFO - requests == 1.2.3 22:14:47 INFO - wsgiref == 0.1.2 22:14:47 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:14:47 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')]} 22:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:47 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')]} 22:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:47 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 0x7fd09342af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1151350>, '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 0x1152250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11526e0>, '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': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', '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 22:14:47 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 22:14:47 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 22:14:47 INFO - Using env: {'DISPLAY': ':0', 22:14:47 INFO - 'HOME': '/home/cltbld', 22:14:47 INFO - 'LANG': 'en_US.UTF-8', 22:14:47 INFO - 'LANGUAGE': 'en_US:en', 22:14:47 INFO - 'LOGNAME': 'cltbld', 22:14:47 INFO - 'MAIL': '/var/mail/cltbld', 22:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:47 INFO - 'MOZ_NO_REMOTE': '1', 22:14:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:47 INFO - 'NO_EM_RESTART': '1', 22:14:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:14:47 INFO - 'PWD': '/builds/slave/test-pgo', 22:14:47 INFO - 'SHELL': '/bin/bash', 22:14:47 INFO - 'SHLVL': '1', 22:14:47 INFO - 'TERM': 'linux', 22:14:47 INFO - 'TMOUT': '86400', 22:14:47 INFO - 'USER': 'cltbld', 22:14:47 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', 22:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:47 INFO - '_': '/tools/buildbot/bin/python'} 22:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:47 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)) 22:14:47 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)) 22:14:47 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)) 22:14:47 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)) 22:14:47 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)) 22:14:47 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)) 22:14:47 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)) 22:14:47 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)) 22:14:47 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)) 22:14:47 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)) 22:14:47 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)) 22:14:47 INFO - Cleaning up... 22:14:47 INFO - Return code: 0 22:14:47 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 22:14:47 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')]} 22:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:47 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')]} 22:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:47 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 0x7fd09342af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1151350>, '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 0x1152250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11526e0>, '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': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', '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 22:14:47 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 22:14:47 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 22:14:47 INFO - Using env: {'DISPLAY': ':0', 22:14:47 INFO - 'HOME': '/home/cltbld', 22:14:47 INFO - 'LANG': 'en_US.UTF-8', 22:14:47 INFO - 'LANGUAGE': 'en_US:en', 22:14:47 INFO - 'LOGNAME': 'cltbld', 22:14:47 INFO - 'MAIL': '/var/mail/cltbld', 22:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:47 INFO - 'MOZ_NO_REMOTE': '1', 22:14:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:47 INFO - 'NO_EM_RESTART': '1', 22:14:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:14:47 INFO - 'PWD': '/builds/slave/test-pgo', 22:14:47 INFO - 'SHELL': '/bin/bash', 22:14:47 INFO - 'SHLVL': '1', 22:14:47 INFO - 'TERM': 'linux', 22:14:47 INFO - 'TMOUT': '86400', 22:14:47 INFO - 'USER': 'cltbld', 22:14:47 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', 22:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:47 INFO - '_': '/tools/buildbot/bin/python'} 22:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:47 INFO - Downloading/unpacking jsonschema 22:14:47 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 22:14:47 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 22:14:47 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 22:14:47 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 22:14:47 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 22:14:47 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 22:14:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:14:49 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 22:14:49 INFO - Downloading/unpacking functools32 (from jsonschema) 22:14:49 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 22:14:49 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 22:14:49 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 22:14:49 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 22:14:49 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 22:14:49 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 22:14:49 INFO - Downloading functools32-3.2.3-2.tar.gz 22:14:49 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 22:14:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 22:14:49 INFO - warning: no files found matching '*.txt' 22:14:49 INFO - no previously-included directories found matching 'build' 22:14:49 INFO - no previously-included directories found matching 'dist' 22:14:49 INFO - no previously-included directories found matching '.git*' 22:14:49 INFO - Installing collected packages: jsonschema, functools32 22:14:49 INFO - Running setup.py install for functools32 22:14:49 INFO - warning: no files found matching '*.txt' 22:14:49 INFO - no previously-included directories found matching 'build' 22:14:49 INFO - no previously-included directories found matching 'dist' 22:14:49 INFO - no previously-included directories found matching '.git*' 22:14:50 INFO - Successfully installed jsonschema functools32 22:14:50 INFO - Cleaning up... 22:14:50 INFO - Return code: 0 22:14:50 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 22:14:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:50 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 0x7fd09342af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1151350>, '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 0x1152250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11526e0>, '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': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', '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 22:14:50 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 22:14:50 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 22:14:50 INFO - Using env: {'DISPLAY': ':0', 22:14:50 INFO - 'HOME': '/home/cltbld', 22:14:50 INFO - 'LANG': 'en_US.UTF-8', 22:14:50 INFO - 'LANGUAGE': 'en_US:en', 22:14:50 INFO - 'LOGNAME': 'cltbld', 22:14:50 INFO - 'MAIL': '/var/mail/cltbld', 22:14:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:50 INFO - 'MOZ_NO_REMOTE': '1', 22:14:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:50 INFO - 'NO_EM_RESTART': '1', 22:14:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:14:50 INFO - 'PWD': '/builds/slave/test-pgo', 22:14:50 INFO - 'SHELL': '/bin/bash', 22:14:50 INFO - 'SHLVL': '1', 22:14:50 INFO - 'TERM': 'linux', 22:14:50 INFO - 'TMOUT': '86400', 22:14:50 INFO - 'USER': 'cltbld', 22:14:50 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', 22:14:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:50 INFO - '_': '/tools/buildbot/bin/python'} 22:14:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:50 INFO - Downloading/unpacking flake8 22:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:14:51 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 22:14:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:14:51 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 22:14:51 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 22:14:51 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 22:14:51 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 22:14:51 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 22:14:51 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 22:14:52 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:14:52 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 22:14:52 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:14: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 22:14: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 22:14: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 22:14: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 22:14: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 22:14: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 22:14:52 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 22:14:52 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:14: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 22:14: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 22:14: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 22:14: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 22:14: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 22:14: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 22:14:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 22:14:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 22:14:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:14:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:14:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:14:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:14:52 INFO - no previously-included directories found matching 'docs/_build' 22:14:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:14:52 INFO - Running setup.py install for pep8 22:14:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:14:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:14:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:14:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:14:52 INFO - no previously-included directories found matching 'docs/_build' 22:14:52 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 22:14:52 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:14:52 INFO - Cleaning up... 22:14:52 INFO - Return code: 0 22:14:52 INFO - Running post-action listener: _resource_record_post_action 22:14:52 INFO - Running post-action listener: _start_resource_monitoring 22:14:52 INFO - Starting resource monitoring. 22:14:52 INFO - ##### 22:14:52 INFO - ##### Running install step. 22:14:52 INFO - ##### 22:14:52 INFO - Running pre-action listener: _resource_record_pre_action 22:14:52 INFO - Running main action method: install 22:14:52 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:14:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:14:53 INFO - Reading from file tmpfile_stdout 22:14:53 INFO - Detecting whether we're running mozinstall >=1.0... 22:14:53 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 22:14:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 22:14:53 INFO - Reading from file tmpfile_stdout 22:14:53 INFO - Output received: 22:14:53 INFO - Usage: mozinstall [options] installer 22:14:53 INFO - Options: 22:14:53 INFO - -h, --help show this help message and exit 22:14:53 INFO - -d DEST, --destination=DEST 22:14:53 INFO - Directory to install application into. [default: 22:14:53 INFO - "/builds/slave/test-pgo"] 22:14:53 INFO - --app=APP Application being installed. [default: firefox] 22:14:53 INFO - mkdir: /builds/slave/test-pgo/build/application 22:14:53 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'] 22:14:53 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 22:15:08 INFO - Reading from file tmpfile_stdout 22:15:08 INFO - Output received: 22:15:08 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 22:15:08 INFO - Running post-action listener: _resource_record_post_action 22:15:08 INFO - ##### 22:15:08 INFO - ##### Running run-tests step. 22:15:08 INFO - ##### 22:15:08 INFO - Running pre-action listener: _resource_record_pre_action 22:15:08 INFO - Running main action method: run_tests 22:15:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 22:15:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 22:15:08 INFO - Python 2.7.3 22:15:08 INFO - Return code: 0 22:15:08 INFO - grabbing minidump binary from tooltool 22:15:08 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')]} 22:15:08 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 0x1151350>, '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 0x1152250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11526e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 22:15:08 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 22:15:08 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 22:15:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:15:08 INFO - Return code: 0 22:15:08 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 22:15:08 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 22:15:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 22:15:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 22:15:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 22:15:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 22:15:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 22:15:09 INFO - Return code: 0 22:15:09 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/1451419478/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 22:15:09 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/1451419478/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost 22:15:09 INFO - Using env: {'DISPLAY': ':0', 22:15:09 INFO - 'HOME': '/home/cltbld', 22:15:09 INFO - 'LANG': 'en_US.UTF-8', 22:15:09 INFO - 'LANGUAGE': 'en_US:en', 22:15:09 INFO - 'LOGNAME': 'cltbld', 22:15:09 INFO - 'MAIL': '/var/mail/cltbld', 22:15:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:15:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 22:15:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:15:09 INFO - 'MOZ_NO_REMOTE': '1', 22:15:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:15:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:15:09 INFO - 'NO_EM_RESTART': '1', 22:15:09 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:15:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:15:09 INFO - 'PWD': '/builds/slave/test-pgo', 22:15:09 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 22:15:09 INFO - 'SHELL': '/bin/bash', 22:15:09 INFO - 'SHLVL': '1', 22:15:09 INFO - 'TERM': 'linux', 22:15:09 INFO - 'TMOUT': '86400', 22:15:09 INFO - 'USER': 'cltbld', 22:15:09 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766', 22:15:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:15:09 INFO - '_': '/tools/buildbot/bin/python'} 22:15:09 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/1451419478/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost'] with output_timeout 3600 22:15:09 INFO - mozversion INFO | application_buildid: 20151229120438 22:15:09 INFO - mozversion INFO | application_changeset: 0789488640762cf6c601f33b801d290e1861f70e 22:15:09 INFO - mozversion INFO | application_display_name: Nightly 22:15:09 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:15:09 INFO - mozversion INFO | application_name: Firefox 22:15:09 INFO - mozversion INFO | application_remotingname: firefox 22:15:09 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 22:15:09 INFO - mozversion INFO | application_vendor: Mozilla 22:15:09 INFO - mozversion INFO | application_version: 44.0 22:15:09 INFO - mozversion INFO | platform_buildid: 20151229120438 22:15:09 INFO - mozversion INFO | platform_changeset: 0789488640762cf6c601f33b801d290e1861f70e 22:15:09 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 22:15:09 INFO - mozversion INFO | platform_version: 44.0 22:15:09 INFO - 2015-12-29 22:15:09,654 DEBUG : using testdate: 1451456109 22:15:09 INFO - 2015-12-29 22:15:09,654 DEBUG : actual date: 1451456109 22:15:09 INFO - 2015-12-29 22:15:09,732 INFO : starting webserver on 'localhost:55295' 22:15:09 INFO - 2015-12-29 22:15:09,734 INFO : Starting test suite talos-linux64-ix-056 22:15:09 INFO - 2015-12-29 22:15:09,734 INFO : Starting test dromaeo_css 22:15:09 INFO - 2015-12-29 22:15:09,734 DEBUG : operating with platform_type : linux_ 22:15:09 INFO - 2015-12-29 22:15:09,735 INFO : Initialising browser for dromaeo_css test... 22:15:09 INFO - 2015-12-29 22:15:09,750 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpuSHLwC/profile http://localhost:55295/getInfo.html 22:15:13 INFO - 2015-12-29 22:15:13,794 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:15:13 INFO - 2015-12-29 22:15:13,794 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:15:13 INFO - 2015-12-29 22:15:13,794 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:15:13 INFO - 2015-12-29 22:15:13,794 DEBUG : BROWSER_OUTPUT: __metrics 22:15:13 INFO - 2015-12-29 22:15:13,931 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] 22:15:15 INFO - 2015-12-29 22:15:15,277 INFO : Browser initialized. 22:15:15 INFO - 2015-12-29 22:15:15,278 INFO : Running cycle 1/1 for dromaeo_css test... 22:15:15 INFO - 2015-12-29 22:15:15,278 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpuSHLwC/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:15:26 INFO - 2015-12-29 22:15:26,808 DEBUG : BROWSER_OUTPUT: RSS: Main: 128372736 22:15:26 INFO - 2015-12-29 22:15:26,809 DEBUG : BROWSER_OUTPUT: 22:16:17 INFO - 2015-12-29 22:16:17,960 DEBUG : BROWSER_OUTPUT: 1451456177958 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] 22:18:25 INFO - 2015-12-29 22:18:25,972 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55295/tests/dromaeo/cssquery-ext.html) 22:18:27 INFO - 2015-12-29 22:18:27,041 DEBUG : BROWSER_OUTPUT: RSS: Main: 205115392 22:18:27 INFO - 2015-12-29 22:18:27,041 DEBUG : BROWSER_OUTPUT: 22:21:29 INFO - 2015-12-29 22:21:29,091 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/dromaeo/cssquery-ext.html (next: http://localhost:55295/tests/dromaeo/cssquery-jquery.html) 22:21:29 INFO - 2015-12-29 22:21:29,652 DEBUG : BROWSER_OUTPUT: RSS: Main: 210993152 22:21:29 INFO - 2015-12-29 22:21:29,653 DEBUG : BROWSER_OUTPUT: 22:24:31 INFO - 2015-12-29 22:24:31,362 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55295/tests/dromaeo/cssquery-mootools.html) 22:24:31 INFO - 2015-12-29 22:24:31,915 DEBUG : BROWSER_OUTPUT: RSS: Main: 203022336 22:24:31 INFO - 2015-12-29 22:24:31,915 DEBUG : BROWSER_OUTPUT: 22:27:33 INFO - 2015-12-29 22:27:33,587 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55295/tests/dromaeo/cssquery-prototype.html) 22:27:34 INFO - 2015-12-29 22:27:34,156 DEBUG : BROWSER_OUTPUT: RSS: Main: 203579392 22:27:34 INFO - 2015-12-29 22:27:34,157 DEBUG : BROWSER_OUTPUT: 22:30:36 INFO - 2015-12-29 22:30:36,434 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55295/tests/dromaeo/cssquery-yui.html) 22:30:36 INFO - 2015-12-29 22:30:36,987 DEBUG : BROWSER_OUTPUT: RSS: Main: 195342336 22:30:36 INFO - 2015-12-29 22:30:36,987 DEBUG : BROWSER_OUTPUT: 22:33:39 INFO - 2015-12-29 22:33:39,138 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/dromaeo/cssquery-yui.html (next: http://localhost:55295/tests/dromaeo/cssquery-dojo.html) 22:33:39 INFO - 2015-12-29 22:33:39,388 DEBUG : BROWSER_OUTPUT: RSS: Main: 220090368 22:33:39 INFO - 2015-12-29 22:33:39,388 DEBUG : BROWSER_OUTPUT: 22:33:39 INFO - 2015-12-29 22:33:39,389 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:33:39 INFO - 2015-12-29 22:33:39,389 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:33:39 INFO - 2015-12-29 22:33:39,390 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:33:39 INFO - 2015-12-29 22:33:39,390 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:33:39 INFO - 2015-12-29 22:33:39,391 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3289.161263877711;3421.982890085549;3422.8117453540062;3447.965520344796;3471.1148657092444;5616.073347897592;5640;5655.1517272409155;5678.318601767794;5683.31800183978;5653.773849046033;5673.205751194836;5676.120201368794;5680.8295751127425;5693.658380497162;4923.18767403378;5122.35970503688;5170.896582068375;5234.6859188448625;5264.447233040533;5152.072626927152;5168.8707782305555;5170.172772356424;5176.197689358156;5179.663321884084;5124.92312615311;5125.410577783566;5156.53591176794;5159.303494028314;5239.607029472786;5142.382914050319;5214.32213812202;5222;5224.425313215544;5228.241904923765;5098.796048158069;5145.845624631267;5177.611679124043;5208.348956380452;5234.42421333653;5251.973740131313;5378.946210537883;5452.2094296327205;5527.55779537636;5595.328560572717;6067.575269731121;6073.089036644495;6085.026395776655;6087;6089.847753806146;6076.817695469143;6081.452669259744;6098.847528811771;6107.358727333611;6115.1744514490865;5465.043617366985;5522.22688823565;5578.135389014709;5598.936202121666;5631.802886898939;6162.907556386658;6172.9074063889075;6176.752929882844;6180.567360284737;6182.1345011698395;5732.910746958065;6016.187814644967;6023.427774361428;6023.819285421444;6032.276126864805;5938.346781853993;6017.458428741435;6113.816585502442;6126.509879209652;6141.324454310112;6121.960940613755;6129.632222066691;6171.321154672983;6172.259328880563;6173.660448675276;6143.416375444325;6143.539234557426;6145.108959200891;6147.108669242934;6148.846278843064;4494.910101797946;4519.096180763795;4576.588107070313;4619.284010978304;4633.930491042637;4138.02756352257;4178.686598505064;4197.4753155855515;4204.873853784391;4217.599328063829;5532.17017447378;5768.019436695772;5781.913271300934;5782.161586569924;5789.6526208426785;6018.307894592091;6044.486218671462;6082.969585152135;6122;6131.6321020738005;5806.738696758572;5807.477327040587;5813;5813.970930145407;5816.098504731774;5146.511081447256;5248.239005344204;5316.3354580677415;5329.493698098674;5340.439253878365;6130.260541069943;6217.067439884054;6222.471089957314;6225.346338634468;6228.750849966042;6077.848053798691;6081.331053584191;6082.665453400105;6088.360722124113;6152.569320147547;6019.217501724747;6136.447719705248;6165.630062196283;6235.844103897439;6247.781327653511;6007.939920600913;6049.243844519435;6084.665343406155;6087.178230938944;6089.817305480843;6163.322034576283;6174.8147555573405;6176.227971503562;6185.041318595445;6188.1027251049245;5702.40124786908;5812.82561523155;5861.912071318849;5978.4021597839865;6012.87974240504;5909.320428150818;5918.822435326947;5927.911081333697;5934.495567876779;5942.6731529766275;5830.883382332415;5851.795187168429;5857.7949771757785;5858.502027327725;5858.765649373977;5654.85862853432;5752.654840709571;5797.594168408171;5926.170336153028;5933.970330148321;5882.470577648032;5933.258342707161;5968.432998865101;5974.044152935509;5982.222311099529 22:33:39 INFO - 2015-12-29 22:33:39,391 DEBUG : BROWSER_OUTPUT: |1;ext.html;600.2376981233833;639.0956796133469;640.9839754006149;643.4466358931318;645.5448908519494;33845.66154338456;42389.364159537596;42700.359494607605;42748.78625606876;42754.57245427545;46310.76844615772;46736.2989555157;46889.53110468894;46922.76538617311;46950;8227.053888802775;8360.289375403068;9086.909130908673;9273.72178834636;10499.790004199911;10593.470326483684;10741.355518668866;10744.623938162165;10745.14038876888;10748.946255268713;10605.946970265139;10749.140068794517;10760.192985526117;10764.13886889051;10766.084882784973;10402.791944161112;10472.790544189149;10499;10506.842397364046;10507.68476945689;10471.528781204865;10656.360618362924;10694.46527673621;10713.142948564097;10713.517891694892;42726;43324.566754332685;43417.34873976893;43432.56567434348;43454.34818477726;3857.0550215197177;3870.845166193349;4099.959000409987;4218.240716670998;4342;32031.359372812414;32145.6785432145;32478.512822307683;32766.180845478815;32877.34245315104;3836.693064554829;3874.1864208516245;3900.4149377593303;3915.569287378386;3919.647231749156;309521;312177;314299;314334.4283278592;314935;57253.71373143191;58132;66056.33943660502;68681.31318686849;69065;64607.353926460135;64616.353836461974;64921.67539162274;64963;64977.675111624136;17653.38213162559;17658.293668253384;17823.73264401035;17892.9105354475;17900;13279.734405312032;13339.466421343232;13343.933280333536;13379.13035652699;13512.324383780968;22191.889040554695;22435.439114021952;22474.66288005681;22580.435489112904;22632.642041477236;8090.676372945016;8280.378971577156;8368.958155209306;8401.411901166983;8427.199416055464;1010.5907107621425;1019.704285757137;1146.5700362364114;1147.9196456247982;1147.9942600286945;30223.848880755897;30249.848750756544;30322.393552129273;30820.537691934638;31043.37913241768;1126.6000569797768;1135.7728454309172;1146.363768108691;1157.7366248983217;1237.0845574275152;13622;15653.060816351055;15842.920785396;15957.521274361789;16400.75398869018;352628;356442;356924;358859;359887;59219.703901480214;59260.40739592635;59323.70338148368;59364;59579.404205957384;1402.6843960108895;1421.6801219725685;1495.6859059597607;1507.0505581483594;1538.446159382599;28156.85921570379;28444.715552844205;28663.42673146567;29125.563116552847;29133.417331653673;53399.733001334745;54481;54518;54533.72733136309;54545.181822273495;235857;237723;238003;239154;239946;33174.33651326912;33212.833935831135;33283.167920802174;33483;33771.493427599096;46602.76698616621;47107.05785884332;47688;47993;48424.7578762118;39121;39762.40356394598;40005;40279.25511992745;40727.389089163094;38058;38715.612843872324;39124.41313380359;39943.2011359777;40149.397759033054;36421;36636.26727465489;36895.262094758495;38736.6126338733;39206.80396597999;32193;34040.65959340375;35090.473642895886;35097;36210;26536.469270614867;27107.457850843268;27140.893299007566;27233.455330893667;27290.45419091647 22:33:39 INFO - 2015-12-29 22:33:39,392 DEBUG : BROWSER_OUTPUT: |2;jquery.html;365.18017051718897;367.23065178451145;369.2670049950849;369.3997254461501;373.71597585834775;14606.342714577842;16573.751393729104;16884.57788555285;17987;18637.25450981961;18138.727919081226;18191.81808181918;18365.632687346246;18426;18573.257069717194;5520.99999999999;5645.774169033234;5651.06757385031;5656.123300888348;5670.518005969477;4304.892377690567;4436.401085853401;5577.3028371453565;6099.542534309922;6267.561270711052;6050.334463209044;6160.445559899608;6180.351063138373;6208.379162083778;6215.471684906788;6142.723577439004;6176.84557886052;6200.162977997958;6203.658798766066;6205.782797602084;4444.422225110748;5441.673499590037;5444.591655625804;5444.91832622511;5463.726813659301;14147.504837330644;14150.575482735534;14280.07179533327;14417.78373324401;14418.495352662607;2188.540406514634;2210.1822325739417;2210.502636906699;2213.424509627492;2362.4802543440414;10256;10282.537285822082;10447.477626118663;10632.308899921558;10705.83941240882;1762.9118544072874;1815.056170791181;1858.5353661584604;1868.7196920461904;1884.302807961063;65355.67322163406;68613.65693171552;68899;71258;72121;7515.511491753128;7545.094588649287;7571.053618297713;7618.657160427683;7636.961815190888;9055.917139058054;9062.36563440566;9076.41003334781;9076.8638470423;9079.273658107335;6113.296970848321;6133.877322453526;6181.721822517998;6186.226721659793;6189.96905015472;6298.559100862895;6312.52656050798;6426.550141490145;6457.580257283262;6520.771772987922;3465.5148279240925;3527.6295988921306;3536.6463353664944;3545.3263879862743;3547.840347184383;1199;1252.5678640868887;3091.1962889648403;3180.4911214205617;3250.3499300140065;468.7117422784963;484.5179046848338;503.26272011503323;518.3054706693049;529.9549538289211;11364.715882103013;12239.510419583296;12356.196847204903;12398.13213075076;12493.375331233583;523.6046784677544;525.1466367153375;528.5480913818707;529.9072662284085;530.4006472685841;6715.563488373338;6771.593704377753;6820.727170913207;6863.176418829772;6926.722931082801;658329;668839;679303;690265;705549;82524;85784;86950.56524717463;87377;87765.12234877697;575.807104619957;580.4862696513532;581.1312088427827;581.3895409819672;581.9243498345188;11694;11845.170838041255;11896.405179741152;11975.161738678209;11991.160618756603;39159.80420097881;39254.80372598119;39664.60335396609;41489.37765933574;41531.792341038104;49975;51482.742586288325;51557.74221128871;51768;51902;7251.347378735728;7281.927180728125;7334.449916256195;7378.262173782793;7442;12690.809637855624;12782.68043298925;12991.610251692464;12994.220346779224;12999.610011699664;11246;11343.886561134614;11475.019589561465;11557.422128893353;11632.534698612299;11594.072474202212;11811.527538898348;11999.880001199876;12129;12148.271103733805;10323;10414.47927603632;10771.89228107709;11494.425278736197;11927.284362937904;7223.747168849065;7272.636368181464;7281.344678978918;7322.487425880137;7340.559566426032;11064.612738554111;11562.364069975896;11917.880821192024;12101.878981210075;12159.817602736142 22:33:39 INFO - 2015-12-29 22:33:39,394 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1518.3243456661783;1523.542937118864;1753.6317373351596;1830.569816093218;1861.7114347276172;8083.312918401935;8279.006519217688;8297.253247207751;8301.501909885395;8315.792105197374;7206.207317195104;7474.177840437547;7766.339861111798;7978.720744773934;8146.348292136629;2306.388806966151;2330.6387509935985;2352;2356.0458014504065;2357.446000189954;2012;2337.123578658003;2340.2979106268053;2396.8921398537022;2397;2029.6042271757012;2267.1271560449263;2295.116380193621;2300.2639155470256;2300.850444721096;2245.685604015447;2263.2757517594378;2271.3980795089315;2275.8748268845206;2275.9089636414524;2321.7910388065156;2330.2193765088714;2363.1138323128826;2364.8226383021342;2372.4187574044468;8111.594420278962;8158.959205203995;8166.142555031695;8170.877436838393;8179.509229446193;4873.610111191097;4926.679765815211;4940.258961155855;4947.332110165117;4949.232868905326;3065.800722953001;3078.350762353306;3080.907572772798;3107.0989413070183;3121.7502599792188;1000.3997601439152;1002.4686915934566;1007.6372505897871;1031.6440827914357;1032.4889179855945;10819.83770243447;10835.079018283455;10851.565937362497;10865.782684346348;10899.945500272448;4413.1394378095965;4423.358613001097;4504.887377815581;4506.909861802746;4508.752018638941;9170.541472926327;9179.393606118903;9210.401323913924;9279.350445468752;9325.626974920928;4821.614270858393;4870.732109733928;4885.975570122127;4915.336429582032;4920.581750551171;4471.172833025836;4494.0337827367275;4536.591706746409;4542.477615074309;4545.2954792007295;3948.447217389568;3952.3676211805973;3979.641832235113;3991.022199561068;4013.4381186633896;1079.6005477973042;1147.3861484105964;1260.4391045984448;1261.3125846413727;1265.4558539827779;1012.099231683802;1021.8518314844306;1098.3739268616812;1225.3566877389442;1395.762720337545;7408.2591740826565;7424.034875466263;7425.368843648243;7494.812629684193;7511.586862722494;1206.957756478519;1211.3156066822216;1214.5081242096965;1215.1918973882446;1224.4306397525193;5810.099434587646;5886.1465087562065;5896.262967129109;5897.056470964625;5902.498287645512;9302.90697093034;9825.115739583473;9827.705168844803;9834.360766550151;9845;9722.173615242642;9741.9025809741;9764.755881103029;9767;9781.16860066888;7466.925330746623;7636.770896873103;7639.809004774925;7655.157932627407;7658.65536050879;7289.052423184952;7327.194008659149;7336.559806411632;7365.926340736738;7367.889481657672;8733.737987860375;8744.737657870273;8749.825003500022;8762.824743505222;8769.517676527852;8840.734777956672;9039.502827344559;9054.185123338932;9055.094490550891;9079.727608171765;7517.323440890346;7610.505317154367;7613.733519326797;7615.771526854203;7632.847343052997;8239.382046346429;8276.420650554403;8333.624986875484;8340.624671889658;8350.290225330915;7038.049863268393;7702.768916932501;7756.495827771177;7772.57250851186;7773.41699372538;6994.2656021117045;7580.924190758022;7655.6937722490475;7683.0780306363595;7705.959695441268;7097.7160913562875;7132.1441427029085;7141.107361579802;7145.821354465972;7151.928480715126;6790.694418751069;6816.0457535946;6816.454683625199;6841.760538381132;7068.893966590608;6873.793786186222;6890.10428644273;6903.75836845708;6905.067815844997;6906.447484201352 22:33:39 INFO - 2015-12-29 22:33:39,395 DEBUG : BROWSER_OUTPUT: |4;prototype.html;338.86445421831263;340.77679120176276;343.74906318387593;346.9843857026434;352.88707613563656;5941.851453713659;5996.8500787480225;6001.819945401634;6006.849828754278;6008.609440386377;5686.715664216783;5753.194552762606;5777.942220577793;5882.970585147068;5890.116482527623;4575.450945886481;4608.331791890174;4617.37665415168;4626.537346265381;4629.513901040393;4610.815567377301;4616.676832621718;4622.052479241747;4624.28323609841;4624.976875115636;4436.0240747035605;4497.595216430521;4511.70673906195;4558.772061396933;4572.40558727365;4531.705439146445;4532.614727748145;4543.273076307792;4545.11370282795;4551.385562948993;4019.517657881073;4025.63769260765;4031.093004074089;4032.9798351008058;4033.69747268956;5578.609497335188;5616;5625.156226566007;5627.831165065055;5628.831135065954;5207.661502002357;5304.655197412156;5305.681659100466;5308.283381743492;5310.017646735328;6251.624902505819;6253.749850005995;6254.655993920333;6254.812355629293;6257.217847769029;4448.555144485558;4448.911021779546;4451.399061126739;4460;4466;6372.458341041017;6425.903611445832;6486.610803351814;6490.740370385226;6507.44686701631;4632.2125238709505;4632.420947381577;4636.11913736389;4636.6058884995155;4708.505606911293;4746.952530474651;4764.880877978009;4765.26138448541;4768.046390721869;4773.236282194832;5422.810201642924;5423.403425623178;5424.376196737426;5425.864353391197;5427.050266203399;6297;6395.488360931115;6409.615423074631;6413.647249401328;6418.037294405876;1658.0466231916696;1701.7107091794178;1708.4532949456054;1710.6835235481476;1710.9230084646222;1129.6215767717742;1156.2195518025467;1165.143619439711;1180.6103985684704;1185.7569198314497;6149.96925015381;6214.130021796952;6222.439980401786;6232.8130156095385;6237.438630523275;6560.835979100465;6566.179227596551;6578.6052836828985;6587.110740050032;6587.868242635216;5982.820515384546;6098.237720284965;6104.603200792023;6217.036359364306;6229.221347331583;3609.097725568608;3624.4200927851416;3628.0385697790107;3633.94549081764;3634.1096431373953;6723.5293529452465;6726.7981960541265;6730.394264516119;6734.494912881455;6738.7304507819135;6560.212774466999;6692.631905245258;6702.128723266042;6704.899426508607;6706.798796036127;6757.662116894136;6786.287439818847;6791.456683465311;6796.76211332601;6797.218319893177;6568.6058836469965;6617.536772425884;6629.701663425254;6638.236602790741;6644.867102658017;6431.5819471734185;6565.113709649137;6567.934320656732;6568.080468734476;6572.605643661395;6757.493188010821;6788.185417749902;6813.863722725617;6819.079424277659;6820.488463365168;4746.311784791255;4747.762611869462;4756.762161891822;4758.547937945889;4758.928616070831;6478.481721462366;6537.607743535403;6561.3438656134;6579.901301480386;6635.933640663725;6387.137736405712;6388.616682999035;6395.904061438989;6396.520260980538;6401.359864013748;6365.904511432424;6410.358964103552;6412.070249813844;6422.036694495863;6423.132877061724;6261.24865016201;6384.808455746335;6394.136791533084;6397.104405383343;6397.712102955285;6438.871222575429;6492.058651495412;6497.772577959749;6500.772472963424;6503.44720698727;6353.428191462791;6358.650274234961;6359.141515895295;6360.427561519486;6360.459360954185 22:33:39 INFO - 2015-12-29 22:33:39,398 DEBUG : BROWSER_OUTPUT: |5;yui.html;762.1540090499544;879.2087121590572;893.1381217125474;894.5571941888766;911.8586619074038;13037.934810325947;13430.194188348705;13495.662608434794;13509.121907076033;13545.255010974404;13148.474061037547;13182.934085329583;13212.867871321283;13272.800907986388;13292.800607990865;756.4213376766778;764.4954330142107;922.6678395777532;932.911373419524;946.0823001688351;911.5943405184695;923.0769230769231;931.0131260863473;933.4679232837285;934.1965909317981;972.8589354543587;981.5239608789731;984.7882705218377;989.8168838764816;990.7126933189367;796.7808852565532;806.1938061938062;965.8937516873174;981.5582987655583;982.7690492734201;726.2700985509582;727.745289148799;728.1517032657001;731.4221764805797;731.7731503234014;13256.072074954756;13420.396082176228;13444.462221511225;13444.5294414696;13466.057375983684;11480.655580332603;12106.39468026604;12201.328926909016;12282.631521054383;12329.383530823423;10487.318324308895;10552.366857988469;10630.893691063067;10704.411257380842;10835.91640835918;3629.618890016563;3658.70730341572;3671.192337685705;3684.3552378333684;3789.2421515696697;51330;51393.743031284605;51412.485875141145;51817.48182518202;53393.19910201389;9842.950785246028;9904.900950990543;9944.204463642891;9991.150752186;10006.599736010623;20023.59952800965;20083.59832803365;20096.497587560134;20111.597768044558;20115.39653810388;6790.422814060761;6847.315268473113;6945.826354341182;6955.02629631852;7107.502474826712;4556.2937744649635;4647.395838540968;4649.5815376616265;4680.719156850531;4707.576318131385;2923.956140657892;2948.6903875093235;2952.9704702952695;2955.3055032067878;2957.5267957126757;459.82066993872417;466.62902992121343;471.77590644443615;473.10582998133555;474.3643517686317;634.8888944434798;653.2291895563282;676.864627074587;684.8767221900006;709.4537206351081;8846.203841654282;8894.155055269739;8987.77530561728;9130.680426185052;9163.404378715362;539.051269765216;553.8366181976327;625.9593126446766;676.5906626491001;714.4998501049287;4337.392765012901;4354.716943398669;4366.257736184856;4464.441944756905;4477.910441791211;61375;68690.3130968684;68743.3125668747;68888.6555567229;69014.65492672505;55939;56178.719106404205;56559;56672.43327566754;57224.71387643035;50357.6981337704;50718.239226411635;50864;51565;53914.19128713072;9253.352265341362;9353.39202951806;9431.952840235756;9613.134817866425;9622.90377096248;38890.80554597209;39754.40368394418;39930.80034599808;41309.58690413057;41944;48368;50227.74886125546;53449.73275133755;53645.463545364044;53750;7589.58257295854;8290.046644635944;8395.95802020986;8574.742757717278;8638.17937295956;11771.823422648838;12294.877051229374;12701.809472857729;13276.203427794364;13597.932010339884;11480.82778758336;11601.303921764722;11874.584389546324;12303.93848030754;12373.690657733268;11973.521059157536;12115.515379384726;12155.453004614637;12269.141160118706;12484.438200280827;10195.541200645841;11380.829287560528;11490.94254528722;11699.415029248334;12133.393330333625;9650;10046.799064018533;10057.727905480004;10249.692509224735;10546.947265263625;9922.305438619227;9936.900630993887;9944.016794961628;9957.253206009433;10267.640632577824 22:33:39 INFO - 2015-12-29 22:33:39,398 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:33:39 INFO - 2015-12-29 22:33:39,399 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:33:39 INFO - 2015-12-29 22:33:39,399 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,817 22:33:39 INFO - 2015-12-29 22:33:39,399 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:33:39 INFO - 2015-12-29 22:33:39,399 DEBUG : BROWSER_OUTPUT: __startTimestamp1451457219388__endTimestamp 22:33:39 INFO - 2015-12-29 22:33:39,399 DEBUG : BROWSER_OUTPUT: 22:33:39 INFO - 2015-12-29 22:33:39,399 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:33:39 INFO - 2015-12-29 22:33:39,399 DEBUG : BROWSER_OUTPUT: Number of tests: 6 22:33:39 INFO - 2015-12-29 22:33:39,400 DEBUG : BROWSER_OUTPUT: 22:33:39 INFO - 2015-12-29 22:33:39,400 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5637.85 Median:5851.80 stddev:627.91 (10.7%) stddev-sans-first:602.20 22:33:39 INFO - 2015-12-29 22:33:39,400 DEBUG : BROWSER_OUTPUT: Values: 3289.2 3422.0 3422.8 3448.0 3471.1 5616.1 5640.0 5655.2 5678.3 5683.3 5653.8 5673.2 5676.1 5680.8 5693.7 4923.2 5122.4 5170.9 5234.7 5264.4 5152.1 5168.9 5170.2 5176.2 5179.7 5124.9 5125.4 5156.5 5159.3 5239.6 5142.4 5214.3 5222.0 5224.4 5228.2 5098.8 5145.8 5177.6 5208.3 5234.4 5252.0 5378.9 5452.2 5527.6 5595.3 6067.6 6073.1 6085.0 6087.0 6089.8 6076.8 6081.5 6098.8 6107.4 6115.2 5465.0 5522.2 5578.1 5598.9 5631.8 6162.9 6172.9 6176.8 6180.6 6182.1 5732.9 6016.2 6023.4 6023.8 6032.3 5938.3 6017.5 6113.8 6126.5 6141.3 6122.0 6129.6 6171.3 6172.3 6173.7 6143.4 6143.5 6145.1 6147.1 6148.8 4494.9 4519.1 4576.6 4619.3 4633.9 4138.0 4178.7 4197.5 4204.9 4217.6 5532.2 5768.0 5781.9 5782.2 5789.7 6018.3 6044.5 6083.0 6122.0 6131.6 5806.7 5807.5 5813.0 5814.0 5816.1 5146.5 5248.2 5316.3 5329.5 5340.4 6130.3 6217.1 6222.5 6225.3 6228.8 6077.8 6081.3 6082.7 6088.4 6152.6 6019.2 6136.4 6165.6 6235.8 6247.8 6007.9 6049.2 6084.7 6087.2 6089.8 6163.3 6174.8 6176.2 6185.0 6188.1 5702.4 5812.8 5861.9 5978.4 6012.9 5909.3 5918.8 5927.9 5934.5 5942.7 5830.9 5851.8 5857.8 5858.5 5858.8 5654.9 5752.7 5797.6 5926.2 5934.0 5882.5 5933.3 5968.4 5974.0 5982.2 22:33:39 INFO - 2015-12-29 22:33:39,400 DEBUG : BROWSER_OUTPUT: 22:33:39 INFO - 2015-12-29 22:33:39,400 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:49271.28 Median:33627.25 stddev:80119.12 (238.3%) stddev-sans-first:80260.53 22:33:39 INFO - 2015-12-29 22:33:39,400 DEBUG : BROWSER_OUTPUT: Values: 600.2 639.1 641.0 643.4 645.5 33845.7 42389.4 42700.4 42748.8 42754.6 46310.8 46736.3 46889.5 46922.8 46950.0 8227.1 8360.3 9086.9 9273.7 10499.8 10593.5 10741.4 10744.6 10745.1 10748.9 10605.9 10749.1 10760.2 10764.1 10766.1 10402.8 10472.8 10499.0 10506.8 10507.7 10471.5 10656.4 10694.5 10713.1 10713.5 42726.0 43324.6 43417.3 43432.6 43454.3 3857.1 3870.8 4100.0 4218.2 4342.0 32031.4 32145.7 32478.5 32766.2 32877.3 3836.7 3874.2 3900.4 3915.6 3919.6 309521.0 312177.0 314299.0 314334.4 314935.0 57253.7 58132.0 66056.3 68681.3 69065.0 64607.4 64616.4 64921.7 64963.0 64977.7 17653.4 17658.3 17823.7 17892.9 17900.0 13279.7 13339.5 13343.9 13379.1 13512.3 22191.9 22435.4 22474.7 22580.4 22632.6 8090.7 8280.4 8369.0 8401.4 8427.2 1010.6 1019.7 1146.6 1147.9 1148.0 30223.8 30249.8 30322.4 30820.5 31043.4 1126.6 1135.8 1146.4 1157.7 1237.1 13622.0 15653.1 15842.9 15957.5 16400.8 352628.0 356442.0 356924.0 358859.0 359887.0 59219.7 59260.4 59323.7 59364.0 59579.4 1402.7 1421.7 1495.7 1507.1 1538.4 28156.9 28444.7 28663.4 29125.6 29133.4 53399.7 54481.0 54518.0 54533.7 54545.2 235857.0 237723.0 238003.0 239154.0 239946.0 33174.3 33212.8 33283.2 33483.0 33771.5 46602.8 47107.1 47688.0 47993.0 48424.8 39121.0 39762.4 40005.0 40279.3 40727.4 38058.0 38715.6 39124.4 39943.2 40149.4 36421.0 36636.3 36895.3 38736.6 39206.8 32193.0 34040.7 35090.5 35097.0 36210.0 26536.5 27107.5 27140.9 27233.5 27290.5 22:33:39 INFO - 2015-12-29 22:33:39,400 DEBUG : BROWSER_OUTPUT: 22:33:39 INFO - 2015-12-29 22:33:39,400 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:32267.71 Median:25239.13 stddev:111461.97 (441.6%) stddev-sans-first:111748.90 22:33:39 INFO - 2015-12-29 22:33:39,402 DEBUG : BROWSER_OUTPUT: Values: 365.2 367.2 369.3 369.4 373.7 14606.3 16573.8 16884.6 17987.0 18637.3 18138.7 18191.8 18365.6 18426.0 18573.3 5521.0 5645.8 5651.1 5656.1 5670.5 4304.9 4436.4 5577.3 6099.5 6267.6 6050.3 6160.4 6180.4 6208.4 6215.5 6142.7 6176.8 6200.2 6203.7 6205.8 4444.4 5441.7 5444.6 5444.9 5463.7 14147.5 14150.6 14280.1 14417.8 14418.5 2188.5 2210.2 2210.5 2213.4 2362.5 10256.0 10282.5 10447.5 10632.3 10705.8 1762.9 1815.1 1858.5 1868.7 1884.3 65355.7 68613.7 68899.0 71258.0 72121.0 7515.5 7545.1 7571.1 7618.7 7637.0 9055.9 9062.4 9076.4 9076.9 9079.3 6113.3 6133.9 6181.7 6186.2 6190.0 6298.6 6312.5 6426.6 6457.6 6520.8 3465.5 3527.6 3536.6 3545.3 3547.8 1199.0 1252.6 3091.2 3180.5 3250.3 468.7 484.5 503.3 518.3 530.0 11364.7 12239.5 12356.2 12398.1 12493.4 523.6 525.1 528.5 529.9 530.4 6715.6 6771.6 6820.7 6863.2 6926.7 658329.0 668839.0 679303.0 690265.0 705549.0 82524.0 85784.0 86950.6 87377.0 87765.1 575.8 580.5 581.1 581.4 581.9 11694.0 11845.2 11896.4 11975.2 11991.2 39159.8 39254.8 39664.6 41489.4 41531.8 49975.0 51482.7 51557.7 51768.0 51902.0 7251.3 7281.9 7334.4 7378.3 7442.0 12690.8 12782.7 12991.6 12994.2 12999.6 11246.0 11343.9 11475.0 11557.4 11632.5 11594.1 11811.5 11999.9 12129.0 12148.3 10323.0 10414.5 10771.9 11494.4 11927.3 7223.7 7272.6 7281.3 7322.5 7340.6 11064.6 11562.4 11917.9 12101.9 12159.8 22:33:39 INFO - 2015-12-29 22:33:39,402 DEBUG : BROWSER_OUTPUT: 22:33:39 INFO - 2015-12-29 22:33:39,402 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5703.30 Median:6803.37 stddev:2950.05 (43.4%) stddev-sans-first:2941.56 22:33:39 INFO - 2015-12-29 22:33:39,402 DEBUG : BROWSER_OUTPUT: Values: 1518.3 1523.5 1753.6 1830.6 1861.7 8083.3 8279.0 8297.3 8301.5 8315.8 7206.2 7474.2 7766.3 7978.7 8146.3 2306.4 2330.6 2352.0 2356.0 2357.4 2012.0 2337.1 2340.3 2396.9 2397.0 2029.6 2267.1 2295.1 2300.3 2300.9 2245.7 2263.3 2271.4 2275.9 2275.9 2321.8 2330.2 2363.1 2364.8 2372.4 8111.6 8159.0 8166.1 8170.9 8179.5 4873.6 4926.7 4940.3 4947.3 4949.2 3065.8 3078.4 3080.9 3107.1 3121.8 1000.4 1002.5 1007.6 1031.6 1032.5 10819.8 10835.1 10851.6 10865.8 10899.9 4413.1 4423.4 4504.9 4506.9 4508.8 9170.5 9179.4 9210.4 9279.4 9325.6 4821.6 4870.7 4886.0 4915.3 4920.6 4471.2 4494.0 4536.6 4542.5 4545.3 3948.4 3952.4 3979.6 3991.0 4013.4 1079.6 1147.4 1260.4 1261.3 1265.5 1012.1 1021.9 1098.4 1225.4 1395.8 7408.3 7424.0 7425.4 7494.8 7511.6 1207.0 1211.3 1214.5 1215.2 1224.4 5810.1 5886.1 5896.3 5897.1 5902.5 9302.9 9825.1 9827.7 9834.4 9845.0 9722.2 9741.9 9764.8 9767.0 9781.2 7466.9 7636.8 7639.8 7655.2 7658.7 7289.1 7327.2 7336.6 7365.9 7367.9 8733.7 8744.7 8749.8 8762.8 8769.5 8840.7 9039.5 9054.2 9055.1 9079.7 7517.3 7610.5 7613.7 7615.8 7632.8 8239.4 8276.4 8333.6 8340.6 8350.3 7038.0 7702.8 7756.5 7772.6 7773.4 6994.3 7580.9 7655.7 7683.1 7706.0 7097.7 7132.1 7141.1 7145.8 7151.9 6790.7 6816.0 6816.5 6841.8 7068.9 6873.8 6890.1 6903.8 6905.1 6906.4 22:33:39 INFO - 2015-12-29 22:33:39,402 DEBUG : BROWSER_OUTPUT: 22:33:39 INFO - 2015-12-29 22:33:39,402 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5350.55 Median:6053.42 stddev:1582.20 (26.1%) stddev-sans-first:1541.27 22:33:39 INFO - 2015-12-29 22:33:39,402 DEBUG : BROWSER_OUTPUT: Values: 338.9 340.8 343.7 347.0 352.9 5941.9 5996.9 6001.8 6006.8 6008.6 5686.7 5753.2 5777.9 5883.0 5890.1 4575.5 4608.3 4617.4 4626.5 4629.5 4610.8 4616.7 4622.1 4624.3 4625.0 4436.0 4497.6 4511.7 4558.8 4572.4 4531.7 4532.6 4543.3 4545.1 4551.4 4019.5 4025.6 4031.1 4033.0 4033.7 5578.6 5616.0 5625.2 5627.8 5628.8 5207.7 5304.7 5305.7 5308.3 5310.0 6251.6 6253.7 6254.7 6254.8 6257.2 4448.6 4448.9 4451.4 4460.0 4466.0 6372.5 6425.9 6486.6 6490.7 6507.4 4632.2 4632.4 4636.1 4636.6 4708.5 4747.0 4764.9 4765.3 4768.0 4773.2 5422.8 5423.4 5424.4 5425.9 5427.1 6297.0 6395.5 6409.6 6413.6 6418.0 1658.0 1701.7 1708.5 1710.7 1710.9 1129.6 1156.2 1165.1 1180.6 1185.8 6150.0 6214.1 6222.4 6232.8 6237.4 6560.8 6566.2 6578.6 6587.1 6587.9 5982.8 6098.2 6104.6 6217.0 6229.2 3609.1 3624.4 3628.0 3633.9 3634.1 6723.5 6726.8 6730.4 6734.5 6738.7 6560.2 6692.6 6702.1 6704.9 6706.8 6757.7 6786.3 6791.5 6796.8 6797.2 6568.6 6617.5 6629.7 6638.2 6644.9 6431.6 6565.1 6567.9 6568.1 6572.6 6757.5 6788.2 6813.9 6819.1 6820.5 4746.3 4747.8 4756.8 4758.5 4758.9 6478.5 6537.6 6561.3 6579.9 6635.9 6387.1 6388.6 6395.9 6396.5 6401.4 6365.9 6410.4 6412.1 6422.0 6423.1 6261.2 6384.8 6394.1 6397.1 6397.7 6438.9 6492.1 6497.8 6500.8 6503.4 6353.4 6358.7 6359.1 6360.4 6360.5 22:33:39 INFO - 2015-12-29 22:33:39,403 DEBUG : BROWSER_OUTPUT: 22:33:39 INFO - 2015-12-29 22:33:39,403 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14916.80 Median:51097.00 stddev:18183.65 (35.6%) stddev-sans-first:18203.59 22:33:39 INFO - 2015-12-29 22:33:39,404 DEBUG : BROWSER_OUTPUT: Values: 762.2 879.2 893.1 894.6 911.9 13037.9 13430.2 13495.7 13509.1 13545.3 13148.5 13182.9 13212.9 13272.8 13292.8 756.4 764.5 922.7 932.9 946.1 911.6 923.1 931.0 933.5 934.2 972.9 981.5 984.8 989.8 990.7 796.8 806.2 965.9 981.6 982.8 726.3 727.7 728.2 731.4 731.8 13256.1 13420.4 13444.5 13444.5 13466.1 11480.7 12106.4 12201.3 12282.6 12329.4 10487.3 10552.4 10630.9 10704.4 10835.9 3629.6 3658.7 3671.2 3684.4 3789.2 51330.0 51393.7 51412.5 51817.5 53393.2 9843.0 9904.9 9944.2 9991.2 10006.6 20023.6 20083.6 20096.5 20111.6 20115.4 6790.4 6847.3 6945.8 6955.0 7107.5 4556.3 4647.4 4649.6 4680.7 4707.6 2924.0 2948.7 2953.0 2955.3 2957.5 459.8 466.6 471.8 473.1 474.4 634.9 653.2 676.9 684.9 709.5 8846.2 8894.2 8987.8 9130.7 9163.4 539.1 553.8 626.0 676.6 714.5 4337.4 4354.7 4366.3 4464.4 4477.9 61375.0 68690.3 68743.3 68888.7 69014.7 55939.0 56178.7 56559.0 56672.4 57224.7 50357.7 50718.2 50864.0 51565.0 53914.2 9253.4 9353.4 9432.0 9613.1 9622.9 38890.8 39754.4 39930.8 41309.6 41944.0 48368.0 50227.7 53449.7 53645.5 53750.0 7589.6 8290.0 8396.0 8574.7 8638.2 11771.8 12294.9 12701.8 13276.2 13597.9 11480.8 11601.3 11874.6 12303.9 12373.7 11973.5 12115.5 12155.5 12269.1 12484.4 10195.5 11380.8 11490.9 11699.4 12133.4 9650.0 10046.8 10057.7 10249.7 10546.9 9922.3 9936.9 9944.0 9957.3 10267.6 22:33:39 INFO - 2015-12-29 22:33:39,404 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:33:39 INFO - 2015-12-29 22:33:39,404 DEBUG : BROWSER_OUTPUT: 22:33:39 INFO - 2015-12-29 22:33:39,968 INFO : Browser exited with error code: 0 22:33:39 INFO - 2015-12-29 22:33:39,984 INFO : Completed test dromaeo_css (00:18:30) 22:33:39 INFO - 2015-12-29 22:33:39,985 INFO : Starting test kraken 22:33:39 INFO - 2015-12-29 22:33:39,985 DEBUG : operating with platform_type : linux_ 22:33:39 INFO - 2015-12-29 22:33:39,985 INFO : Initialising browser for kraken test... 22:33:39 INFO - 2015-12-29 22:33:39,991 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp29Nvpj/profile http://localhost:55295/getInfo.html 22:33:43 INFO - 2015-12-29 22:33:43,838 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:33:43 INFO - 2015-12-29 22:33:43,839 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:33:43 INFO - 2015-12-29 22:33:43,839 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:33:43 INFO - 2015-12-29 22:33:43,839 DEBUG : BROWSER_OUTPUT: __metrics 22:33:43 INFO - 2015-12-29 22:33:43,940 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] 22:33:45 INFO - 2015-12-29 22:33:45,513 INFO : Browser initialized. 22:33:45 INFO - 2015-12-29 22:33:45,514 INFO : Running cycle 1/1 for kraken test... 22:33:45 INFO - 2015-12-29 22:33:45,514 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp29Nvpj/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:33:57 INFO - 2015-12-29 22:33:57,067 DEBUG : BROWSER_OUTPUT: RSS: Main: 128819200 22:33:57 INFO - 2015-12-29 22:33:57,067 DEBUG : BROWSER_OUTPUT: 22:33:59 INFO - 2015-12-29 22:33:59,501 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55295/tests/kraken/driver.html?testName=audio-beat-detection) 22:34:00 INFO - 2015-12-29 22:34:00,067 DEBUG : BROWSER_OUTPUT: RSS: Main: 164392960 22:34:00 INFO - 2015-12-29 22:34:00,067 DEBUG : BROWSER_OUTPUT: 22:34:02 INFO - 2015-12-29 22:34:02,597 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55295/tests/kraken/driver.html?testName=audio-dft) 22:34:03 INFO - 2015-12-29 22:34:03,162 DEBUG : BROWSER_OUTPUT: RSS: Main: 161103872 22:34:03 INFO - 2015-12-29 22:34:03,162 DEBUG : BROWSER_OUTPUT: 22:34:05 INFO - 2015-12-29 22:34:05,954 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55295/tests/kraken/driver.html?testName=audio-fft) 22:34:06 INFO - 2015-12-29 22:34:06,515 DEBUG : BROWSER_OUTPUT: RSS: Main: 157933568 22:34:06 INFO - 2015-12-29 22:34:06,516 DEBUG : BROWSER_OUTPUT: 22:34:08 INFO - 2015-12-29 22:34:08,494 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55295/tests/kraken/driver.html?testName=audio-oscillator) 22:34:09 INFO - 2015-12-29 22:34:09,055 DEBUG : BROWSER_OUTPUT: RSS: Main: 158015488 22:34:09 INFO - 2015-12-29 22:34:09,055 DEBUG : BROWSER_OUTPUT: 22:34:11 INFO - 2015-12-29 22:34:11,201 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55295/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:34:11 INFO - 2015-12-29 22:34:11,763 DEBUG : BROWSER_OUTPUT: RSS: Main: 156827648 22:34:11 INFO - 2015-12-29 22:34:11,763 DEBUG : BROWSER_OUTPUT: 22:34:14 INFO - 2015-12-29 22:34:14,651 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55295/tests/kraken/driver.html?testName=imaging-darkroom) 22:34:15 INFO - 2015-12-29 22:34:15,216 DEBUG : BROWSER_OUTPUT: RSS: Main: 156028928 22:34:15 INFO - 2015-12-29 22:34:15,217 DEBUG : BROWSER_OUTPUT: 22:34:18 INFO - 2015-12-29 22:34:18,640 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55295/tests/kraken/driver.html?testName=imaging-desaturate) 22:34:19 INFO - 2015-12-29 22:34:19,210 DEBUG : BROWSER_OUTPUT: RSS: Main: 156905472 22:34:19 INFO - 2015-12-29 22:34:19,210 DEBUG : BROWSER_OUTPUT: 22:34:21 INFO - 2015-12-29 22:34:21,968 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55295/tests/kraken/driver.html?testName=json-parse-financial) 22:34:22 INFO - 2015-12-29 22:34:22,535 DEBUG : BROWSER_OUTPUT: RSS: Main: 157847552 22:34:22 INFO - 2015-12-29 22:34:22,536 DEBUG : BROWSER_OUTPUT: 22:34:24 INFO - 2015-12-29 22:34:24,046 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55295/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:34:24 INFO - 2015-12-29 22:34:24,599 DEBUG : BROWSER_OUTPUT: RSS: Main: 162136064 22:34:24 INFO - 2015-12-29 22:34:24,599 DEBUG : BROWSER_OUTPUT: 22:34:26 INFO - 2015-12-29 22:34:26,355 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55295/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:34:26 INFO - 2015-12-29 22:34:26,941 DEBUG : BROWSER_OUTPUT: RSS: Main: 161619968 22:34:26 INFO - 2015-12-29 22:34:26,941 DEBUG : BROWSER_OUTPUT: 22:34:28 INFO - 2015-12-29 22:34:28,905 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55295/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:34:29 INFO - 2015-12-29 22:34:29,471 DEBUG : BROWSER_OUTPUT: RSS: Main: 165511168 22:34:29 INFO - 2015-12-29 22:34:29,471 DEBUG : BROWSER_OUTPUT: 22:34:31 INFO - 2015-12-29 22:34:31,806 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55295/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:34:32 INFO - 2015-12-29 22:34:32,372 DEBUG : BROWSER_OUTPUT: RSS: Main: 169664512 22:34:32 INFO - 2015-12-29 22:34:32,372 DEBUG : BROWSER_OUTPUT: 22:34:34 INFO - 2015-12-29 22:34:34,808 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55295/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:34:35 INFO - 2015-12-29 22:34:35,382 DEBUG : BROWSER_OUTPUT: RSS: Main: 166977536 22:34:35 INFO - 2015-12-29 22:34:35,382 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,031 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55295/tests/kraken/driver.html?testName=ai-astar) 22:34:37 INFO - 2015-12-29 22:34:37,281 DEBUG : BROWSER_OUTPUT: RSS: Main: 224845824 22:34:37 INFO - 2015-12-29 22:34:37,281 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,283 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:34:37 INFO - 2015-12-29 22:34:37,283 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:34:37 INFO - 2015-12-29 22:34:37,283 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:34:37 INFO - 2015-12-29 22:34:37,283 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:34:37 INFO - 2015-12-29 22:34:37,284 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;113;105;111;112;111;101;112;108;111 22:34:37 INFO - 2015-12-29 22:34:37,284 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;144;120;142;157;123;156;143;124;160;130 22:34:37 INFO - 2015-12-29 22:34:37,284 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;161;161;160;162;159;159;161;161;162 22:34:37 INFO - 2015-12-29 22:34:37,284 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;72;158;71;72;72;72;156;71 22:34:37 INFO - 2015-12-29 22:34:37,284 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;91;90;136;89;90;90;131;89;90;135 22:34:37 INFO - 2015-12-29 22:34:37,285 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;109;109;110;110;110;110;110;110 22:34:37 INFO - 2015-12-29 22:34:37,285 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;146;147;146;146;146;145;145;145 22:34:37 INFO - 2015-12-29 22:34:37,285 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;96;95;95;95;95;96;96;97 22:34:37 INFO - 2015-12-29 22:34:37,285 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;55;56;56;57;56;55;56;54;55 22:34:37 INFO - 2015-12-29 22:34:37,285 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;64;65;65;64;64;64;64;64 22:34:37 INFO - 2015-12-29 22:34:37,285 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;79;79;79;79;79;78;79;78;77 22:34:37 INFO - 2015-12-29 22:34:37,286 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;117;126;109;109;117;110;111;115;111;113 22:34:37 INFO - 2015-12-29 22:34:37,286 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;138;137;136;139;138;138;146;137;137 22:34:37 INFO - 2015-12-29 22:34:37,286 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;65;65;67;65;63;65;66;66;65 22:34:37 INFO - 2015-12-29 22:34:37,286 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:34:37 INFO - 2015-12-29 22:34:37,286 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:34:37 INFO - 2015-12-29 22:34:37,286 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,864 22:34:37 INFO - 2015-12-29 22:34:37,286 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:34:37 INFO - 2015-12-29 22:34:37,287 DEBUG : BROWSER_OUTPUT: __startTimestamp1451457277282__endTimestamp 22:34:37 INFO - 2015-12-29 22:34:37,287 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,290 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:34:37 INFO - 2015-12-29 22:34:37,290 DEBUG : BROWSER_OUTPUT: Number of tests: 14 22:34:37 INFO - 2015-12-29 22:34:37,290 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,290 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:109.60 Median:111.50 stddev:3.84 (3.4%) stddev-sans-first:3.97 22:34:37 INFO - 2015-12-29 22:34:37,290 DEBUG : BROWSER_OUTPUT: Values: 112.0 113.0 105.0 111.0 112.0 111.0 101.0 112.0 108.0 111.0 22:34:37 INFO - 2015-12-29 22:34:37,290 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,290 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:139.90 Median:143.50 stddev:14.98 (10.4%) stddev-sans-first:15.81 22:34:37 INFO - 2015-12-29 22:34:37,291 DEBUG : BROWSER_OUTPUT: Values: 144.0 120.0 142.0 157.0 123.0 156.0 143.0 124.0 160.0 130.0 22:34:37 INFO - 2015-12-29 22:34:37,291 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,291 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 22:34:37 INFO - 2015-12-29 22:34:37,291 DEBUG : BROWSER_OUTPUT: Values: 161.0 161.0 161.0 160.0 162.0 159.0 159.0 161.0 161.0 162.0 22:34:37 INFO - 2015-12-29 22:34:37,291 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,291 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.70 Median:72.00 stddev:36.00 (50.0%) stddev-sans-first:37.68 22:34:37 INFO - 2015-12-29 22:34:37,291 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 72.0 158.0 71.0 72.0 72.0 72.0 156.0 71.0 22:34:37 INFO - 2015-12-29 22:34:37,291 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,291 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.10 Median:90.00 stddev:21.37 (23.7%) stddev-sans-first:22.21 22:34:37 INFO - 2015-12-29 22:34:37,291 DEBUG : BROWSER_OUTPUT: Values: 91.0 90.0 136.0 89.0 90.0 90.0 131.0 89.0 90.0 135.0 22:34:37 INFO - 2015-12-29 22:34:37,291 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,291 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.80 Median:110.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:34:37 INFO - 2015-12-29 22:34:37,291 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 109.0 109.0 110.0 110.0 110.0 110.0 110.0 110.0 22:34:37 INFO - 2015-12-29 22:34:37,291 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,291 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 22:34:37 INFO - 2015-12-29 22:34:37,292 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 146.0 147.0 146.0 146.0 146.0 145.0 145.0 145.0 22:34:37 INFO - 2015-12-29 22:34:37,292 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,292 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 22:34:37 INFO - 2015-12-29 22:34:37,292 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 96.0 97.0 22:34:37 INFO - 2015-12-29 22:34:37,292 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,292 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.50 Median:56.00 stddev:0.85 (1.5%) stddev-sans-first:0.88 22:34:37 INFO - 2015-12-29 22:34:37,292 DEBUG : BROWSER_OUTPUT: Values: 55.0 55.0 56.0 56.0 57.0 56.0 55.0 56.0 54.0 55.0 22:34:37 INFO - 2015-12-29 22:34:37,292 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,292 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.20 Median:64.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 22:34:37 INFO - 2015-12-29 22:34:37,292 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 64.0 65.0 65.0 64.0 64.0 64.0 64.0 64.0 22:34:37 INFO - 2015-12-29 22:34:37,292 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,292 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:79.00 stddev:2.15 (2.7%) stddev-sans-first:0.73 22:34:37 INFO - 2015-12-29 22:34:37,292 DEBUG : BROWSER_OUTPUT: Values: 85.0 79.0 79.0 79.0 79.0 79.0 78.0 79.0 78.0 77.0 22:34:37 INFO - 2015-12-29 22:34:37,292 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,292 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:113.80 Median:114.00 stddev:5.25 (4.6%) stddev-sans-first:5.43 22:34:37 INFO - 2015-12-29 22:34:37,292 DEBUG : BROWSER_OUTPUT: Values: 117.0 126.0 109.0 109.0 117.0 110.0 111.0 115.0 111.0 113.0 22:34:37 INFO - 2015-12-29 22:34:37,293 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,293 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.60 Median:138.00 stddev:2.84 (2.1%) stddev-sans-first:2.96 22:34:37 INFO - 2015-12-29 22:34:37,293 DEBUG : BROWSER_OUTPUT: Values: 140.0 138.0 137.0 136.0 139.0 138.0 138.0 146.0 137.0 137.0 22:34:37 INFO - 2015-12-29 22:34:37,293 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,293 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.50 stddev:1.06 (1.6%) stddev-sans-first:1.09 22:34:37 INFO - 2015-12-29 22:34:37,293 DEBUG : BROWSER_OUTPUT: Values: 66.0 65.0 65.0 67.0 65.0 63.0 65.0 66.0 66.0 65.0 22:34:37 INFO - 2015-12-29 22:34:37,293 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:34:37 INFO - 2015-12-29 22:34:37,293 DEBUG : BROWSER_OUTPUT: 22:34:37 INFO - 2015-12-29 22:34:37,969 INFO : Browser exited with error code: 0 22:34:38 INFO - 2015-12-29 22:34:38,023 INFO : Completed test kraken (00:00:58) 22:34:38 INFO - 2015-12-29 22:34:38,023 INFO : Starting test v8_7 22:34:38 INFO - 2015-12-29 22:34:38,023 DEBUG : operating with platform_type : linux_ 22:34:38 INFO - 2015-12-29 22:34:38,023 INFO : Initialising browser for v8_7 test... 22:34:38 INFO - 2015-12-29 22:34:38,030 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpPR8BOR/profile http://localhost:55295/getInfo.html 22:34:42 INFO - 2015-12-29 22:34:42,130 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:34:42 INFO - 2015-12-29 22:34:42,130 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:34:42 INFO - 2015-12-29 22:34:42,130 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:34:42 INFO - 2015-12-29 22:34:42,130 DEBUG : BROWSER_OUTPUT: __metrics 22:34:42 INFO - 2015-12-29 22:34:42,219 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] 22:34:43 INFO - 2015-12-29 22:34:43,625 INFO : Browser initialized. 22:34:43 INFO - 2015-12-29 22:34:43,626 INFO : Running cycle 1/1 for v8_7 test... 22:34:43 INFO - 2015-12-29 22:34:43,626 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpPR8BOR/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:34:55 INFO - 2015-12-29 22:34:55,225 DEBUG : BROWSER_OUTPUT: RSS: Main: 128516096 22:34:55 INFO - 2015-12-29 22:34:55,225 DEBUG : BROWSER_OUTPUT: 22:35:16 INFO - 2015-12-29 22:35:16,594 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55295/tests/v8_7/run.html (next: http://localhost:55295/tests/v8_7/run.html) 22:35:16 INFO - 2015-12-29 22:35:16,844 DEBUG : BROWSER_OUTPUT: RSS: Main: 417153024 22:35:16 INFO - 2015-12-29 22:35:16,845 DEBUG : BROWSER_OUTPUT: 22:35:16 INFO - 2015-12-29 22:35:16,845 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:35:16 INFO - 2015-12-29 22:35:16,845 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:35:16 INFO - 2015-12-29 22:35:16,846 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:35:16 INFO - 2015-12-29 22:35:16,846 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:35:16 INFO - 2015-12-29 22:35:16,846 DEBUG : BROWSER_OUTPUT: |0;Richards;164.7175094712568 22:35:16 INFO - 2015-12-29 22:35:16,847 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.88046166529267 22:35:16 INFO - 2015-12-29 22:35:16,847 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.79854504756577 22:35:16 INFO - 2015-12-29 22:35:16,847 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5000 22:35:16 INFO - 2015-12-29 22:35:16,847 DEBUG : BROWSER_OUTPUT: |4;RayTrace;948.7666034155598 22:35:16 INFO - 2015-12-29 22:35:16,848 DEBUG : BROWSER_OUTPUT: |5;Earley;553.0973451327434 22:35:16 INFO - 2015-12-29 22:35:16,848 DEBUG : BROWSER_OUTPUT: |6;Boyer;10458.333333333334 22:35:16 INFO - 2015-12-29 22:35:16,848 DEBUG : BROWSER_OUTPUT: |7;RegExp;23857.14285714286 22:35:16 INFO - 2015-12-29 22:35:16,849 DEBUG : BROWSER_OUTPUT: |8;Splay;535.8470525756771 22:35:16 INFO - 2015-12-29 22:35:16,849 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4817.307692307692 22:35:16 INFO - 2015-12-29 22:35:16,849 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:35:16 INFO - 2015-12-29 22:35:16,851 DEBUG : BROWSER_OUTPUT: __startTimestamp1451457316844__endTimestamp 22:35:16 INFO - 2015-12-29 22:35:16,851 DEBUG : BROWSER_OUTPUT: 22:35:16 INFO - 2015-12-29 22:35:16,851 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:35:16 INFO - 2015-12-29 22:35:16,851 DEBUG : BROWSER_OUTPUT: Number of tests: 10 22:35:16 INFO - 2015-12-29 22:35:16,852 DEBUG : BROWSER_OUTPUT: 22:35:16 INFO - 2015-12-29 22:35:16,852 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.72 Median:164.72 stddev:0.00 (0.0%) 22:35:16 INFO - 2015-12-29 22:35:16,852 DEBUG : BROWSER_OUTPUT: Values: 164.7 22:35:16 INFO - 2015-12-29 22:35:16,852 DEBUG : BROWSER_OUTPUT: 22:35:16 INFO - 2015-12-29 22:35:16,852 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.88 Median:164.88 stddev:0.00 (0.0%) 22:35:16 INFO - 2015-12-29 22:35:16,853 DEBUG : BROWSER_OUTPUT: Values: 164.9 22:35:16 INFO - 2015-12-29 22:35:16,853 DEBUG : BROWSER_OUTPUT: 22:35:16 INFO - 2015-12-29 22:35:16,853 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.80 Median:279.80 stddev:0.00 (0.0%) 22:35:16 INFO - 2015-12-29 22:35:16,853 DEBUG : BROWSER_OUTPUT: Values: 279.8 22:35:16 INFO - 2015-12-29 22:35:16,853 DEBUG : BROWSER_OUTPUT: 22:35:16 INFO - 2015-12-29 22:35:16,853 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5000.00 Median:5000.00 stddev:0.00 (0.0%) 22:35:16 INFO - 2015-12-29 22:35:16,853 DEBUG : BROWSER_OUTPUT: Values: 5000.0 22:35:16 INFO - 2015-12-29 22:35:16,854 DEBUG : BROWSER_OUTPUT: 22:35:16 INFO - 2015-12-29 22:35:16,854 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:948.77 Median:948.77 stddev:0.00 (0.0%) 22:35:16 INFO - 2015-12-29 22:35:16,854 DEBUG : BROWSER_OUTPUT: Values: 948.8 22:35:16 INFO - 2015-12-29 22:35:16,854 DEBUG : BROWSER_OUTPUT: 22:35:16 INFO - 2015-12-29 22:35:16,854 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:553.10 Median:553.10 stddev:0.00 (0.0%) 22:35:16 INFO - 2015-12-29 22:35:16,854 DEBUG : BROWSER_OUTPUT: Values: 553.1 22:35:16 INFO - 2015-12-29 22:35:16,854 DEBUG : BROWSER_OUTPUT: 22:35:16 INFO - 2015-12-29 22:35:16,854 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10458.33 Median:10458.33 stddev:0.00 (0.0%) 22:35:16 INFO - 2015-12-29 22:35:16,854 DEBUG : BROWSER_OUTPUT: Values: 10458.3 22:35:16 INFO - 2015-12-29 22:35:16,855 DEBUG : BROWSER_OUTPUT: 22:35:16 INFO - 2015-12-29 22:35:16,855 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23857.14 Median:23857.14 stddev:0.00 (0.0%) 22:35:16 INFO - 2015-12-29 22:35:16,855 DEBUG : BROWSER_OUTPUT: Values: 23857.1 22:35:16 INFO - 2015-12-29 22:35:16,855 DEBUG : BROWSER_OUTPUT: 22:35:16 INFO - 2015-12-29 22:35:16,855 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:535.85 Median:535.85 stddev:0.00 (0.0%) 22:35:16 INFO - 2015-12-29 22:35:16,855 DEBUG : BROWSER_OUTPUT: Values: 535.8 22:35:16 INFO - 2015-12-29 22:35:16,855 DEBUG : BROWSER_OUTPUT: 22:35:16 INFO - 2015-12-29 22:35:16,855 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4817.31 Median:4817.31 stddev:0.00 (0.0%) 22:35:16 INFO - 2015-12-29 22:35:16,855 DEBUG : BROWSER_OUTPUT: Values: 4817.3 22:35:16 INFO - 2015-12-29 22:35:16,856 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:35:16 INFO - 2015-12-29 22:35:16,856 DEBUG : BROWSER_OUTPUT: 22:35:17 INFO - 2015-12-29 22:35:17,493 INFO : Browser exited with error code: 0 22:35:17 INFO - 2015-12-29 22:35:17,511 INFO : Completed test v8_7 (00:00:39) 22:35:17 INFO - 2015-12-29 22:35:17,853 INFO : Completed test suite (00:20:08) 22:35:17 INFO - 2015-12-29 22:35:17,853 DEBUG : Working with test: dromaeo_css 22:35:17 INFO - 2015-12-29 22:35:17,853 DEBUG : Generating results file: dromaeo_css 22:35:17 INFO - 2015-12-29 22:35:17,854 DEBUG : Working with test: kraken 22:35:17 INFO - 2015-12-29 22:35:17,855 DEBUG : Generating results file: kraken 22:35:17 INFO - 2015-12-29 22:35:17,855 INFO : javascript benchmark 22:35:17 INFO - 2015-12-29 22:35:17,855 DEBUG : Working with test: v8_7 22:35:17 INFO - 2015-12-29 22:35:17,855 DEBUG : Generating results file: v8_7 22:35:17 INFO - 2015-12-29 22:35:17,856 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:35:17 INFO - 2015-12-29 22:35:17,911 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 22:35:17 INFO - 2015-12-29 22:35:17,911 DEBUG : process_Request line: dromaeo_css 7137.18 graph.html#tests=[[72,53,35]] 22:35:17 INFO - 2015-12-29 22:35:17,911 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:35:18 INFO - 2015-12-29 22:35:18,040 DEBUG : process_Request line: kraken 1469.90 graph.html#tests=[[232,53,35]] 22:35:18 INFO - 2015-12-29 22:35:18,040 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:35:18 INFO - 2015-12-29 22:35:18,081 DEBUG : process_Request line: v8_7 23361.16 graph.html#tests=[[230,53,35]] 22:35:18 INFO - 2015-12-29 22:35:18,083 INFO : javascript benchmark 22:35:18 INFO - 2015-12-29 22:35:18,086 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3289.161263877711, 3421.982890085549, 3422.8117453540062, 3447.965520344796, 3471.1148657092444, 5616.073347897592, 5640.0, 5655.1517272409155, 5678.318601767794, 5683.31800183978, 5653.773849046033, 5673.205751194836, 5676.120201368794, 5680.8295751127425, 5693.658380497162, 4923.18767403378, 5122.35970503688, 5170.896582068375, 5234.6859188448625, 5264.447233040533, 5152.072626927152, 5168.8707782305555, 5170.172772356424, 5176.197689358156, 5179.663321884084, 5124.92312615311, 5125.410577783566, 5156.53591176794, 5159.303494028314, 5239.607029472786, 5142.382914050319, 5214.32213812202, 5222.0, 5224.425313215544, 5228.241904923765, 5098.796048158069, 5145.845624631267, 5177.611679124043, 5208.348956380452, 5234.42421333653, 5251.973740131313, 5378.946210537883, 5452.2094296327205, 5527.55779537636, 5595.328560572717, 6067.575269731121, 6073.089036644495, 6085.026395776655, 6087.0, 6089.847753806146, 6076.817695469143, 6081.452669259744, 6098.847528811771, 6107.358727333611, 6115.1744514490865, 5465.043617366985, 5522.22688823565, 5578.135389014709, 5598.936202121666, 5631.802886898939, 6162.907556386658, 6172.9074063889075, 6176.752929882844, 6180.567360284737, 6182.1345011698395, 5732.910746958065, 6016.187814644967, 6023.427774361428, 6023.819285421444, 6032.276126864805, 5938.346781853993, 6017.458428741435, 6113.816585502442, 6126.509879209652, 6141.324454310112, 6121.960940613755, 6129.632222066691, 6171.321154672983, 6172.259328880563, 6173.660448675276, 6143.416375444325, 6143.539234557426, 6145.108959200891, 6147.108669242934, 6148.846278843064, 4494.910101797946, 4519.096180763795, 4576.588107070313, 4619.284010978304, 4633.930491042637, 4138.02756352257, 4178.686598505064, 4197.4753155855515, 4204.873853784391, 4217.599328063829, 5532.17017447378, 5768.019436695772, 5781.913271300934, 5782.161586569924, 5789.6526208426785, 6018.307894592091, 6044.486218671462, 6082.969585152135, 6122.0, 6131.6321020738005, 5806.738696758572, 5807.477327040587, 5813.0, 5813.970930145407, 5816.098504731774, 5146.511081447256, 5248.239005344204, 5316.3354580677415, 5329.493698098674, 5340.439253878365, 6130.260541069943, 6217.067439884054, 6222.471089957314, 6225.346338634468, 6228.750849966042, 6077.848053798691, 6081.331053584191, 6082.665453400105, 6088.360722124113, 6152.569320147547, 6019.217501724747, 6136.447719705248, 6165.630062196283, 6235.844103897439, 6247.781327653511, 6007.939920600913, 6049.243844519435, 6084.665343406155, 6087.178230938944, 6089.817305480843, 6163.322034576283, 6174.8147555573405, 6176.227971503562, 6185.041318595445, 6188.1027251049245, 5702.40124786908, 5812.82561523155, 5861.912071318849, 5978.4021597839865, 6012.87974240504, 5909.320428150818, 5918.822435326947, 5927.911081333697, 5934.495567876779, 5942.6731529766275, 5830.883382332415, 5851.795187168429, 5857.7949771757785, 5858.502027327725, 5858.765649373977, 5654.85862853432, 5752.654840709571, 5797.594168408171, 5926.170336153028, 5933.970330148321, 5882.470577648032, 5933.258342707161, 5968.432998865101, 5974.044152935509, 5982.222311099529], "unit": "ms", "name": "dojo.html", "value": 5597.142453293151}, {"lowerIsBetter": false, "replicates": [600.2376981233833, 639.0956796133469, 640.9839754006149, 643.4466358931318, 645.5448908519494, 33845.66154338456, 42389.364159537596, 42700.359494607605, 42748.78625606876, 42754.57245427545, 46310.76844615772, 46736.2989555157, 46889.53110468894, 46922.76538617311, 46950.0, 8227.053888802775, 8360.289375403068, 9086.909130908673, 9273.72178834636, 10499.790004199911, 10593.470326483684, 10741.355518668866, 10744.623938162165, 10745.14038876888, 10748.946255268713, 10605.946970265139, 10749.140068794517, 10760.192985526117, 10764.13886889051, 10766.084882784973, 10402.791944161112, 10472.790544189149, 10499.0, 10506.842397364046, 10507.68476945689, 10471.528781204865, 10656.360618362924, 10694.46527673621, 10713.142948564097, 10713.517891694892, 42726.0, 43324.566754332685, 43417.34873976893, 43432.56567434348, 43454.34818477726, 3857.0550215197177, 3870.845166193349, 4099.959000409987, 4218.240716670998, 4342.0, 32031.359372812414, 32145.6785432145, 32478.512822307683, 32766.180845478815, 32877.34245315104, 3836.693064554829, 3874.1864208516245, 3900.4149377593303, 3915.569287378386, 3919.647231749156, 309521.0, 312177.0, 314299.0, 314334.4283278592, 314935.0, 57253.71373143191, 58132.0, 66056.33943660502, 68681.31318686849, 69065.0, 64607.353926460135, 64616.353836461974, 64921.67539162274, 64963.0, 64977.675111624136, 17653.38213162559, 17658.293668253384, 17823.73264401035, 17892.9105354475, 17900.0, 13279.734405312032, 13339.466421343232, 13343.933280333536, 13379.13035652699, 13512.324383780968, 22191.889040554695, 22435.439114021952, 22474.66288005681, 22580.435489112904, 22632.642041477236, 8090.676372945016, 8280.378971577156, 8368.958155209306, 8401.411901166983, 8427.199416055464, 1010.5907107621425, 1019.704285757137, 1146.5700362364114, 1147.9196456247982, 1147.9942600286945, 30223.848880755897, 30249.848750756544, 30322.393552129273, 30820.537691934638, 31043.37913241768, 1126.6000569797768, 1135.7728454309172, 1146.363768108691, 1157.7366248983217, 1237.0845574275152, 13622.0, 15653.060816351055, 15842.920785396, 15957.521274361789, 16400.75398869018, 352628.0, 356442.0, 356924.0, 358859.0, 359887.0, 59219.703901480214, 59260.40739592635, 59323.70338148368, 59364.0, 59579.404205957384, 1402.6843960108895, 1421.6801219725685, 1495.6859059597607, 1507.0505581483594, 1538.446159382599, 28156.85921570379, 28444.715552844205, 28663.42673146567, 29125.563116552847, 29133.417331653673, 53399.733001334745, 54481.0, 54518.0, 54533.72733136309, 54545.181822273495, 235857.0, 237723.0, 238003.0, 239154.0, 239946.0, 33174.33651326912, 33212.833935831135, 33283.167920802174, 33483.0, 33771.493427599096, 46602.76698616621, 47107.05785884332, 47688.0, 47993.0, 48424.7578762118, 39121.0, 39762.40356394598, 40005.0, 40279.25511992745, 40727.389089163094, 38058.0, 38715.612843872324, 39124.41313380359, 39943.2011359777, 40149.397759033054, 36421.0, 36636.26727465489, 36895.262094758495, 38736.6126338733, 39206.80396597999, 32193.0, 34040.65959340375, 35090.473642895886, 35097.0, 36210.0, 26536.469270614867, 27107.457850843268, 27140.893299007566, 27233.455330893667, 27290.45419091647], "unit": "ms", "name": "ext.html", "value": 20266.09508964187}, {"lowerIsBetter": false, "replicates": [365.18017051718897, 367.23065178451145, 369.2670049950849, 369.3997254461501, 373.71597585834775, 14606.342714577842, 16573.751393729104, 16884.57788555285, 17987.0, 18637.25450981961, 18138.727919081226, 18191.81808181918, 18365.632687346246, 18426.0, 18573.257069717194, 5520.99999999999, 5645.774169033234, 5651.06757385031, 5656.123300888348, 5670.518005969477, 4304.892377690567, 4436.401085853401, 5577.3028371453565, 6099.542534309922, 6267.561270711052, 6050.334463209044, 6160.445559899608, 6180.351063138373, 6208.379162083778, 6215.471684906788, 6142.723577439004, 6176.84557886052, 6200.162977997958, 6203.658798766066, 6205.782797602084, 4444.422225110748, 5441.673499590037, 5444.591655625804, 5444.91832622511, 5463.726813659301, 14147.504837330644, 14150.575482735534, 14280.07179533327, 14417.78373324401, 14418.495352662607, 2188.540406514634, 2210.1822325739417, 2210.502636906699, 2213.424509627492, 2362.4802543440414, 10256.0, 10282.537285822082, 10447.477626118663, 10632.308899921558, 10705.83941240882, 1762.9118544072874, 1815.056170791181, 1858.5353661584604, 1868.7196920461904, 1884.302807961063, 65355.67322163406, 68613.65693171552, 68899.0, 71258.0, 72121.0, 7515.511491753128, 7545.094588649287, 7571.053618297713, 7618.657160427683, 7636.961815190888, 9055.917139058054, 9062.36563440566, 9076.41003334781, 9076.8638470423, 9079.273658107335, 6113.296970848321, 6133.877322453526, 6181.721822517998, 6186.226721659793, 6189.96905015472, 6298.559100862895, 6312.52656050798, 6426.550141490145, 6457.580257283262, 6520.771772987922, 3465.5148279240925, 3527.6295988921306, 3536.6463353664944, 3545.3263879862743, 3547.840347184383, 1199.0, 1252.5678640868887, 3091.1962889648403, 3180.4911214205617, 3250.3499300140065, 468.7117422784963, 484.5179046848338, 503.26272011503323, 518.3054706693049, 529.9549538289211, 11364.715882103013, 12239.510419583296, 12356.196847204903, 12398.13213075076, 12493.375331233583, 523.6046784677544, 525.1466367153375, 528.5480913818707, 529.9072662284085, 530.4006472685841, 6715.563488373338, 6771.593704377753, 6820.727170913207, 6863.176418829772, 6926.722931082801, 658329.0, 668839.0, 679303.0, 690265.0, 705549.0, 82524.0, 85784.0, 86950.56524717463, 87377.0, 87765.12234877697, 575.807104619957, 580.4862696513532, 581.1312088427827, 581.3895409819672, 581.9243498345188, 11694.0, 11845.170838041255, 11896.405179741152, 11975.161738678209, 11991.160618756603, 39159.80420097881, 39254.80372598119, 39664.60335396609, 41489.37765933574, 41531.792341038104, 49975.0, 51482.742586288325, 51557.74221128871, 51768.0, 51902.0, 7251.347378735728, 7281.927180728125, 7334.449916256195, 7378.262173782793, 7442.0, 12690.809637855624, 12782.68043298925, 12991.610251692464, 12994.220346779224, 12999.610011699664, 11246.0, 11343.886561134614, 11475.019589561465, 11557.422128893353, 11632.534698612299, 11594.072474202212, 11811.527538898348, 11999.880001199876, 12129.0, 12148.271103733805, 10323.0, 10414.47927603632, 10771.89228107709, 11494.425278736197, 11927.284362937904, 7223.747168849065, 7272.636368181464, 7281.344678978918, 7322.487425880137, 7340.559566426032, 11064.612738554111, 11562.364069975896, 11917.880821192024, 12101.878981210075, 12159.817602736142], "unit": "ms", "name": "jquery.html", "value": 7831.163737784285}, {"lowerIsBetter": false, "replicates": [1518.3243456661783, 1523.542937118864, 1753.6317373351596, 1830.569816093218, 1861.7114347276172, 8083.312918401935, 8279.006519217688, 8297.253247207751, 8301.501909885395, 8315.792105197374, 7206.207317195104, 7474.177840437547, 7766.339861111798, 7978.720744773934, 8146.348292136629, 2306.388806966151, 2330.6387509935985, 2352.0, 2356.0458014504065, 2357.446000189954, 2012.0, 2337.123578658003, 2340.2979106268053, 2396.8921398537022, 2397.0, 2029.6042271757012, 2267.1271560449263, 2295.116380193621, 2300.2639155470256, 2300.850444721096, 2245.685604015447, 2263.2757517594378, 2271.3980795089315, 2275.8748268845206, 2275.9089636414524, 2321.7910388065156, 2330.2193765088714, 2363.1138323128826, 2364.8226383021342, 2372.4187574044468, 8111.594420278962, 8158.959205203995, 8166.142555031695, 8170.877436838393, 8179.509229446193, 4873.610111191097, 4926.679765815211, 4940.258961155855, 4947.332110165117, 4949.232868905326, 3065.800722953001, 3078.350762353306, 3080.907572772798, 3107.0989413070183, 3121.7502599792188, 1000.3997601439152, 1002.4686915934566, 1007.6372505897871, 1031.6440827914357, 1032.4889179855945, 10819.83770243447, 10835.079018283455, 10851.565937362497, 10865.782684346348, 10899.945500272448, 4413.1394378095965, 4423.358613001097, 4504.887377815581, 4506.909861802746, 4508.752018638941, 9170.541472926327, 9179.393606118903, 9210.401323913924, 9279.350445468752, 9325.626974920928, 4821.614270858393, 4870.732109733928, 4885.975570122127, 4915.336429582032, 4920.581750551171, 4471.172833025836, 4494.0337827367275, 4536.591706746409, 4542.477615074309, 4545.2954792007295, 3948.447217389568, 3952.3676211805973, 3979.641832235113, 3991.022199561068, 4013.4381186633896, 1079.6005477973042, 1147.3861484105964, 1260.4391045984448, 1261.3125846413727, 1265.4558539827779, 1012.099231683802, 1021.8518314844306, 1098.3739268616812, 1225.3566877389442, 1395.762720337545, 7408.2591740826565, 7424.034875466263, 7425.368843648243, 7494.812629684193, 7511.586862722494, 1206.957756478519, 1211.3156066822216, 1214.5081242096965, 1215.1918973882446, 1224.4306397525193, 5810.099434587646, 5886.1465087562065, 5896.262967129109, 5897.056470964625, 5902.498287645512, 9302.90697093034, 9825.115739583473, 9827.705168844803, 9834.360766550151, 9845.0, 9722.173615242642, 9741.9025809741, 9764.755881103029, 9767.0, 9781.16860066888, 7466.925330746623, 7636.770896873103, 7639.809004774925, 7655.157932627407, 7658.65536050879, 7289.052423184952, 7327.194008659149, 7336.559806411632, 7365.926340736738, 7367.889481657672, 8733.737987860375, 8744.737657870273, 8749.825003500022, 8762.824743505222, 8769.517676527852, 8840.734777956672, 9039.502827344559, 9054.185123338932, 9055.094490550891, 9079.727608171765, 7517.323440890346, 7610.505317154367, 7613.733519326797, 7615.771526854203, 7632.847343052997, 8239.382046346429, 8276.420650554403, 8333.624986875484, 8340.624671889658, 8350.290225330915, 7038.049863268393, 7702.768916932501, 7756.495827771177, 7772.57250851186, 7773.41699372538, 6994.2656021117045, 7580.924190758022, 7655.6937722490475, 7683.0780306363595, 7705.959695441268, 7097.7160913562875, 7132.1441427029085, 7141.107361579802, 7145.821354465972, 7151.928480715126, 6790.694418751069, 6816.0457535946, 6816.454683625199, 6841.760538381132, 7068.893966590608, 6873.793786186222, 6890.10428644273, 6903.75836845708, 6905.067815844997, 6906.447484201352], "unit": "ms", "name": "mootools.html", "value": 4674.982404889009}, {"lowerIsBetter": false, "replicates": [338.86445421831263, 340.77679120176276, 343.74906318387593, 346.9843857026434, 352.88707613563656, 5941.851453713659, 5996.8500787480225, 6001.819945401634, 6006.849828754278, 6008.609440386377, 5686.715664216783, 5753.194552762606, 5777.942220577793, 5882.970585147068, 5890.116482527623, 4575.450945886481, 4608.331791890174, 4617.37665415168, 4626.537346265381, 4629.513901040393, 4610.815567377301, 4616.676832621718, 4622.052479241747, 4624.28323609841, 4624.976875115636, 4436.0240747035605, 4497.595216430521, 4511.70673906195, 4558.772061396933, 4572.40558727365, 4531.705439146445, 4532.614727748145, 4543.273076307792, 4545.11370282795, 4551.385562948993, 4019.517657881073, 4025.63769260765, 4031.093004074089, 4032.9798351008058, 4033.69747268956, 5578.609497335188, 5616.0, 5625.156226566007, 5627.831165065055, 5628.831135065954, 5207.661502002357, 5304.655197412156, 5305.681659100466, 5308.283381743492, 5310.017646735328, 6251.624902505819, 6253.749850005995, 6254.655993920333, 6254.812355629293, 6257.217847769029, 4448.555144485558, 4448.911021779546, 4451.399061126739, 4460.0, 4466.0, 6372.458341041017, 6425.903611445832, 6486.610803351814, 6490.740370385226, 6507.44686701631, 4632.2125238709505, 4632.420947381577, 4636.11913736389, 4636.6058884995155, 4708.505606911293, 4746.952530474651, 4764.880877978009, 4765.26138448541, 4768.046390721869, 4773.236282194832, 5422.810201642924, 5423.403425623178, 5424.376196737426, 5425.864353391197, 5427.050266203399, 6297.0, 6395.488360931115, 6409.615423074631, 6413.647249401328, 6418.037294405876, 1658.0466231916696, 1701.7107091794178, 1708.4532949456054, 1710.6835235481476, 1710.9230084646222, 1129.6215767717742, 1156.2195518025467, 1165.143619439711, 1180.6103985684704, 1185.7569198314497, 6149.96925015381, 6214.130021796952, 6222.439980401786, 6232.8130156095385, 6237.438630523275, 6560.835979100465, 6566.179227596551, 6578.6052836828985, 6587.110740050032, 6587.868242635216, 5982.820515384546, 6098.237720284965, 6104.603200792023, 6217.036359364306, 6229.221347331583, 3609.097725568608, 3624.4200927851416, 3628.0385697790107, 3633.94549081764, 3634.1096431373953, 6723.5293529452465, 6726.7981960541265, 6730.394264516119, 6734.494912881455, 6738.7304507819135, 6560.212774466999, 6692.631905245258, 6702.128723266042, 6704.899426508607, 6706.798796036127, 6757.662116894136, 6786.287439818847, 6791.456683465311, 6796.76211332601, 6797.218319893177, 6568.6058836469965, 6617.536772425884, 6629.701663425254, 6638.236602790741, 6644.867102658017, 6431.5819471734185, 6565.113709649137, 6567.934320656732, 6568.080468734476, 6572.605643661395, 6757.493188010821, 6788.185417749902, 6813.863722725617, 6819.079424277659, 6820.488463365168, 4746.311784791255, 4747.762611869462, 4756.762161891822, 4758.547937945889, 4758.928616070831, 6478.481721462366, 6537.607743535403, 6561.3438656134, 6579.901301480386, 6635.933640663725, 6387.137736405712, 6388.616682999035, 6395.904061438989, 6396.520260980538, 6401.359864013748, 6365.904511432424, 6410.358964103552, 6412.070249813844, 6422.036694495863, 6423.132877061724, 6261.24865016201, 6384.808455746335, 6394.136791533084, 6397.104405383343, 6397.712102955285, 6438.871222575429, 6492.058651495412, 6497.772577959749, 6500.772472963424, 6503.44720698727, 6353.428191462791, 6358.650274234961, 6359.141515895295, 6360.427561519486, 6360.459360954185], "unit": "ms", "name": "prototype.html", "value": 4844.659449296734}, {"lowerIsBetter": false, "replicates": [762.1540090499544, 879.2087121590572, 893.1381217125474, 894.5571941888766, 911.8586619074038, 13037.934810325947, 13430.194188348705, 13495.662608434794, 13509.121907076033, 13545.255010974404, 13148.474061037547, 13182.934085329583, 13212.867871321283, 13272.800907986388, 13292.800607990865, 756.4213376766778, 764.4954330142107, 922.6678395777532, 932.911373419524, 946.0823001688351, 911.5943405184695, 923.0769230769231, 931.0131260863473, 933.4679232837285, 934.1965909317981, 972.8589354543587, 981.5239608789731, 984.7882705218377, 989.8168838764816, 990.7126933189367, 796.7808852565532, 806.1938061938062, 965.8937516873174, 981.5582987655583, 982.7690492734201, 726.2700985509582, 727.745289148799, 728.1517032657001, 731.4221764805797, 731.7731503234014, 13256.072074954756, 13420.396082176228, 13444.462221511225, 13444.5294414696, 13466.057375983684, 11480.655580332603, 12106.39468026604, 12201.328926909016, 12282.631521054383, 12329.383530823423, 10487.318324308895, 10552.366857988469, 10630.893691063067, 10704.411257380842, 10835.91640835918, 3629.618890016563, 3658.70730341572, 3671.192337685705, 3684.3552378333684, 3789.2421515696697, 51330.0, 51393.743031284605, 51412.485875141145, 51817.48182518202, 53393.19910201389, 9842.950785246028, 9904.900950990543, 9944.204463642891, 9991.150752186, 10006.599736010623, 20023.59952800965, 20083.59832803365, 20096.497587560134, 20111.597768044558, 20115.39653810388, 6790.422814060761, 6847.315268473113, 6945.826354341182, 6955.02629631852, 7107.502474826712, 4556.2937744649635, 4647.395838540968, 4649.5815376616265, 4680.719156850531, 4707.576318131385, 2923.956140657892, 2948.6903875093235, 2952.9704702952695, 2955.3055032067878, 2957.5267957126757, 459.82066993872417, 466.62902992121343, 471.77590644443615, 473.10582998133555, 474.3643517686317, 634.8888944434798, 653.2291895563282, 676.864627074587, 684.8767221900006, 709.4537206351081, 8846.203841654282, 8894.155055269739, 8987.77530561728, 9130.680426185052, 9163.404378715362, 539.051269765216, 553.8366181976327, 625.9593126446766, 676.5906626491001, 714.4998501049287, 4337.392765012901, 4354.716943398669, 4366.257736184856, 4464.441944756905, 4477.910441791211, 61375.0, 68690.3130968684, 68743.3125668747, 68888.6555567229, 69014.65492672505, 55939.0, 56178.719106404205, 56559.0, 56672.43327566754, 57224.71387643035, 50357.6981337704, 50718.239226411635, 50864.0, 51565.0, 53914.19128713072, 9253.352265341362, 9353.39202951806, 9431.952840235756, 9613.134817866425, 9622.90377096248, 38890.80554597209, 39754.40368394418, 39930.80034599808, 41309.58690413057, 41944.0, 48368.0, 50227.74886125546, 53449.73275133755, 53645.463545364044, 53750.0, 7589.58257295854, 8290.046644635944, 8395.95802020986, 8574.742757717278, 8638.17937295956, 11771.823422648838, 12294.877051229374, 12701.809472857729, 13276.203427794364, 13597.932010339884, 11480.82778758336, 11601.303921764722, 11874.584389546324, 12303.93848030754, 12373.690657733268, 11973.521059157536, 12115.515379384726, 12155.453004614637, 12269.141160118706, 12484.438200280827, 10195.541200645841, 11380.829287560528, 11490.94254528722, 11699.415029248334, 12133.393330333625, 9650.0, 10046.799064018533, 10057.727905480004, 10249.692509224735, 10546.947265263625, 9922.305438619227, 9936.900630993887, 9944.016794961628, 9957.253206009433, 10267.640632577824], "unit": "ms", "name": "yui.html", "value": 6569.253189162058}], "name": "dromaeo_css", "value": 7137.1802762324205}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 113.0, 105.0, 111.0, 112.0, 111.0, 101.0, 112.0, 108.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 109.6}, {"lowerIsBetter": true, "replicates": [144.0, 120.0, 142.0, 157.0, 123.0, 156.0, 143.0, 124.0, 160.0, 130.0], "unit": "ms", "name": "audio-beat-detection", "value": 139.9}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 161.0, 160.0, 162.0, 159.0, 159.0, 161.0, 161.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 160.7}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 72.0, 158.0, 71.0, 72.0, 72.0, 72.0, 156.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 88.7}, {"lowerIsBetter": true, "replicates": [91.0, 90.0, 136.0, 89.0, 90.0, 90.0, 131.0, 89.0, 90.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 103.1}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.8}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.9}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 97.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [55.0, 55.0, 56.0, 56.0, 57.0, 56.0, 55.0, 56.0, 54.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 55.5}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 64.0, 65.0, 65.0, 64.0, 64.0, 64.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.2}, {"lowerIsBetter": true, "replicates": [85.0, 79.0, 79.0, 79.0, 79.0, 79.0, 78.0, 79.0, 78.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.2}, {"lowerIsBetter": true, "replicates": [117.0, 126.0, 109.0, 109.0, 117.0, 110.0, 111.0, 115.0, 111.0, 113.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 113.8}, {"lowerIsBetter": true, "replicates": [140.0, 138.0, 137.0, 136.0, 139.0, 138.0, 138.0, 146.0, 137.0, 137.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 138.6}, {"lowerIsBetter": true, "replicates": [66.0, 65.0, 65.0, 67.0, 65.0, 63.0, 65.0, 66.0, 66.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.3}], "name": "kraken", "value": 1469.8999999999999}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.7175094712568], "unit": "score", "name": "Richards", "value": 214.31844199999998}, {"lowerIsBetter": false, "replicates": [164.88046166529267], "unit": "score", "name": "DeltaBlue", "value": 401.0056700000001}, {"lowerIsBetter": false, "replicates": [279.79854504756577], "unit": "score", "name": "Encrypt", "value": 951.330894}, {"lowerIsBetter": false, "replicates": [5000.0], "unit": "score", "name": "Decrypt", "value": 53.23619999999997}, {"lowerIsBetter": false, "replicates": [948.7666034155598], "unit": "score", "name": "RayTrace", "value": 779.9484060000001}, {"lowerIsBetter": false, "replicates": [553.0973451327434], "unit": "score", "name": "Earley", "value": 1204.9651040000003}, {"lowerIsBetter": false, "replicates": [10458.333333333334], "unit": "score", "name": "Boyer", "value": 63.725545816733074}, {"lowerIsBetter": false, "replicates": [23857.14285714286], "unit": "score", "name": "RegExp", "value": 38.185}, {"lowerIsBetter": false, "replicates": [535.8470525756771], "unit": "score", "name": "Splay", "value": 152.07884340931622}, {"lowerIsBetter": false, "replicates": [4817.307692307692], "unit": "score", "name": "NavierStokes", "value": 308.055888223553}], "name": "v8_7", "value": 23361.16308974735}]} 22:35:18 INFO - RETURN: dromaeo_css: 7137.18 22:35:18 INFO - RETURN: kraken: 1469.90 22:35:18 INFO - RETURN: v8_7: 23361.16 22:35:18 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23361.16"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1469.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7137.18"}}} 22:35:18 INFO - Return code: 0 22:35:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 22:35:18 INFO - # TBPL SUCCESS # 22:35:18 INFO - Running post-action listener: _resource_record_post_action 22:35:18 INFO - Running post-run listener: _resource_record_post_run 22:35:19 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 16384; Write bytes: 505925632; Read time: 676; Write time: 846440 22:35:19 INFO - install - Wall time: 16s; CPU: 17.0%; Read bytes: 4096; Write bytes: 244711424; Read time: 312; Write time: 545592 22:35:19 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 12288; Write bytes: 260190208; Read time: 364; Write time: 300544 22:35:19 INFO - Running post-run listener: _upload_blobber_files 22:35:19 INFO - Blob upload gear active. 22:35:19 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 22:35:19 INFO - Copying logs to upload dir... 22:35:19 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1254.451896 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2015-12-29 22:35:19.115190) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-29 22:35:19.118860) ========= 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=0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009954 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451419478/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-29 22:35:19.173460) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 22:35:19.174031) ========= 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=0ae9de487587ba4b94dd3100000001b5-1451454849.638636-824352766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004658 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 22:35:19.226209) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-29 22:35:19.226769) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-29 22:35:19.227293) ========= ========= Total master_lag: 0.23 =========