builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-007 starttime: 1451946989.17 results: success (0) buildid: 20160104121230 builduid: 75321f8bb23e4f6dabac74ffe097da3c revision: ec2b2ab4e57d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:29.166055) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:29.166489) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:29.166770) ========= 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=b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006409 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:29.195861) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:29.196158) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:29.225988) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:29.226378) ========= 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=b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004684 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:29.275064) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:29.275371) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:29.275770) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:29.276093) ========= 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=b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-04 14:36:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.7M=0s 2016-01-04 14:36:29 (43.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.152850 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:29.441280) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:29.441614) ========= 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=b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019516 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:29.492028) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:29.492479) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ec2b2ab4e57d --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 ec2b2ab4e57d --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=b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-04 14:36:29,897 Setting DEBUG logging. 2016-01-04 14:36:29,897 attempt 1/10 2016-01-04 14:36:29,897 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ec2b2ab4e57d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-04 14:36:30,187 unpacking tar archive at: mozilla-beta-ec2b2ab4e57d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.627345 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:30.132536) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:30.132834) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:30.145285) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:30.145568) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-04 14:36:30.145944) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-01-04 14:36:30.146220) ========= /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=b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:36:30 INFO - MultiFileLogger online at 20160104 14:36:30 in /builds/slave/test-pgo 14:36:30 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 14:36:30 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 14:36:30 INFO - {'append_to_log': False, 14:36:30 INFO - 'base_work_dir': '/builds/slave/test-pgo', 14:36:30 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:36:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 14:36:30 INFO - 'branch': 'Mozilla-Beta', 14:36:30 INFO - 'buildbot_json_path': 'buildprops.json', 14:36:30 INFO - 'config_files': ('talos/linux_config.py',), 14:36:30 INFO - 'default_actions': ('clobber', 14:36:30 INFO - 'read-buildbot-config', 14:36:30 INFO - 'download-and-extract', 14:36:30 INFO - 'populate-webroot', 14:36:30 INFO - 'create-virtualenv', 14:36:30 INFO - 'install', 14:36:30 INFO - 'run-tests'), 14:36:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:36:30 INFO - 'download_minidump_stackwalk': True, 14:36:30 INFO - 'download_symbols': 'ondemand', 14:36:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:36:30 INFO - 'tooltool.py': '/tools/tooltool.py', 14:36:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:36:30 INFO - '/tools/misc-python/virtualenv.py')}, 14:36:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:36:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:36:30 INFO - 'installer_path': 'installer.exe', 14:36:30 INFO - 'log_level': 'info', 14:36:30 INFO - 'log_name': 'talos', 14:36:30 INFO - 'log_to_console': True, 14:36:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:36:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:36:30 INFO - 'opt_config_files': (), 14:36:30 INFO - 'pip_index': False, 14:36:30 INFO - 'sps_profile': False, 14:36:30 INFO - 'sps_profile_interval': 0, 14:36:30 INFO - 'suite': 'dromaeojs', 14:36:30 INFO - 'system_bits': '32', 14:36:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:36:30 INFO - 'title': 'talos-linux64-ix-007', 14:36:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:36:30 INFO - 'use_talos_json': True, 14:36:30 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 14:36:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:36:30 INFO - 'work_dir': 'build'} 14:36:30 INFO - ##### 14:36:30 INFO - ##### Running clobber step. 14:36:30 INFO - ##### 14:36:30 INFO - Running pre-action listener: _resource_record_pre_action 14:36:30 INFO - Running main action method: clobber 14:36:30 INFO - rmtree: /builds/slave/test-pgo/build 14:36:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 14:36:31 INFO - Running post-action listener: _resource_record_post_action 14:36:31 INFO - ##### 14:36:31 INFO - ##### Running read-buildbot-config step. 14:36:31 INFO - ##### 14:36:31 INFO - Running pre-action listener: _resource_record_pre_action 14:36:31 INFO - Running main action method: read_buildbot_config 14:36:31 INFO - Using buildbot properties: 14:36:31 INFO - { 14:36:31 INFO - "properties": { 14:36:31 INFO - "buildnumber": 61, 14:36:31 INFO - "product": "firefox", 14:36:31 INFO - "script_repo_revision": "production", 14:36:31 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 14:36:31 INFO - "repository": "", 14:36:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 14:36:31 INFO - "buildid": "20160104121230", 14:36:31 INFO - "slavename": "talos-linux64-ix-007", 14:36:31 INFO - "pgo_build": "True", 14:36:31 INFO - "basedir": "/builds/slave/test-pgo", 14:36:31 INFO - "project": "", 14:36:31 INFO - "platform": "ubuntu64_hw", 14:36:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:36:31 INFO - "slavebuilddir": "test", 14:36:31 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 14:36:31 INFO - "branch": "mozilla-beta", 14:36:31 INFO - "repo_path": "releases/mozilla-beta", 14:36:31 INFO - "stage_platform": "linux64-pgo", 14:36:31 INFO - "builduid": "75321f8bb23e4f6dabac74ffe097da3c", 14:36:31 INFO - "revision": "ec2b2ab4e57d" 14:36:31 INFO - }, 14:36:31 INFO - "sourcestamp": { 14:36:31 INFO - "repository": "", 14:36:31 INFO - "hasPatch": false, 14:36:31 INFO - "project": "", 14:36:31 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:36:31 INFO - "changes": [ 14:36:31 INFO - { 14:36:31 INFO - "category": null, 14:36:31 INFO - "files": [ 14:36:31 INFO - { 14:36:31 INFO - "url": null, 14:36:31 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/firefox-44.0.en-US.linux-x86_64.tar.bz2" 14:36:31 INFO - } 14:36:31 INFO - ], 14:36:31 INFO - "repository": "", 14:36:31 INFO - "rev": "ec2b2ab4e57d", 14:36:31 INFO - "who": "sendchange", 14:36:31 INFO - "when": 1451946933, 14:36:31 INFO - "number": 7414709, 14:36:31 INFO - "comments": "Bug 1230686 - use RefPtr_DrawTarget__ instead of DrawTarget* to track changes in SurfaceFromElement a=ritu", 14:36:31 INFO - "project": "", 14:36:31 INFO - "at": "Mon 04 Jan 2016 14:35:33", 14:36:31 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:36:31 INFO - "revlink": "", 14:36:31 INFO - "properties": [ 14:36:31 INFO - [ 14:36:31 INFO - "buildid", 14:36:31 INFO - "20160104121230", 14:36:31 INFO - "Change" 14:36:31 INFO - ], 14:36:31 INFO - [ 14:36:31 INFO - "builduid", 14:36:31 INFO - "75321f8bb23e4f6dabac74ffe097da3c", 14:36:31 INFO - "Change" 14:36:31 INFO - ], 14:36:31 INFO - [ 14:36:31 INFO - "pgo_build", 14:36:31 INFO - "True", 14:36:31 INFO - "Change" 14:36:31 INFO - ] 14:36:31 INFO - ], 14:36:31 INFO - "revision": "ec2b2ab4e57d" 14:36:31 INFO - } 14:36:31 INFO - ], 14:36:31 INFO - "revision": "ec2b2ab4e57d" 14:36:31 INFO - } 14:36:31 INFO - } 14:36:31 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/firefox-44.0.en-US.linux-x86_64.tar.bz2. 14:36:31 INFO - Running post-action listener: _resource_record_post_action 14:36:31 INFO - ##### 14:36:31 INFO - ##### Running download-and-extract step. 14:36:31 INFO - ##### 14:36:31 INFO - Running pre-action listener: _resource_record_pre_action 14:36:31 INFO - Running main action method: download_and_extract 14:36:31 INFO - mkdir: /builds/slave/test-pgo/build/tests 14:36:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/test_packages.json 14:36:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 14:36:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 14:36:31 INFO - Downloaded 1270 bytes. 14:36:31 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 14:36:31 INFO - Using the following test package requirements: 14:36:31 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:36:31 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:36:31 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 14:36:31 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:36:31 INFO - u'jsshell-linux-x86_64.zip'], 14:36:31 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:36:31 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 14:36:31 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:36:31 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:36:31 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 14:36:31 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:36:31 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 14:36:31 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:36:31 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 14:36:31 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:36:31 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:36:31 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 14:36:31 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:36:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:36:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/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 14:36:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/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 14:36:34 INFO - Downloaded 22572253 bytes. 14:36:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 14:36:34 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:36:34 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 14:36:35 INFO - Return code: 0 14:36:35 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 14:36: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')]} 14:36:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:36:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/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 14:36:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/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 14:36:37 INFO - Downloaded 22572253 bytes. 14:36:37 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 14:36:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:36:37 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 14:36:38 INFO - Return code: 0 14:36:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:38 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:36:38 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/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 14:36:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/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 14:36:40 INFO - Downloaded 11131970 bytes. 14:36:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 14:36:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:36:40 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 14:36:40 INFO - Return code: 0 14:36: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')]} 14:36:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:36:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/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 14:36:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/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 14:36:51 INFO - Downloaded 56631549 bytes. 14:36:51 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:36:51 INFO - mkdir: /builds/slave/test-pgo/properties 14:36:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 14:36:51 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 14:36:51 INFO - Contents: 14:36:51 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:36:51 INFO - Running post-action listener: _resource_record_post_action 14:36:51 INFO - Running post-action listener: set_extra_try_arguments 14:36:51 INFO - ##### 14:36:51 INFO - ##### Running populate-webroot step. 14:36:51 INFO - ##### 14:36:51 INFO - Running pre-action listener: _resource_record_pre_action 14:36:51 INFO - Running main action method: populate_webroot 14:36:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:36:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:36:51 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 14:36:51 INFO - '../fennec_ids.txt'], 14:36:51 INFO - 'tests': ['tcheck2']}, 14:36:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:36:51 INFO - '--tppagecycles', 14:36:51 INFO - '7'], 14:36:51 INFO - 'tests': ['tsvgm']}}, 14:36:51 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 14:36:51 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 14:36:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:36:51 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:36:51 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 14:36:51 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:36:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:36:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:36:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:36:51 INFO - 'talos_options': ['--e10s'], 14:36:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:36:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:36:51 INFO - 'tests': ['damp', 'tps']}, 14:36:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:36:51 INFO - 'talos_options': ['--e10s'], 14:36:51 INFO - 'tests': ['damp', 'tps']}, 14:36:51 INFO - 'g3': {'tests': ['dromaeo_dom']}, 14:36:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:36:51 INFO - 'other': {'tests': ['a11yr', 14:36:51 INFO - 'ts_paint', 14:36:51 INFO - 'tpaint', 14:36:51 INFO - 'sessionrestore', 14:36:51 INFO - 'sessionrestore_no_auto_restore']}, 14:36:51 INFO - 'other-e10s': {'talos_options': ['--e10s'], 14:36:51 INFO - 'tests': ['a11yr', 14:36:51 INFO - 'ts_paint', 14:36:51 INFO - 'tpaint', 14:36:51 INFO - 'sessionrestore', 14:36:51 INFO - 'sessionrestore_no_auto_restore']}, 14:36:51 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 14:36:51 INFO - 'tests': ['a11yr', 14:36:51 INFO - 'ts_paint', 14:36:51 INFO - 'tpaint', 14:36:51 INFO - 'sessionrestore', 14:36:51 INFO - 'sessionrestore_no_auto_restore']}, 14:36:51 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 14:36:51 INFO - 'tests': ['a11yr', 14:36:51 INFO - 'ts_paint', 14:36:51 INFO - 'tpaint', 14:36:51 INFO - 'sessionrestore', 14:36:51 INFO - 'sessionrestore_no_auto_restore']}, 14:36:51 INFO - 'other_l64': {'tests': ['a11yr', 14:36:51 INFO - 'ts_paint', 14:36:51 INFO - 'tpaint', 14:36:51 INFO - 'sessionrestore', 14:36:51 INFO - 'sessionrestore_no_auto_restore']}, 14:36:51 INFO - 'other_nol64': {'tests': ['a11yr', 14:36:51 INFO - 'ts_paint', 14:36:51 INFO - 'tpaint', 14:36:51 INFO - 'sessionrestore', 14:36:51 INFO - 'sessionrestore_no_auto_restore']}, 14:36:51 INFO - 'svgr': {'tests': ['tsvgx', 14:36:51 INFO - 'tsvgr_opacity', 14:36:51 INFO - 'tart', 14:36:51 INFO - 'tscrollx', 14:36:51 INFO - 'cart']}, 14:36:51 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 14:36:51 INFO - 'tests': ['tsvgx', 14:36:51 INFO - 'tsvgr_opacity', 14:36:51 INFO - 'tart', 14:36:51 INFO - 'tscrollx', 14:36:51 INFO - 'cart']}, 14:36:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:36:51 INFO - 'tests': ['tp5o']}, 14:36:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:36:51 INFO - 'talos_options': ['--e10s'], 14:36:51 INFO - 'tests': ['tp5o']}, 14:36:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:36:51 INFO - 'talos_options': ['--xperf_path', 14:36:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:36:51 INFO - 'tests': ['tp5n']}, 14:36:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:36:51 INFO - 'talos_options': ['--e10s', 14:36:51 INFO - '--xperf_path', 14:36:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:36:51 INFO - 'tests': ['tp5n']}}, 14:36:51 INFO - 'talos.zip': {'path': '', 14:36:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:36:51 INFO - Running post-action listener: _resource_record_post_action 14:36:51 INFO - ##### 14:36:51 INFO - ##### Running create-virtualenv step. 14:36:51 INFO - ##### 14:36:51 INFO - Running pre-action listener: _resource_record_pre_action 14:36:51 INFO - Running main action method: create_virtualenv 14:36:51 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 14:36:51 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 14:36:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 14:36:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:36:51 INFO - Using real prefix '/usr' 14:36:51 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 14:36:52 INFO - Installing distribute.............................................................................................................................................................................................done. 14:36:53 INFO - Installing pip.................done. 14:36:54 INFO - Return code: 0 14:36:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 14:36:54 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')]} 14:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:54 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')]} 14:36:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:54 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 0x7ff5a4276f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17aa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1839350>, '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 0x183a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183a6e0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', '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 14:36:54 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 14:36:54 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 14:36:54 INFO - Using env: {'DISPLAY': ':0', 14:36:54 INFO - 'HOME': '/home/cltbld', 14:36:54 INFO - 'LANG': 'en_US.UTF-8', 14:36:54 INFO - 'LANGUAGE': 'en_US:en', 14:36:54 INFO - 'LOGNAME': 'cltbld', 14:36:54 INFO - 'MAIL': '/var/mail/cltbld', 14:36:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:54 INFO - 'MOZ_NO_REMOTE': '1', 14:36:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:54 INFO - 'NO_EM_RESTART': '1', 14:36:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:36:54 INFO - 'PWD': '/builds/slave/test-pgo', 14:36:54 INFO - 'SHELL': '/bin/bash', 14:36:54 INFO - 'SHLVL': '1', 14:36:54 INFO - 'TERM': 'linux', 14:36:54 INFO - 'TMOUT': '86400', 14:36:54 INFO - 'USER': 'cltbld', 14:36:54 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', 14:36:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:54 INFO - '_': '/tools/buildbot/bin/python'} 14:36:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:54 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 14:36:54 INFO - Cleaning up... 14:36:54 INFO - Return code: 0 14:36:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 14:36:54 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')]} 14:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:54 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')]} 14:36:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:54 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 0x7ff5a4276f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17aa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1839350>, '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 0x183a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183a6e0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', '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 14:36:54 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 14:36:54 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 14:36:54 INFO - Using env: {'DISPLAY': ':0', 14:36:54 INFO - 'HOME': '/home/cltbld', 14:36:54 INFO - 'LANG': 'en_US.UTF-8', 14:36:54 INFO - 'LANGUAGE': 'en_US:en', 14:36:54 INFO - 'LOGNAME': 'cltbld', 14:36:54 INFO - 'MAIL': '/var/mail/cltbld', 14:36:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:54 INFO - 'MOZ_NO_REMOTE': '1', 14:36:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:54 INFO - 'NO_EM_RESTART': '1', 14:36:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:36:54 INFO - 'PWD': '/builds/slave/test-pgo', 14:36:54 INFO - 'SHELL': '/bin/bash', 14:36:54 INFO - 'SHLVL': '1', 14:36:54 INFO - 'TERM': 'linux', 14:36:54 INFO - 'TMOUT': '86400', 14:36:54 INFO - 'USER': 'cltbld', 14:36:54 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', 14:36:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:54 INFO - '_': '/tools/buildbot/bin/python'} 14:36:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:54 INFO - Downloading/unpacking psutil>=0.7.1 14:36:54 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 14:36:54 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 14:36:54 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 14:36:54 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 14:36:54 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 14:36:54 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 14:36:56 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 14:36:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 14:36:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 14:36:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:36:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:36:56 INFO - Installing collected packages: psutil 14:36:56 INFO - Running setup.py install for psutil 14:36:56 INFO - building 'psutil._psutil_linux' extension 14:36:56 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 14:36:56 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 14:36:56 INFO - building 'psutil._psutil_posix' extension 14:36:56 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 14:36:56 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 14:36:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:36:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:36:56 INFO - Successfully installed psutil 14:36:56 INFO - Cleaning up... 14:36:56 INFO - Return code: 0 14:36:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 14:36:56 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')]} 14:36:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:56 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')]} 14:36:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:56 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 0x7ff5a4276f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17aa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1839350>, '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 0x183a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183a6e0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', '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 14:36:56 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 14:36:56 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 14:36:56 INFO - Using env: {'DISPLAY': ':0', 14:36:56 INFO - 'HOME': '/home/cltbld', 14:36:56 INFO - 'LANG': 'en_US.UTF-8', 14:36:56 INFO - 'LANGUAGE': 'en_US:en', 14:36:56 INFO - 'LOGNAME': 'cltbld', 14:36:56 INFO - 'MAIL': '/var/mail/cltbld', 14:36:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:56 INFO - 'MOZ_NO_REMOTE': '1', 14:36:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:56 INFO - 'NO_EM_RESTART': '1', 14:36:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:36:56 INFO - 'PWD': '/builds/slave/test-pgo', 14:36:56 INFO - 'SHELL': '/bin/bash', 14:36:56 INFO - 'SHLVL': '1', 14:36:56 INFO - 'TERM': 'linux', 14:36:56 INFO - 'TMOUT': '86400', 14:36:56 INFO - 'USER': 'cltbld', 14:36:56 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', 14:36:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:56 INFO - '_': '/tools/buildbot/bin/python'} 14:36:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:36:56 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 14:36:56 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 14:36:56 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 14:36:56 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 14:36:56 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 14:36:56 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 14:36:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:36:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 14:36:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:36:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:36:58 INFO - Installing collected packages: mozsystemmonitor 14:36:58 INFO - Running setup.py install for mozsystemmonitor 14:36:58 INFO - Successfully installed mozsystemmonitor 14:36:58 INFO - Cleaning up... 14:36:58 INFO - Return code: 0 14:36:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 14:36:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:58 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5a4276f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17aa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1839350>, '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 0x183a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183a6e0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', '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 14:36:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 14:36:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:36:58 INFO - Using env: {'DISPLAY': ':0', 14:36:58 INFO - 'HOME': '/home/cltbld', 14:36:58 INFO - 'LANG': 'en_US.UTF-8', 14:36:58 INFO - 'LANGUAGE': 'en_US:en', 14:36:58 INFO - 'LOGNAME': 'cltbld', 14:36:58 INFO - 'MAIL': '/var/mail/cltbld', 14:36:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:58 INFO - 'MOZ_NO_REMOTE': '1', 14:36:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:58 INFO - 'NO_EM_RESTART': '1', 14:36:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:36:58 INFO - 'PWD': '/builds/slave/test-pgo', 14:36:58 INFO - 'SHELL': '/bin/bash', 14:36:58 INFO - 'SHLVL': '1', 14:36:58 INFO - 'TERM': 'linux', 14:36:58 INFO - 'TMOUT': '86400', 14:36:58 INFO - 'USER': 'cltbld', 14:36:58 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', 14:36:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:58 INFO - '_': '/tools/buildbot/bin/python'} 14:36:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:59 INFO - Downloading/unpacking blobuploader==1.2.4 14:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:00 INFO - Downloading blobuploader-1.2.4.tar.gz 14:37:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 14:37:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:37:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:37:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 14:37:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 14:37:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:37:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:01 INFO - Downloading docopt-0.6.1.tar.gz 14:37:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 14:37:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 14:37:01 INFO - Installing collected packages: blobuploader, requests, docopt 14:37:01 INFO - Running setup.py install for blobuploader 14:37:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:37:01 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 14:37:01 INFO - Running setup.py install for requests 14:37:02 INFO - Running setup.py install for docopt 14:37:02 INFO - Successfully installed blobuploader requests docopt 14:37:02 INFO - Cleaning up... 14:37:02 INFO - Return code: 0 14:37:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:37:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:37:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5a4276f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17aa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1839350>, '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 0x183a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183a6e0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', '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 14:37:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 14:37:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:37:02 INFO - Using env: {'DISPLAY': ':0', 14:37:02 INFO - 'HOME': '/home/cltbld', 14:37:02 INFO - 'LANG': 'en_US.UTF-8', 14:37:02 INFO - 'LANGUAGE': 'en_US:en', 14:37:02 INFO - 'LOGNAME': 'cltbld', 14:37:02 INFO - 'MAIL': '/var/mail/cltbld', 14:37:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:02 INFO - 'MOZ_NO_REMOTE': '1', 14:37:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:02 INFO - 'NO_EM_RESTART': '1', 14:37:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:37:02 INFO - 'PWD': '/builds/slave/test-pgo', 14:37:02 INFO - 'SHELL': '/bin/bash', 14:37:02 INFO - 'SHLVL': '1', 14:37:02 INFO - 'TERM': 'linux', 14:37:02 INFO - 'TMOUT': '86400', 14:37:02 INFO - 'USER': 'cltbld', 14:37:02 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', 14:37:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:02 INFO - '_': '/tools/buildbot/bin/python'} 14:37:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:37:02 INFO - Running setup.py (path:/tmp/pip-4YGOfl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:37:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:37:02 INFO - Running setup.py (path:/tmp/pip-E7zhpp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:37:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:37:02 INFO - Running setup.py (path:/tmp/pip-axsa07-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:37:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:37:02 INFO - Running setup.py (path:/tmp/pip-mwQZZL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:37:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:37:02 INFO - Running setup.py (path:/tmp/pip-vtbpFk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:37:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:37:02 INFO - Running setup.py (path:/tmp/pip-G0_HFN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:37:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:37:02 INFO - Running setup.py (path:/tmp/pip-POXF2E-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:37:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:37:03 INFO - Running setup.py (path:/tmp/pip-WkVoeu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:37:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:37:03 INFO - Running setup.py (path:/tmp/pip-LZ2Sy4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:37:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:37:03 INFO - Running setup.py (path:/tmp/pip-NapfKL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:37:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:37:03 INFO - Running setup.py (path:/tmp/pip-6rPT7X-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:37:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:37:03 INFO - Running setup.py (path:/tmp/pip-CglPuo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:37:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:37:03 INFO - Running setup.py (path:/tmp/pip-snvJiN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:37:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:37:03 INFO - Running setup.py (path:/tmp/pip-VVCsKC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:37:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:37:03 INFO - Running setup.py (path:/tmp/pip-ogevPF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:37:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:37:03 INFO - Running setup.py (path:/tmp/pip-WEK2uf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:37:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:37:03 INFO - Running setup.py (path:/tmp/pip-AdcsRx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:37:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:37:03 INFO - Running setup.py install for manifestparser 14:37:03 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 14:37:03 INFO - Running setup.py install for mozcrash 14:37:03 INFO - Running setup.py install for mozdebug 14:37:04 INFO - Running setup.py install for mozdevice 14:37:04 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 14:37:04 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 14:37:04 INFO - Running setup.py install for mozfile 14:37:04 INFO - Running setup.py install for mozhttpd 14:37:04 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 14:37:04 INFO - Running setup.py install for mozinfo 14:37:04 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 14:37:04 INFO - Running setup.py install for mozInstall 14:37:04 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 14:37:04 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 14:37:04 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 14:37:04 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 14:37:04 INFO - Running setup.py install for mozleak 14:37:04 INFO - Running setup.py install for mozlog 14:37:04 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 14:37:04 INFO - Running setup.py install for moznetwork 14:37:04 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 14:37:04 INFO - Running setup.py install for mozprocess 14:37:04 INFO - Running setup.py install for mozprofile 14:37:05 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 14:37:05 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 14:37:05 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 14:37:05 INFO - Running setup.py install for mozrunner 14:37:05 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 14:37:05 INFO - Running setup.py install for mozscreenshot 14:37:05 INFO - Running setup.py install for moztest 14:37:05 INFO - Running setup.py install for mozversion 14:37:05 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 14:37:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:37:05 INFO - Cleaning up... 14:37:05 INFO - Return code: 0 14:37:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:37:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:37:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:37:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5a4276f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17aa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1839350>, '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 0x183a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183a6e0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', '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 14:37:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 14:37:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:37:05 INFO - Using env: {'DISPLAY': ':0', 14:37:05 INFO - 'HOME': '/home/cltbld', 14:37:05 INFO - 'LANG': 'en_US.UTF-8', 14:37:05 INFO - 'LANGUAGE': 'en_US:en', 14:37:05 INFO - 'LOGNAME': 'cltbld', 14:37:05 INFO - 'MAIL': '/var/mail/cltbld', 14:37:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:05 INFO - 'MOZ_NO_REMOTE': '1', 14:37:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:05 INFO - 'NO_EM_RESTART': '1', 14:37:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:37:05 INFO - 'PWD': '/builds/slave/test-pgo', 14:37:05 INFO - 'SHELL': '/bin/bash', 14:37:05 INFO - 'SHLVL': '1', 14:37:05 INFO - 'TERM': 'linux', 14:37:05 INFO - 'TMOUT': '86400', 14:37:05 INFO - 'USER': 'cltbld', 14:37:05 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', 14:37:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:05 INFO - '_': '/tools/buildbot/bin/python'} 14:37:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:37:05 INFO - Running setup.py (path:/tmp/pip-X3gUUz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 14:37:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:37:05 INFO - Running setup.py (path:/tmp/pip-Hx8I96-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 14:37:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:37:05 INFO - Running setup.py (path:/tmp/pip-P_9BIj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 14:37:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:37:05 INFO - Running setup.py (path:/tmp/pip-idEDpB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:37:06 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)) 14:37:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:37:06 INFO - Running setup.py (path:/tmp/pip-xMVw_P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:37:06 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)) 14:37:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:37:06 INFO - Running setup.py (path:/tmp/pip-1VPU_F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 14:37:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:37:06 INFO - Running setup.py (path:/tmp/pip-Gf1bib-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 14:37:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:37:06 INFO - Running setup.py (path:/tmp/pip-ctb2I5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 14:37:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:37:06 INFO - Running setup.py (path:/tmp/pip-eVBP1p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 14:37:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:37:06 INFO - Running setup.py (path:/tmp/pip-idWQ6j-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 14:37:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:37:06 INFO - Running setup.py (path:/tmp/pip-P_foY6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 14:37:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:37:06 INFO - Running setup.py (path:/tmp/pip-Ozbkog-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 14:37:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:37:06 INFO - Running setup.py (path:/tmp/pip-OWYiJb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 14:37:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:37:06 INFO - Running setup.py (path:/tmp/pip-urHCIH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 14:37:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:37:06 INFO - Running setup.py (path:/tmp/pip-lLPmUq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 14:37:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:37:06 INFO - Running setup.py (path:/tmp/pip-c3TG6F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 14:37:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:37:06 INFO - Running setup.py (path:/tmp/pip-JMQHpp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 14:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 14:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 14:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 14:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 14:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 14:37:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 14:37:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:08 INFO - Downloading blessings-1.5.1.tar.gz 14:37:08 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 14:37:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 14:37:08 INFO - Installing collected packages: blessings 14:37:08 INFO - Running setup.py install for blessings 14:37:08 INFO - Successfully installed blessings 14:37:08 INFO - Cleaning up... 14:37:08 INFO - Return code: 0 14:37:08 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 14:37:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:37:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:37:09 INFO - Reading from file tmpfile_stdout 14:37:09 INFO - Current package versions: 14:37:09 INFO - argparse == 1.2.1 14:37:09 INFO - blessings == 1.5.1 14:37:09 INFO - blobuploader == 1.2.4 14:37:09 INFO - docopt == 0.6.1 14:37:09 INFO - manifestparser == 1.1 14:37:09 INFO - mozInstall == 1.12 14:37:09 INFO - mozcrash == 0.16 14:37:09 INFO - mozdebug == 0.1 14:37:09 INFO - mozdevice == 0.46 14:37:09 INFO - mozfile == 1.2 14:37:09 INFO - mozhttpd == 0.7 14:37:09 INFO - mozinfo == 0.8 14:37:09 INFO - mozleak == 0.1 14:37:09 INFO - mozlog == 3.0 14:37:09 INFO - moznetwork == 0.27 14:37:09 INFO - mozprocess == 0.22 14:37:09 INFO - mozprofile == 0.27 14:37:09 INFO - mozrunner == 6.11 14:37:09 INFO - mozscreenshot == 0.1 14:37:09 INFO - mozsystemmonitor == 0.0 14:37:09 INFO - moztest == 0.7 14:37:09 INFO - mozversion == 1.4 14:37:09 INFO - psutil == 3.1.1 14:37:09 INFO - requests == 1.2.3 14:37:09 INFO - wsgiref == 0.1.2 14:37:09 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:37:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:37:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5a4276f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17aa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1839350>, '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 0x183a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183a6e0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', '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 14:37:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 14:37:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:37:09 INFO - Using env: {'DISPLAY': ':0', 14:37:09 INFO - 'HOME': '/home/cltbld', 14:37:09 INFO - 'LANG': 'en_US.UTF-8', 14:37:09 INFO - 'LANGUAGE': 'en_US:en', 14:37:09 INFO - 'LOGNAME': 'cltbld', 14:37:09 INFO - 'MAIL': '/var/mail/cltbld', 14:37:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:09 INFO - 'MOZ_NO_REMOTE': '1', 14:37:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:09 INFO - 'NO_EM_RESTART': '1', 14:37:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:37:09 INFO - 'PWD': '/builds/slave/test-pgo', 14:37:09 INFO - 'SHELL': '/bin/bash', 14:37:09 INFO - 'SHLVL': '1', 14:37:09 INFO - 'TERM': 'linux', 14:37:09 INFO - 'TMOUT': '86400', 14:37:09 INFO - 'USER': 'cltbld', 14:37:09 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', 14:37:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:09 INFO - '_': '/tools/buildbot/bin/python'} 14:37:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 14:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 14:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 14:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 14:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 14:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 14:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 14:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 14:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 14:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 14:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 14:37:09 INFO - Cleaning up... 14:37:09 INFO - Return code: 0 14:37:09 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 14:37:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:37:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5a4276f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17aa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1839350>, '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 0x183a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183a6e0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', '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 14:37:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 14:37:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:37:09 INFO - Using env: {'DISPLAY': ':0', 14:37:09 INFO - 'HOME': '/home/cltbld', 14:37:09 INFO - 'LANG': 'en_US.UTF-8', 14:37:09 INFO - 'LANGUAGE': 'en_US:en', 14:37:09 INFO - 'LOGNAME': 'cltbld', 14:37:09 INFO - 'MAIL': '/var/mail/cltbld', 14:37:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:09 INFO - 'MOZ_NO_REMOTE': '1', 14:37:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:09 INFO - 'NO_EM_RESTART': '1', 14:37:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:37:09 INFO - 'PWD': '/builds/slave/test-pgo', 14:37:09 INFO - 'SHELL': '/bin/bash', 14:37:09 INFO - 'SHLVL': '1', 14:37:09 INFO - 'TERM': 'linux', 14:37:09 INFO - 'TMOUT': '86400', 14:37:09 INFO - 'USER': 'cltbld', 14:37:09 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', 14:37:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:09 INFO - '_': '/tools/buildbot/bin/python'} 14:37:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:09 INFO - Downloading/unpacking jsonschema 14:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:37:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 14:37:11 INFO - Downloading/unpacking functools32 (from jsonschema) 14:37:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:11 INFO - Downloading functools32-3.2.3-2.tar.gz 14:37:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 14:37:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 14:37:11 INFO - warning: no files found matching '*.txt' 14:37:11 INFO - no previously-included directories found matching 'build' 14:37:11 INFO - no previously-included directories found matching 'dist' 14:37:11 INFO - no previously-included directories found matching '.git*' 14:37:11 INFO - Installing collected packages: jsonschema, functools32 14:37:11 INFO - Running setup.py install for functools32 14:37:11 INFO - warning: no files found matching '*.txt' 14:37:11 INFO - no previously-included directories found matching 'build' 14:37:11 INFO - no previously-included directories found matching 'dist' 14:37:11 INFO - no previously-included directories found matching '.git*' 14:37:11 INFO - Successfully installed jsonschema functools32 14:37:11 INFO - Cleaning up... 14:37:11 INFO - Return code: 0 14:37:11 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 14:37:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:37:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:37:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:11 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5a4276f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17aa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1839350>, '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 0x183a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183a6e0>, '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': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', '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 14:37:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 14:37:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:37:11 INFO - Using env: {'DISPLAY': ':0', 14:37:11 INFO - 'HOME': '/home/cltbld', 14:37:11 INFO - 'LANG': 'en_US.UTF-8', 14:37:11 INFO - 'LANGUAGE': 'en_US:en', 14:37:11 INFO - 'LOGNAME': 'cltbld', 14:37:11 INFO - 'MAIL': '/var/mail/cltbld', 14:37:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:11 INFO - 'MOZ_NO_REMOTE': '1', 14:37:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:11 INFO - 'NO_EM_RESTART': '1', 14:37:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:37:11 INFO - 'PWD': '/builds/slave/test-pgo', 14:37:11 INFO - 'SHELL': '/bin/bash', 14:37:11 INFO - 'SHLVL': '1', 14:37:11 INFO - 'TERM': 'linux', 14:37:11 INFO - 'TMOUT': '86400', 14:37:11 INFO - 'USER': 'cltbld', 14:37:11 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', 14:37:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:11 INFO - '_': '/tools/buildbot/bin/python'} 14:37:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:11 INFO - Downloading/unpacking flake8 14:37:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:37:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 14:37:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:37:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:37:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 14:37:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:37:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:14 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 14:37:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 14:37:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 14:37:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:37:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:37:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:37:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:37:14 INFO - no previously-included directories found matching 'docs/_build' 14:37:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:37:14 INFO - Running setup.py install for pep8 14:37:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:37:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:37:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:37:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:37:14 INFO - no previously-included directories found matching 'docs/_build' 14:37:14 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 14:37:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:37:14 INFO - Cleaning up... 14:37:14 INFO - Return code: 0 14:37:14 INFO - Running post-action listener: _resource_record_post_action 14:37:14 INFO - Running post-action listener: _start_resource_monitoring 14:37:14 INFO - Starting resource monitoring. 14:37:14 INFO - ##### 14:37:14 INFO - ##### Running install step. 14:37:14 INFO - ##### 14:37:14 INFO - Running pre-action listener: _resource_record_pre_action 14:37:14 INFO - Running main action method: install 14:37:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:37:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:37:14 INFO - Reading from file tmpfile_stdout 14:37:14 INFO - Detecting whether we're running mozinstall >=1.0... 14:37:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 14:37:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 14:37:14 INFO - Reading from file tmpfile_stdout 14:37:14 INFO - Output received: 14:37:14 INFO - Usage: mozinstall [options] installer 14:37:14 INFO - Options: 14:37:14 INFO - -h, --help show this help message and exit 14:37:14 INFO - -d DEST, --destination=DEST 14:37:14 INFO - Directory to install application into. [default: 14:37:14 INFO - "/builds/slave/test-pgo"] 14:37:14 INFO - --app=APP Application being installed. [default: firefox] 14:37:14 INFO - mkdir: /builds/slave/test-pgo/build/application 14:37:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 14:37:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 14:37:29 INFO - Reading from file tmpfile_stdout 14:37:29 INFO - Output received: 14:37:29 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 14:37:29 INFO - Running post-action listener: _resource_record_post_action 14:37:29 INFO - ##### 14:37:29 INFO - ##### Running run-tests step. 14:37:29 INFO - ##### 14:37:29 INFO - Running pre-action listener: _resource_record_pre_action 14:37:29 INFO - Running main action method: run_tests 14:37:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 14:37:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 14:37:29 INFO - Python 2.7.3 14:37:29 INFO - Return code: 0 14:37:29 INFO - grabbing minidump binary from tooltool 14:37: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')]} 14:37:29 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 0x1839350>, '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 0x183a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183a6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 14:37:29 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 14:37:29 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 14:37:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:37:29 INFO - Return code: 0 14:37:29 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 14:37:29 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 14:37:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 14:37:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 14:37:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 14:37:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 14:37:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 14:37:30 INFO - Return code: 0 14:37:30 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/1451938350/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 14:37:30 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/1451938350/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-007 --webServer localhost 14:37:30 INFO - Using env: {'DISPLAY': ':0', 14:37:30 INFO - 'HOME': '/home/cltbld', 14:37:30 INFO - 'LANG': 'en_US.UTF-8', 14:37:30 INFO - 'LANGUAGE': 'en_US:en', 14:37:30 INFO - 'LOGNAME': 'cltbld', 14:37:30 INFO - 'MAIL': '/var/mail/cltbld', 14:37:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:37:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 14:37:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:30 INFO - 'MOZ_NO_REMOTE': '1', 14:37:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:37:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:30 INFO - 'NO_EM_RESTART': '1', 14:37:30 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:37:30 INFO - 'PWD': '/builds/slave/test-pgo', 14:37:30 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 14:37:30 INFO - 'SHELL': '/bin/bash', 14:37:30 INFO - 'SHLVL': '1', 14:37:30 INFO - 'TERM': 'linux', 14:37:30 INFO - 'TMOUT': '86400', 14:37:30 INFO - 'USER': 'cltbld', 14:37:30 INFO - 'XDG_SESSION_COOKIE': 'b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974', 14:37:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:30 INFO - '_': '/tools/buildbot/bin/python'} 14:37:30 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/1451938350/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--webServer', 'localhost'] with output_timeout 3600 14:37:30 INFO - mozversion INFO | application_buildid: 20160104121230 14:37:30 INFO - mozversion INFO | application_changeset: ec2b2ab4e57d328b63e4857f74f2311db7ad6c4f 14:37:30 INFO - mozversion INFO | application_display_name: Nightly 14:37:30 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:37:30 INFO - mozversion INFO | application_name: Firefox 14:37:30 INFO - mozversion INFO | application_remotingname: firefox 14:37:30 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:37:30 INFO - mozversion INFO | application_vendor: Mozilla 14:37:30 INFO - mozversion INFO | application_version: 44.0 14:37:30 INFO - mozversion INFO | platform_buildid: 20160104121230 14:37:30 INFO - mozversion INFO | platform_changeset: ec2b2ab4e57d328b63e4857f74f2311db7ad6c4f 14:37:30 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:37:30 INFO - mozversion INFO | platform_version: 44.0 14:37:30 INFO - 2016-01-04 14:37:30,259 DEBUG : using testdate: 1451947050 14:37:30 INFO - 2016-01-04 14:37:30,259 DEBUG : actual date: 1451947050 14:37:30 INFO - 2016-01-04 14:37:30,267 INFO : starting webserver on 'localhost:37720' 14:37:30 INFO - 2016-01-04 14:37:30,268 INFO : Starting test suite talos-linux64-ix-007 14:37:30 INFO - 2016-01-04 14:37:30,268 INFO : Starting test dromaeo_css 14:37:30 INFO - 2016-01-04 14:37:30,269 DEBUG : operating with platform_type : linux_ 14:37:30 INFO - 2016-01-04 14:37:30,269 INFO : Initialising browser for dromaeo_css test... 14:37:30 INFO - 2016-01-04 14:37:30,285 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpP6ueoJ/profile http://localhost:37720/getInfo.html 14:37:33 INFO - 2016-01-04 14:37:33,967 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:37:33 INFO - 2016-01-04 14:37:33,967 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:37:33 INFO - 2016-01-04 14:37:33,967 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:37:33 INFO - 2016-01-04 14:37:33,967 DEBUG : BROWSER_OUTPUT: __metrics 14:37:34 INFO - 2016-01-04 14:37:34,048 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] 14:37:35 INFO - 2016-01-04 14:37:35,309 INFO : Browser initialized. 14:37:35 INFO - 2016-01-04 14:37:35,310 INFO : Running cycle 1/1 for dromaeo_css test... 14:37:35 INFO - 2016-01-04 14:37:35,310 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpP6ueoJ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:37:46 INFO - 2016-01-04 14:37:46,840 DEBUG : BROWSER_OUTPUT: RSS: Main: 128602112 14:37:46 INFO - 2016-01-04 14:37:46,840 DEBUG : BROWSER_OUTPUT: 14:38:38 INFO - 2016-01-04 14:38:38,501 DEBUG : BROWSER_OUTPUT: 1451947118496 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] 14:40:45 INFO - 2016-01-04 14:40:45,719 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37720/tests/dromaeo/cssquery-ext.html) 14:40:46 INFO - 2016-01-04 14:40:46,763 DEBUG : BROWSER_OUTPUT: RSS: Main: 235642880 14:40:46 INFO - 2016-01-04 14:40:46,763 DEBUG : BROWSER_OUTPUT: 14:43:48 INFO - 2016-01-04 14:43:48,868 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/dromaeo/cssquery-ext.html (next: http://localhost:37720/tests/dromaeo/cssquery-jquery.html) 14:43:49 INFO - 2016-01-04 14:43:49,429 DEBUG : BROWSER_OUTPUT: RSS: Main: 201523200 14:43:49 INFO - 2016-01-04 14:43:49,429 DEBUG : BROWSER_OUTPUT: 14:46:51 INFO - 2016-01-04 14:46:51,201 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37720/tests/dromaeo/cssquery-mootools.html) 14:46:51 INFO - 2016-01-04 14:46:51,763 DEBUG : BROWSER_OUTPUT: RSS: Main: 201949184 14:46:51 INFO - 2016-01-04 14:46:51,763 DEBUG : BROWSER_OUTPUT: 14:49:53 INFO - 2016-01-04 14:49:53,384 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37720/tests/dromaeo/cssquery-prototype.html) 14:49:53 INFO - 2016-01-04 14:49:53,946 DEBUG : BROWSER_OUTPUT: RSS: Main: 190869504 14:49:53 INFO - 2016-01-04 14:49:53,946 DEBUG : BROWSER_OUTPUT: 14:52:56 INFO - 2016-01-04 14:52:56,253 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37720/tests/dromaeo/cssquery-yui.html) 14:52:56 INFO - 2016-01-04 14:52:56,839 DEBUG : BROWSER_OUTPUT: RSS: Main: 191942656 14:52:56 INFO - 2016-01-04 14:52:56,839 DEBUG : BROWSER_OUTPUT: 14:55:58 INFO - 2016-01-04 14:55:58,924 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/dromaeo/cssquery-yui.html (next: http://localhost:37720/tests/dromaeo/cssquery-dojo.html) 14:55:59 INFO - 2016-01-04 14:55:59,182 DEBUG : BROWSER_OUTPUT: RSS: Main: 231936000 14:55:59 INFO - 2016-01-04 14:55:59,183 DEBUG : BROWSER_OUTPUT: 14:55:59 INFO - 2016-01-04 14:55:59,183 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:55:59 INFO - 2016-01-04 14:55:59,183 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:55:59 INFO - 2016-01-04 14:55:59,183 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:55:59 INFO - 2016-01-04 14:55:59,183 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:55:59 INFO - 2016-01-04 14:55:59,183 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3236.7410607151423;3312.7846689965168;3340.148262193142;3347.715444187244;3366.7979921204724;5601.131824567188;5646.18130371096;5649.689267090308;5663.093904975205;5675.971620141904;5661.348944871341;5677.460641239086;5683.232763576916;5691.743871525781;5692.743826527806;5021.497850214986;5123.205903085028;5166.483351664822;5219.869503262411;5247.449017853128;5086.542211200991;5102.464241254671;5141.794328226866;5215;5235.1885457753915;5235.345581802275;5271.15661494161;5281.788728450857;5290.365156181264;5292.126799078147;5203.167493201107;5222.738863056832;5283.841484755463;5284.524392804647;5304.867378315535;4981.1532039553;5026.723530205837;5083.491650834924;5112.182050871843;5177.792888284464;5550.805721799758;5556.972215138899;5570.554355651579;5574.498295153456;5579.497845193952;5954.761809527614;6117.755289788403;6122.663253521055;6151.5386346024225;6188.721507532172;6070.02879539276;6165;6166.167567378391;6167.506599472032;6170.413810687977;5761.366249712528;5766.682832444214;5772.163036359746;5790.247267855194;5797.101449275327;6071.969640151771;6175.042868355412;6226.782062627786;6233.438990490878;6237.282712488031;5937.52499800015;6000.279966404059;6026.6986650667295;6043.637381757021;6047.455728984412;6158.230221222348;6162.106494558264;6163.013917773224;6167.044108163242;6184.226971628546;5981.132735753292;6066.575339726265;6080.665563394055;6130.632162070201;6136.631802091889;6131.835460087213;6163.1679723237685;6169;6170.753169873155;6175.536834737323;4720.409948756405;4720.457147428021;4722.362481065081;4722.905541889143;4727.527247275314;4103.630673239423;4112.3420252759415;4181.121964387513;4201.2017716633745;4209.1792100540715;5834.737436815353;5879.52963762898;5881.529477641779;5886.352501224862;5888.646681199141;6235.781747638902;6248.343923888017;6252.812415627538;6253.343398943137;6260.561760676709;5626.156076588462;5664.801731939362;5720.971395143081;5745.798897038667;5757.021306377842;5299.072662284085;5365.517103460707;5384.380796208487;5392.137258038773;5395.433479484676;6139.272759974883;6396.616203027833;6404.423601875854;6407.295197528269;6412.102305677209;6060.484858787053;6096.177016102858;6145.754169833157;6148.1699970503405;6155.538334624832;6308.211473565804;6356.586821856565;6359.427651511386;6360.650164241011;6361.968190159019;5943.108533719804;6073.362296958932;6100.908486372619;6107.541934354852;6115;6018.247719035121;6118.715069835384;6120.767567621542;6127.853107063011;6130.5402094842175;6035.305939816802;6047.576669633259;6058.57589968698;6060.787872424444;6061.787837425669;5816.302043754777;5932.584719069624;6040.577159598962;6042.90935635957;6055.2430946131735;5917.112433135064;6019.849503762441;6023.728932197974;6026.397360263939;6037.516998640186;6036.426539478743;6053.636781793107;6065.423784740619;6068.969655151696;6074.179985701874;5699.658020518782;5795.681237531976;5912.881742365214;5932.851678708067;5946.167536544974 14:55:59 INFO - 2016-01-04 14:55:59,183 DEBUG : BROWSER_OUTPUT: |1;ext.html;571.2796857961725;598.4583951523871;604.1481511069392;616.5221952986437;617.5461036138435;43386;44357.33463998035;44497.110057798825;44581;44648.33027504586;46757.532424675745;47603;47613.0477390452;47644.28533571999;47733;8132.715354962577;8228.670853165866;9191.080891910806;9410.858837117448;10769.246152769309;10984.945075274612;11110.500027498781;11111.111111111131;11111.444427778619;11114.277571957797;11080.556777728882;11164.162687798407;11182.105431565455;11184.496697648625;11191.2725672831;10940.288881222696;10941.890581094167;11045.889541104647;11046;11058.557657693682;10897.400642964634;11002.944985275102;11010.50452729629;11035.172362072797;11037.448127593669;44639.553604463865;44915.55084449147;45011.54988450139;45095;45210.32184517235;3639.3631114554846;3804.486394336757;4116.526599441073;4221.134667393177;4522.819087236507;33778;33837.6616233837;33972.660273397196;34050;34132.31735365304;3419.1623052352084;3574.3566158091535;3582.1223800168864;3781.2248489059675;3965.8215380307956;318699;322438;323606;326609;326890;60069;69292.30707692858;69806.30193698099;69814.65092674505;69827.65086174537;55985.72007139938;70298.64850675817;72218.27781722115;72443.27556724365;72699.27300727031;17937.910310448366;18130.909345453187;18182.18180181895;18206.908965455354;18239.179236934106;13896.027278090638;13973.371198296101;13976.74209321166;14008.649783755487;14013.719725605431;22199.445013874458;22552.21067262638;22563.323100307018;22682.092716291165;23354.64968025481;8570.614322355508;8724.476531408136;8729.738107856774;8755.168259015383;8792.164744349277;1011.4841430870162;1072.710368200597;1147.4148184426003;1159.860816702001;1173.3839734139644;30404.847975759978;30424.239394015327;30912.69087309098;31394.686053139176;31776;1130.7512347283584;1168.6844551970921;1184.6860581945791;1259.508791571288;1276.5978716704208;13923.860761392256;16020.519384418487;16047.759283610756;16230.918845405933;16262.268197930885;284366;289417;289449;290005;290138;53915.460845391826;54543.45456545463;54570;54575.45424545704;54611.726941365036;1324.6820763016806;1415.2782081138694;1485.1683057487842;1503.2408633640161;1610.5973506623345;28994.56508152337;30509.694903050684;33804;35358.46962295516;35440.64559354477;56631;57097.71451142718;57154.42845571491;57197.42802571921;57345.42654573401;344860;346783;349054;349156;350169;47477;47519.287210691175;47907.760461198865;48737.75631121964;48932.266016009075;50524.24213636872;51408;51695.741521292155;51892.221616676536;52088.47911520988;42536.36195457132;42550.57449425466;42772.786136069124;43164;44148.33777493276;42068.78965605152;42199.57800421956;42462.363064553436;42504.362434562885;42928.57071429246;41111.7944410276;41448.58551414447;42751.3587296197;42788;42818;37106.6289337114;38462;38531.5645240421;38707.61292387153;39067.21865562729;27392.315192120357;27887.302817429725;27953.72046279511;28118.859405702842;28170.718292816808 14:55:59 INFO - 2016-01-04 14:55:59,183 DEBUG : BROWSER_OUTPUT: |2;jquery.html;377.78277490443;380.41796052040377;381.9484369610104;389.52867030892617;389.91811719538896;15486.851492459129;17926.282948682052;18214.908925455355;19708.408747737572;19732.40802775919;13228.206307621549;13526.120802147854;13563.32183390829;18925.148368323426;19232.807671923278;4879.341288926003;4996.400431948171;5002.12462819007;5003.39959204896;5003.624728145385;3904.882853514385;3997.240524300388;4930.827421040264;5319.787208511655;5441.374241962186;5357.892842143175;5377.919331210035;5378.273933019051;5388.0570900092525;5397.46025397461;5228.058949389108;5302.920456193122;5339.893202135935;5345.919811202854;5352.464753524636;4641;4641.048585040093;4643.8142474300985;4648.418947631546;4654.883627909296;13883.583492495241;14323.713525729427;14353.067050641675;14398.280085995762;14409.495667651581;2152.300502336747;2184.7815218478186;2266.263464374085;2270.0692715986365;2380.47629521505;10653.094486968617;10668.359898406121;10855.131589472901;11065.336079835235;11376.488058037408;1697.9660406791918;1779.217144456435;1783.785945686513;1793.3364655077573;1813.8911665300125;66063;67001.32998670048;67766.66116669386;68700.6564967177;69153.65423172903;7841.255080767318;7846.254605812553;7865.095514015961;7890.881636775338;7905.486143400661;9174.403663761834;9197.678081267124;9215.53922303882;9220.723378298662;9265.861012084824;6408.134901788292;6488.351164883474;6493.902591461132;6532.738690452328;6542.182227221598;6928.099347084847;6932.445404367639;6934.791956241321;6975.372216500539;6995.300469953066;3667.2665466906724;3709.3137769512728;3711.777293362407;3734.066483379155;3742.9064273393383;1250.1311588445942;1269.9111062225556;1283.1146508908823;1315.8684131586765;1323.6168204226606;430.45762339452517;433.0710625707902;556.5964675610151;557.0474488624417;579.5276849367751;10891.945540272249;11135.109191264679;11267.267627604344;11704.414779261173;11872;549.4697616799807;556.0907915558091;561.472216116857;588.52917665868;589.239880554089;7020.613866237406;7079.646017699094;7144.964275178695;7273.0181425507935;7278.636068196569;572364;592126;597943.0205697975;599124;605058;85490.57254713686;87842.12157878467;88015.55992219997;88022;88318;619.9535034872403;622.3061286665426;622.7291128359178;624.8406656302614;624.9500039996881;11715.414229288672;12176.452059657513;12230.449629766505;12343.75312493763;12356.320402377596;34965.30069398547;38148.61851381451;38366;39513.2097358057;39673;49681.50318496769;49828;51381.743091284305;51489;51698.22452663288;7792.532448053135;7902.525848449111;7912.762617121495;7921.722739704082;7922.603869806602;12925.612231633066;13329.266890321125;13577.32113394346;13597.592072237849;13721;11773.470193841127;11818.349990750592;11979.221350612183;12078.51685932553;12125.514979400725;11310.717232068935;11722;11858.407079646156;11907.583234586747;12264.75470490603;9956.950215248877;10324.896751032695;12211.328376939353;12322.507099715911;12471.625851224479;6994.790156295319;7699.3840492761565;7793.688252469838;7797.337226335825;7810.609469526615;12207.877921220674;12310.384480776105;12449.439775209956;12524.123311368117;12531 14:55:59 INFO - 2016-01-04 14:55:59,184 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1574.2207607234418;1609.139110575842;1640.1389270632917;1831.5238038110087;1929.4211736479062;7319.780406587811;7469.4024478041765;7743.070831500221;7892.408069394797;7925.801854953622;7750.651220695068;7846.489978151416;7936.127026027147;7940.483868548555;7952.801179970504;2392.9162479313227;2420.1287536486852;2422.39440139965;2422.878856057199;2423.442608200115;2140.9250676226334;2178.455386153462;2367.8460900041528;2397.9640305395346;2399.928002159938;2348.3542025942834;2349.5418393413206;2350.459394339303;2358.3160883343808;2359.7994170495526;2385.8807059646947;2408.0969636386353;2412.155745489082;2413.384586930339;2414.5291668124637;2382.7617238276207;2420.056178090546;2425.611902095656;2425.684660994077;2426.1265944260053;8036.07585127712;8091.757247282591;8109.391795615294;8109.553974531398;8110.107888132301;4930.087933732271;4996.7001979881325;5000.549950504435;5002.5997920166665;5004.374453193351;3271.3293774776353;3301.636819949804;3369.1913940654313;3380.4760262159402;3400;1032.8450732390127;1038.5430410619304;1043.8956104389576;1053.752368193474;1066.4081434803682;10855.837162442569;10886.67339979802;10894.128469722404;10895.455227238606;10901.672949811518;4450.5104438511735;4472.731636101845;4544.182047231464;4546.636269098464;4547.522510136454;9287.674931377369;9324.393914395543;9326.72019839406;9329.766755831159;9334.71995840126;5005.349304590452;5009.922866583629;5010.849674509771;5015.699058056528;5017.67385119966;4592.219322715079;4604.148232576984;4604.953950460519;4606.170889239902;4608.078384323148;3608.0619039050034;3836.5779764225913;3848.692104631623;3874.1476875087437;3885.417187421909;1209.673388185185;1275.3431982529005;1283.2941881964882;1295.4688577683105;1298.3702904091508;1200.6338066889507;1420.7939848721878;1433.5914264434793;1458.4093442155943;1458.7009663019055;7698.923010769932;7755.650995705207;7803.843923121619;7825.295723384809;7834.451588388758;1193.5285562203055;1203.524607779922;1215.1251099208555;1227.9508819647292;1242.211195890598;6148.200733904653;6175.62946223228;6276.717547710273;6280.466160376419;6282.403171698681;9526.476043817656;9762.46306453152;9809.950950245204;9821.459819709984;9833.26250531213;9538.52307384628;9704.805903881883;9721.951390243144;9736.756581085388;9739.513024348755;7491.288327608869;7547.547147171187;7556.282153195548;7563.978862853444;7566.205548417337;7278.599677017814;7358.705651773869;7360.595167265852;7371.226021267689;7381.261873812576;8582;8708;8736.344774141837;8740.694075707319;8798.604062817061;8732.869006964773;8900.554972251231;8903.777405564913;8913.465192088495;9023.684171053981;7121.216666166614;7165.498415111101;7205.387542058984;7252.31103045231;7259;7771.533707977539;7816;7864.095629002738;7898.605069746604;7899.644515996909;7378.520396174446;7445.4415918805225;7459.328660420588;7515.624218788929;7542.547447153188;7202.495825292389;7344.122319300947;7376.557406555624;7435.405167586472;7446.739364122446;7450.637777389525;7481.17707052213;7509.3617042552;7516.849663006818;7521.323080922743;6864.141982252218;6912.896306555506;6924.203716572659;6924.61914594682;6926.134233220848;6914.377706006282;6955.269696681776;6958.686859091252;6958.895616565655;6960.338767817049 14:55:59 INFO - 2016-01-04 14:55:59,184 DEBUG : BROWSER_OUTPUT: |4;prototype.html;362.55224797375234;364.21329927356913;365.82806081141865;367.82160652083735;373.9682127019203;5907.85230369241;5993.940060599392;6031.547633927451;6038.909416358752;6041.939580604193;5827.184194212803;5871.9412805871925;5917.763289468416;5926.051831706928;5926.903522848276;4659.53404659533;4664.486906440289;4669.463011753658;4671.85984420468;4672.97663511682;4556.54434556545;4620.653450991189;4635.2120139576355;4669.579737823612;4721.0321884013865;4640.793393717641;4643.93034104487;4649.023705021949;4686.08621318843;4687.460941991681;4629.6990695604845;4640.280756482751;4641.466231383401;4645.674802763808;4661.7902194400995;4159.750414975111;4159.9792001040105;4161.895952601179;4167.2082304362075;4175.770332631704;5632.9436705632825;5743.0811070228565;5761.596688231825;5769.0192667246665;5790.218320526759;5506.531944784698;5509.449055094498;5509.752061157257;5509.972450137724;5511.366192887829;6377.2666143393635;6387.393197646216;6394.104825324458;6401.263854656728;6404.647744374058;4381.38660587518;4418.9337159942625;4669.252919532859;4669.813207471697;4672.345871577982;6307.117003619497;6413.5189860760165;6439.742410303629;6500.707468163897;6509.44669703071;4520.344550040226;4586.243269860435;4596.977015114903;4599.977000114978;4617.58441740245;4630.58324750774;4633.351330813689;4636.420447444069;4642.373279607277;4643.210654188796;5214.296070030573;5253.44838791929;5295.867603309871;5299.33758280215;5365.839024829262;6325.65208913505;6363.840903977345;6363.840903977438;6365.267994180728;6408.839779005562;1662.5677323895875;1663.7005339038847;1664.0681218517668;1668.6329007618303;1671.707451196036;1109.5949978257877;1111.3109871879326;1120.2158489057529;1147.5581900968186;1153.7692461507731;6185.195924529782;6230.688465576703;6231.40801623845;6231.9688401557705;6232.3144454110015;6571.539992200595;6584.967075164594;6620.635865027373;6625.138731964814;6627.304133066055;6050.606710563799;6142.262928448526;6146.815595532052;6180.752769889244;6200.81397558074;3598.676119149289;3614.674679278825;3615.367310720666;3618.7285953553587;3628.0748409155494;6576.342365763481;6591.703373348211;6635.469162466991;6640.103586015923;6645.169353830771;6597.703103360361;6629.138212032405;6632.5688830226845;6637.236717777518;6639.668016599151;6659.334066593302;6741.056252124707;6754.324567543206;6761.966190169116;6767.221769496571;6606.405423511907;6610.966945165435;6613.735450582115;6617.933820661732;6619.205695316593;6279.466245369194;6308.716107775253;6520.021996700533;6528.37980391862;6530.183727034121;6717.798466046026;6723.361280678328;6733.629650369081;6747.595144291358;6749.898751518829;4563.269876819693;4596.931046034245;4600.516945720651;4602.4477062752685;4603.217453033059;6435.903461447989;6555.475561955128;6567.343265673586;6567.540272180901;6569.96715016441;6387.201599800025;6391.712372943136;6400.295967443484;6400.647964362005;6424.357564243725;6475.384838440341;6477.352264773485;6482.027695845472;6487.935120648922;6491.123698300669;6268.260821874176;6343.841403964938;6352.174217351715;6359.141515895295;6364.6316042051185;6526;6526.510511711545;6531.444827189742;6534.052562378333;6542.771003014872;6364.045393191059;6371.872562548816;6378.266499352634;6378.585391949508;6381.489480841615 14:55:59 INFO - 2016-01-04 14:55:59,188 DEBUG : BROWSER_OUTPUT: |5;yui.html;787.181331415328;812.7521106062652;943.8914524829646;964.8855571814554;968.293146003418;11119.22165448418;11536;15941.681166376666;15961;15969.68060638788;12078.456469458873;12081.637550873465;12085.335306558136;12108;12113.212641178317;760.3309088002578;769.4114002787871;911.6307895302413;922.603280589348;938.6104766521885;932.2448816458656;932.4824722279133;936.9344145909789;940.6613619097119;943.5942544705774;978.3591747405461;985.4973963278744;985.7781999050228;987.3582171588489;987.4420952162005;789.2620399926059;797.7247849491945;958.6644674363987;966.6133546581353;970.5001924009141;713.750187434399;716.1477841368754;717.6375930155291;718.5796309159148;718.8921661750779;14601.123932563974;14671.33978970949;14695.338709758087;14701.852981470262;14705;11796.587119450864;12192;12281.631551053482;12290.078244131637;12494.06294527914;9887.110160085555;9911.20710343178;9949.10458058771;10001.249906256988;10151.898481015243;3511.087117349482;3551.1654761131113;3552.0942159749357;3553.8045407502577;3558;52661;52681.47318526804;52837.735811320694;53320.46679533233;53670.46329536733;9533.23734101284;9584.329096963285;9585.185259252901;9632.133108020313;9690.273229507675;19282.903585482265;19289.03554822253;19322.903385482983;19362.128704208346;19378.224871005285;7665.501742386727;7743.4966727162555;7806.843863122705;7859.882101768478;8105.27052565272;4210.578942105765;4227.7463352198965;4228.957710422918;4253.383259427365;4278.08021275421;3426.7087297579487;3462.082548124749;3474.3572439098343;3479.739019573542;3490.127468132967;485.75712143928035;502.0536289095009;506.4707380787086;507.73851466494784;509.35566508366946;472.97398643074274;516.7752027867889;528.2419727690776;570.8515785895697;594.4738906068161;8343.749687509384;8522.57387130641;8593.95703021481;8604.0965698602;8637.784055398666;447.1526457363278;557.6319629044892;595.9314678811905;603.5774957529659;615.6983078291695;4038.495188101487;4192.119654872511;4194.756554307104;4224.957750422518;4276.914461710749;69123.65438172877;70318.64840675764;70414;70562.64718676476;70590.2940970594;61189;61519;61657.38342616606;64254.35745642577;64656.03015954764;56279.718601406734;56430.717846411324;56464.43535564674;56637.43362566404;56889.14666280009;8114.797130071795;8176.632051557575;8183.181681831769;8380;8523.616437260214;41121.1775764489;41384.793076034424;41399.670313201976;42099;42347.57652423436;51855.48144518506;54652;55082.72458637681;55201;55204.44795551993;10398.324108932895;10681.786364272826;10744.355338679705;10779.353238805697;10976.560937562408;11046.116310695286;11166.609168679057;11239.606613768807;11253.212275140662;11500.194986350874;10625.840612390975;10764.300320479144;10818.350898945773;10847.566097356017;11060.281081729661;10502.737431564272;10574.894251057698;10870.891291087304;10892.40091794959;10956.780864382508;10166.644167454104;10368.948155259175;10442.269041167045;10490.475476226005;10650.254482186172;9394.483303418105;9528.857067144138;9566.1868741155;9901.455419951972;9958.402495850272;8446.915530844613;9411.623535058521;9912.553935072794;10213;10272.178225741773 14:55:59 INFO - 2016-01-04 14:55:59,188 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:55:59 INFO - 2016-01-04 14:55:59,188 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:55:59 INFO - 2016-01-04 14:55:59,189 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,800 14:55:59 INFO - 2016-01-04 14:55:59,189 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:55:59 INFO - 2016-01-04 14:55:59,189 DEBUG : BROWSER_OUTPUT: __startTimestamp1451948159175__endTimestamp 14:55:59 INFO - 2016-01-04 14:55:59,189 DEBUG : BROWSER_OUTPUT: 14:55:59 INFO - 2016-01-04 14:55:59,189 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:55:59 INFO - 2016-01-04 14:55:59,189 DEBUG : BROWSER_OUTPUT: Number of tests: 6 14:55:59 INFO - 2016-01-04 14:55:59,189 DEBUG : BROWSER_OUTPUT: 14:55:59 INFO - 2016-01-04 14:55:59,189 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5691.02 Median:5937.52 stddev:651.84 (11.0%) stddev-sans-first:624.75 14:55:59 INFO - 2016-01-04 14:55:59,191 DEBUG : BROWSER_OUTPUT: Values: 3236.7 3312.8 3340.1 3347.7 3366.8 5601.1 5646.2 5649.7 5663.1 5676.0 5661.3 5677.5 5683.2 5691.7 5692.7 5021.5 5123.2 5166.5 5219.9 5247.4 5086.5 5102.5 5141.8 5215.0 5235.2 5235.3 5271.2 5281.8 5290.4 5292.1 5203.2 5222.7 5283.8 5284.5 5304.9 4981.2 5026.7 5083.5 5112.2 5177.8 5550.8 5557.0 5570.6 5574.5 5579.5 5954.8 6117.8 6122.7 6151.5 6188.7 6070.0 6165.0 6166.2 6167.5 6170.4 5761.4 5766.7 5772.2 5790.2 5797.1 6072.0 6175.0 6226.8 6233.4 6237.3 5937.5 6000.3 6026.7 6043.6 6047.5 6158.2 6162.1 6163.0 6167.0 6184.2 5981.1 6066.6 6080.7 6130.6 6136.6 6131.8 6163.2 6169.0 6170.8 6175.5 4720.4 4720.5 4722.4 4722.9 4727.5 4103.6 4112.3 4181.1 4201.2 4209.2 5834.7 5879.5 5881.5 5886.4 5888.6 6235.8 6248.3 6252.8 6253.3 6260.6 5626.2 5664.8 5721.0 5745.8 5757.0 5299.1 5365.5 5384.4 5392.1 5395.4 6139.3 6396.6 6404.4 6407.3 6412.1 6060.5 6096.2 6145.8 6148.2 6155.5 6308.2 6356.6 6359.4 6360.7 6362.0 5943.1 6073.4 6100.9 6107.5 6115.0 6018.2 6118.7 6120.8 6127.9 6130.5 6035.3 6047.6 6058.6 6060.8 6061.8 5816.3 5932.6 6040.6 6042.9 6055.2 5917.1 6019.8 6023.7 6026.4 6037.5 6036.4 6053.6 6065.4 6069.0 6074.2 5699.7 5795.7 5912.9 5932.9 5946.2 14:55:59 INFO - 2016-01-04 14:55:59,191 DEBUG : BROWSER_OUTPUT: 14:55:59 INFO - 2016-01-04 14:55:59,191 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:52253.17 Median:35399.56 stddev:83660.64 (236.3%) stddev-sans-first:83805.34 14:55:59 INFO - 2016-01-04 14:55:59,191 DEBUG : BROWSER_OUTPUT: Values: 571.3 598.5 604.1 616.5 617.5 43386.0 44357.3 44497.1 44581.0 44648.3 46757.5 47603.0 47613.0 47644.3 47733.0 8132.7 8228.7 9191.1 9410.9 10769.2 10984.9 11110.5 11111.1 11111.4 11114.3 11080.6 11164.2 11182.1 11184.5 11191.3 10940.3 10941.9 11045.9 11046.0 11058.6 10897.4 11002.9 11010.5 11035.2 11037.4 44639.6 44915.6 45011.5 45095.0 45210.3 3639.4 3804.5 4116.5 4221.1 4522.8 33778.0 33837.7 33972.7 34050.0 34132.3 3419.2 3574.4 3582.1 3781.2 3965.8 318699.0 322438.0 323606.0 326609.0 326890.0 60069.0 69292.3 69806.3 69814.7 69827.7 55985.7 70298.6 72218.3 72443.3 72699.3 17937.9 18130.9 18182.2 18206.9 18239.2 13896.0 13973.4 13976.7 14008.6 14013.7 22199.4 22552.2 22563.3 22682.1 23354.6 8570.6 8724.5 8729.7 8755.2 8792.2 1011.5 1072.7 1147.4 1159.9 1173.4 30404.8 30424.2 30912.7 31394.7 31776.0 1130.8 1168.7 1184.7 1259.5 1276.6 13923.9 16020.5 16047.8 16230.9 16262.3 284366.0 289417.0 289449.0 290005.0 290138.0 53915.5 54543.5 54570.0 54575.5 54611.7 1324.7 1415.3 1485.2 1503.2 1610.6 28994.6 30509.7 33804.0 35358.5 35440.6 56631.0 57097.7 57154.4 57197.4 57345.4 344860.0 346783.0 349054.0 349156.0 350169.0 47477.0 47519.3 47907.8 48737.8 48932.3 50524.2 51408.0 51695.7 51892.2 52088.5 42536.4 42550.6 42772.8 43164.0 44148.3 42068.8 42199.6 42462.4 42504.4 42928.6 41111.8 41448.6 42751.4 42788.0 42818.0 37106.6 38462.0 38531.6 38707.6 39067.2 27392.3 27887.3 27953.7 28118.9 28170.7 14:55:59 INFO - 2016-01-04 14:55:59,191 DEBUG : BROWSER_OUTPUT: 14:55:59 INFO - 2016-01-04 14:55:59,191 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29738.88 Median:4646.12 stddev:97326.30 (2094.8%) stddev-sans-first:97574.35 14:55:59 INFO - 2016-01-04 14:55:59,191 DEBUG : BROWSER_OUTPUT: Values: 377.8 380.4 381.9 389.5 389.9 15486.9 17926.3 18214.9 19708.4 19732.4 13228.2 13526.1 13563.3 18925.1 19232.8 4879.3 4996.4 5002.1 5003.4 5003.6 3904.9 3997.2 4930.8 5319.8 5441.4 5357.9 5377.9 5378.3 5388.1 5397.5 5228.1 5302.9 5339.9 5345.9 5352.5 4641.0 4641.0 4643.8 4648.4 4654.9 13883.6 14323.7 14353.1 14398.3 14409.5 2152.3 2184.8 2266.3 2270.1 2380.5 10653.1 10668.4 10855.1 11065.3 11376.5 1698.0 1779.2 1783.8 1793.3 1813.9 66063.0 67001.3 67766.7 68700.7 69153.7 7841.3 7846.3 7865.1 7890.9 7905.5 9174.4 9197.7 9215.5 9220.7 9265.9 6408.1 6488.4 6493.9 6532.7 6542.2 6928.1 6932.4 6934.8 6975.4 6995.3 3667.3 3709.3 3711.8 3734.1 3742.9 1250.1 1269.9 1283.1 1315.9 1323.6 430.5 433.1 556.6 557.0 579.5 10891.9 11135.1 11267.3 11704.4 11872.0 549.5 556.1 561.5 588.5 589.2 7020.6 7079.6 7145.0 7273.0 7278.6 572364.0 592126.0 597943.0 599124.0 605058.0 85490.6 87842.1 88015.6 88022.0 88318.0 620.0 622.3 622.7 624.8 625.0 11715.4 12176.5 12230.4 12343.8 12356.3 34965.3 38148.6 38366.0 39513.2 39673.0 49681.5 49828.0 51381.7 51489.0 51698.2 7792.5 7902.5 7912.8 7921.7 7922.6 12925.6 13329.3 13577.3 13597.6 13721.0 11773.5 11818.3 11979.2 12078.5 12125.5 11310.7 11722.0 11858.4 11907.6 12264.8 9957.0 10324.9 12211.3 12322.5 12471.6 6994.8 7699.4 7793.7 7797.3 7810.6 12207.9 12310.4 12449.4 12524.1 12531.0 14:55:59 INFO - 2016-01-04 14:55:59,191 DEBUG : BROWSER_OUTPUT: 14:55:59 INFO - 2016-01-04 14:55:59,191 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5718.18 Median:6888.52 stddev:2892.81 (42.0%) stddev-sans-first:2884.16 14:55:59 INFO - 2016-01-04 14:55:59,192 DEBUG : BROWSER_OUTPUT: Values: 1574.2 1609.1 1640.1 1831.5 1929.4 7319.8 7469.4 7743.1 7892.4 7925.8 7750.7 7846.5 7936.1 7940.5 7952.8 2392.9 2420.1 2422.4 2422.9 2423.4 2140.9 2178.5 2367.8 2398.0 2399.9 2348.4 2349.5 2350.5 2358.3 2359.8 2385.9 2408.1 2412.2 2413.4 2414.5 2382.8 2420.1 2425.6 2425.7 2426.1 8036.1 8091.8 8109.4 8109.6 8110.1 4930.1 4996.7 5000.5 5002.6 5004.4 3271.3 3301.6 3369.2 3380.5 3400.0 1032.8 1038.5 1043.9 1053.8 1066.4 10855.8 10886.7 10894.1 10895.5 10901.7 4450.5 4472.7 4544.2 4546.6 4547.5 9287.7 9324.4 9326.7 9329.8 9334.7 5005.3 5009.9 5010.8 5015.7 5017.7 4592.2 4604.1 4605.0 4606.2 4608.1 3608.1 3836.6 3848.7 3874.1 3885.4 1209.7 1275.3 1283.3 1295.5 1298.4 1200.6 1420.8 1433.6 1458.4 1458.7 7698.9 7755.7 7803.8 7825.3 7834.5 1193.5 1203.5 1215.1 1228.0 1242.2 6148.2 6175.6 6276.7 6280.5 6282.4 9526.5 9762.5 9810.0 9821.5 9833.3 9538.5 9704.8 9722.0 9736.8 9739.5 7491.3 7547.5 7556.3 7564.0 7566.2 7278.6 7358.7 7360.6 7371.2 7381.3 8582.0 8708.0 8736.3 8740.7 8798.6 8732.9 8900.6 8903.8 8913.5 9023.7 7121.2 7165.5 7205.4 7252.3 7259.0 7771.5 7816.0 7864.1 7898.6 7899.6 7378.5 7445.4 7459.3 7515.6 7542.5 7202.5 7344.1 7376.6 7435.4 7446.7 7450.6 7481.2 7509.4 7516.8 7521.3 6864.1 6912.9 6924.2 6924.6 6926.1 6914.4 6955.3 6958.7 6958.9 6960.3 14:55:59 INFO - 2016-01-04 14:55:59,193 DEBUG : BROWSER_OUTPUT: 14:55:59 INFO - 2016-01-04 14:55:59,193 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5359.98 Median:6096.43 stddev:1578.19 (25.9%) stddev-sans-first:1537.40 14:55:59 INFO - 2016-01-04 14:55:59,193 DEBUG : BROWSER_OUTPUT: Values: 362.6 364.2 365.8 367.8 374.0 5907.9 5993.9 6031.5 6038.9 6041.9 5827.2 5871.9 5917.8 5926.1 5926.9 4659.5 4664.5 4669.5 4671.9 4673.0 4556.5 4620.7 4635.2 4669.6 4721.0 4640.8 4643.9 4649.0 4686.1 4687.5 4629.7 4640.3 4641.5 4645.7 4661.8 4159.8 4160.0 4161.9 4167.2 4175.8 5632.9 5743.1 5761.6 5769.0 5790.2 5506.5 5509.4 5509.8 5510.0 5511.4 6377.3 6387.4 6394.1 6401.3 6404.6 4381.4 4418.9 4669.3 4669.8 4672.3 6307.1 6413.5 6439.7 6500.7 6509.4 4520.3 4586.2 4597.0 4600.0 4617.6 4630.6 4633.4 4636.4 4642.4 4643.2 5214.3 5253.4 5295.9 5299.3 5365.8 6325.7 6363.8 6363.8 6365.3 6408.8 1662.6 1663.7 1664.1 1668.6 1671.7 1109.6 1111.3 1120.2 1147.6 1153.8 6185.2 6230.7 6231.4 6232.0 6232.3 6571.5 6585.0 6620.6 6625.1 6627.3 6050.6 6142.3 6146.8 6180.8 6200.8 3598.7 3614.7 3615.4 3618.7 3628.1 6576.3 6591.7 6635.5 6640.1 6645.2 6597.7 6629.1 6632.6 6637.2 6639.7 6659.3 6741.1 6754.3 6762.0 6767.2 6606.4 6611.0 6613.7 6617.9 6619.2 6279.5 6308.7 6520.0 6528.4 6530.2 6717.8 6723.4 6733.6 6747.6 6749.9 4563.3 4596.9 4600.5 4602.4 4603.2 6435.9 6555.5 6567.3 6567.5 6570.0 6387.2 6391.7 6400.3 6400.6 6424.4 6475.4 6477.4 6482.0 6487.9 6491.1 6268.3 6343.8 6352.2 6359.1 6364.6 6526.0 6526.5 6531.4 6534.1 6542.8 6364.0 6371.9 6378.3 6378.6 6381.5 14:55:59 INFO - 2016-01-04 14:55:59,193 DEBUG : BROWSER_OUTPUT: 14:55:59 INFO - 2016-01-04 14:55:59,193 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15299.07 Median:26682.99 stddev:19387.53 (72.7%) stddev-sans-first:19411.29 14:55:59 INFO - 2016-01-04 14:55:59,194 DEBUG : BROWSER_OUTPUT: Values: 787.2 812.8 943.9 964.9 968.3 11119.2 11536.0 15941.7 15961.0 15969.7 12078.5 12081.6 12085.3 12108.0 12113.2 760.3 769.4 911.6 922.6 938.6 932.2 932.5 936.9 940.7 943.6 978.4 985.5 985.8 987.4 987.4 789.3 797.7 958.7 966.6 970.5 713.8 716.1 717.6 718.6 718.9 14601.1 14671.3 14695.3 14701.9 14705.0 11796.6 12192.0 12281.6 12290.1 12494.1 9887.1 9911.2 9949.1 10001.2 10151.9 3511.1 3551.2 3552.1 3553.8 3558.0 52661.0 52681.5 52837.7 53320.5 53670.5 9533.2 9584.3 9585.2 9632.1 9690.3 19282.9 19289.0 19322.9 19362.1 19378.2 7665.5 7743.5 7806.8 7859.9 8105.3 4210.6 4227.7 4229.0 4253.4 4278.1 3426.7 3462.1 3474.4 3479.7 3490.1 485.8 502.1 506.5 507.7 509.4 473.0 516.8 528.2 570.9 594.5 8343.7 8522.6 8594.0 8604.1 8637.8 447.2 557.6 595.9 603.6 615.7 4038.5 4192.1 4194.8 4225.0 4276.9 69123.7 70318.6 70414.0 70562.6 70590.3 61189.0 61519.0 61657.4 64254.4 64656.0 56279.7 56430.7 56464.4 56637.4 56889.1 8114.8 8176.6 8183.2 8380.0 8523.6 41121.2 41384.8 41399.7 42099.0 42347.6 51855.5 54652.0 55082.7 55201.0 55204.4 10398.3 10681.8 10744.4 10779.4 10976.6 11046.1 11166.6 11239.6 11253.2 11500.2 10625.8 10764.3 10818.4 10847.6 11060.3 10502.7 10574.9 10870.9 10892.4 10956.8 10166.6 10368.9 10442.3 10490.5 10650.3 9394.5 9528.9 9566.2 9901.5 9958.4 8446.9 9411.6 9912.6 10213.0 10272.2 14:55:59 INFO - 2016-01-04 14:55:59,195 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:55:59 INFO - 2016-01-04 14:55:59,195 DEBUG : BROWSER_OUTPUT: 14:55:59 INFO - 2016-01-04 14:55:59,754 INFO : Browser exited with error code: 0 14:55:59 INFO - 2016-01-04 14:55:59,772 INFO : Completed test dromaeo_css (00:18:29) 14:55:59 INFO - 2016-01-04 14:55:59,772 INFO : Starting test kraken 14:55:59 INFO - 2016-01-04 14:55:59,772 DEBUG : operating with platform_type : linux_ 14:55:59 INFO - 2016-01-04 14:55:59,772 INFO : Initialising browser for kraken test... 14:55:59 INFO - 2016-01-04 14:55:59,779 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBtoGuj/profile http://localhost:37720/getInfo.html 14:56:03 INFO - 2016-01-04 14:56:03,398 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:56:03 INFO - 2016-01-04 14:56:03,398 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:56:03 INFO - 2016-01-04 14:56:03,398 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:56:03 INFO - 2016-01-04 14:56:03,398 DEBUG : BROWSER_OUTPUT: __metrics 14:56:03 INFO - 2016-01-04 14:56:03,521 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] 14:56:04 INFO - 2016-01-04 14:56:04,777 INFO : Browser initialized. 14:56:04 INFO - 2016-01-04 14:56:04,778 INFO : Running cycle 1/1 for kraken test... 14:56:04 INFO - 2016-01-04 14:56:04,778 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBtoGuj/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:56:16 INFO - 2016-01-04 14:56:16,257 DEBUG : BROWSER_OUTPUT: RSS: Main: 128819200 14:56:16 INFO - 2016-01-04 14:56:16,258 DEBUG : BROWSER_OUTPUT: 14:56:18 INFO - 2016-01-04 14:56:18,680 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37720/tests/kraken/driver.html?testName=audio-beat-detection) 14:56:19 INFO - 2016-01-04 14:56:19,253 DEBUG : BROWSER_OUTPUT: RSS: Main: 164655104 14:56:19 INFO - 2016-01-04 14:56:19,254 DEBUG : BROWSER_OUTPUT: 14:56:21 INFO - 2016-01-04 14:56:21,802 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37720/tests/kraken/driver.html?testName=audio-dft) 14:56:22 INFO - 2016-01-04 14:56:22,367 DEBUG : BROWSER_OUTPUT: RSS: Main: 161955840 14:56:22 INFO - 2016-01-04 14:56:22,368 DEBUG : BROWSER_OUTPUT: 14:56:25 INFO - 2016-01-04 14:56:25,171 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37720/tests/kraken/driver.html?testName=audio-fft) 14:56:25 INFO - 2016-01-04 14:56:25,733 DEBUG : BROWSER_OUTPUT: RSS: Main: 157876224 14:56:25 INFO - 2016-01-04 14:56:25,733 DEBUG : BROWSER_OUTPUT: 14:56:27 INFO - 2016-01-04 14:56:27,705 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37720/tests/kraken/driver.html?testName=audio-oscillator) 14:56:28 INFO - 2016-01-04 14:56:28,266 DEBUG : BROWSER_OUTPUT: RSS: Main: 158248960 14:56:28 INFO - 2016-01-04 14:56:28,266 DEBUG : BROWSER_OUTPUT: 14:56:30 INFO - 2016-01-04 14:56:30,404 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37720/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:56:30 INFO - 2016-01-04 14:56:30,966 DEBUG : BROWSER_OUTPUT: RSS: Main: 157921280 14:56:30 INFO - 2016-01-04 14:56:30,966 DEBUG : BROWSER_OUTPUT: 14:56:33 INFO - 2016-01-04 14:56:33,812 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37720/tests/kraken/driver.html?testName=imaging-darkroom) 14:56:34 INFO - 2016-01-04 14:56:34,379 DEBUG : BROWSER_OUTPUT: RSS: Main: 156151808 14:56:34 INFO - 2016-01-04 14:56:34,379 DEBUG : BROWSER_OUTPUT: 14:56:37 INFO - 2016-01-04 14:56:37,700 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37720/tests/kraken/driver.html?testName=imaging-desaturate) 14:56:38 INFO - 2016-01-04 14:56:38,270 DEBUG : BROWSER_OUTPUT: RSS: Main: 157597696 14:56:38 INFO - 2016-01-04 14:56:38,271 DEBUG : BROWSER_OUTPUT: 14:56:40 INFO - 2016-01-04 14:56:40,999 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37720/tests/kraken/driver.html?testName=json-parse-financial) 14:56:41 INFO - 2016-01-04 14:56:41,572 DEBUG : BROWSER_OUTPUT: RSS: Main: 157089792 14:56:41 INFO - 2016-01-04 14:56:41,573 DEBUG : BROWSER_OUTPUT: 14:56:43 INFO - 2016-01-04 14:56:43,085 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37720/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:56:43 INFO - 2016-01-04 14:56:43,647 DEBUG : BROWSER_OUTPUT: RSS: Main: 161398784 14:56:43 INFO - 2016-01-04 14:56:43,647 DEBUG : BROWSER_OUTPUT: 14:56:45 INFO - 2016-01-04 14:56:45,397 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37720/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:56:46 INFO - 2016-01-04 14:56:45,999 DEBUG : BROWSER_OUTPUT: RSS: Main: 162148352 14:56:46 INFO - 2016-01-04 14:56:45,999 DEBUG : BROWSER_OUTPUT: 14:56:47 INFO - 2016-01-04 14:56:47,996 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37720/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:56:48 INFO - 2016-01-04 14:56:48,565 DEBUG : BROWSER_OUTPUT: RSS: Main: 166322176 14:56:48 INFO - 2016-01-04 14:56:48,565 DEBUG : BROWSER_OUTPUT: 14:56:50 INFO - 2016-01-04 14:56:50,843 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37720/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:56:51 INFO - 2016-01-04 14:56:51,423 DEBUG : BROWSER_OUTPUT: RSS: Main: 169910272 14:56:51 INFO - 2016-01-04 14:56:51,424 DEBUG : BROWSER_OUTPUT: 14:56:53 INFO - 2016-01-04 14:56:53,818 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37720/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:56:54 INFO - 2016-01-04 14:56:54,385 DEBUG : BROWSER_OUTPUT: RSS: Main: 169246720 14:56:54 INFO - 2016-01-04 14:56:54,385 DEBUG : BROWSER_OUTPUT: 14:56:55 INFO - 2016-01-04 14:56:55,985 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37720/tests/kraken/driver.html?testName=ai-astar) 14:56:56 INFO - 2016-01-04 14:56:56,235 DEBUG : BROWSER_OUTPUT: RSS: Main: 228360192 14:56:56 INFO - 2016-01-04 14:56:56,236 DEBUG : BROWSER_OUTPUT: 14:56:56 INFO - 2016-01-04 14:56:56,236 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:56:56 INFO - 2016-01-04 14:56:56,237 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:56:56 INFO - 2016-01-04 14:56:56,237 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:56:56 INFO - 2016-01-04 14:56:56,237 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:56:56 INFO - 2016-01-04 14:56:56,238 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;104;109;109;111;100;109;106;110 14:56:56 INFO - 2016-01-04 14:56:56,238 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;144;121;156;161;124;149;147;123;173;124 14:56:56 INFO - 2016-01-04 14:56:56,238 DEBUG : BROWSER_OUTPUT: |2;audio-dft;164;160;158;160;160;159;160;163;160;159 14:56:56 INFO - 2016-01-04 14:56:56,238 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;72;146;71;72;72;71;164;70 14:56:56 INFO - 2016-01-04 14:56:56,238 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;88;145;89;90;89;130;88;89;132 14:56:56 INFO - 2016-01-04 14:56:56,238 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;110;109;109;109;110;110;109;109 14:56:56 INFO - 2016-01-04 14:56:56,238 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;146;145;145;146;145;146;146;146 14:56:56 INFO - 2016-01-04 14:56:56,238 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;96;95;96;96;97;96;95 14:56:56 INFO - 2016-01-04 14:56:56,239 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;56;56;58;56;56;59;58;53;55 14:56:56 INFO - 2016-01-04 14:56:56,239 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;65;63;64;65;64;65;65;64;65 14:56:56 INFO - 2016-01-04 14:56:56,239 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;78;80;80;86;79;90;77;83;77 14:56:56 INFO - 2016-01-04 14:56:56,239 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;115;108;106;107;113;117;107;114;108;114 14:56:56 INFO - 2016-01-04 14:56:56,239 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;128;138;144;131;138;136;139;141;137;127 14:56:56 INFO - 2016-01-04 14:56:56,239 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;62;62;65;61;61;60;62;60;60;61 14:56:56 INFO - 2016-01-04 14:56:56,239 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:56:56 INFO - 2016-01-04 14:56:56,240 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:56:56 INFO - 2016-01-04 14:56:56,240 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,879 14:56:56 INFO - 2016-01-04 14:56:56,240 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:56:56 INFO - 2016-01-04 14:56:56,240 DEBUG : BROWSER_OUTPUT: __startTimestamp1451948216235__endTimestamp 14:56:56 INFO - 2016-01-04 14:56:56,240 DEBUG : BROWSER_OUTPUT: 14:56:56 INFO - 2016-01-04 14:56:56,243 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:56:56 INFO - 2016-01-04 14:56:56,244 DEBUG : BROWSER_OUTPUT: Number of tests: 14 14:56:56 INFO - 2016-01-04 14:56:56,244 DEBUG : BROWSER_OUTPUT: 14:56:56 INFO - 2016-01-04 14:56:56,244 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.60 Median:109.00 stddev:3.37 (3.1%) stddev-sans-first:3.57 14:56:56 INFO - 2016-01-04 14:56:56,244 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 104.0 109.0 109.0 111.0 100.0 109.0 106.0 110.0 14:56:56 INFO - 2016-01-04 14:56:56,244 DEBUG : BROWSER_OUTPUT: 14:56:56 INFO - 2016-01-04 14:56:56,245 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.20 Median:148.00 stddev:18.40 (12.4%) stddev-sans-first:19.50 14:56:56 INFO - 2016-01-04 14:56:56,245 DEBUG : BROWSER_OUTPUT: Values: 144.0 121.0 156.0 161.0 124.0 149.0 147.0 123.0 173.0 124.0 14:56:56 INFO - 2016-01-04 14:56:56,245 DEBUG : BROWSER_OUTPUT: 14:56:56 INFO - 2016-01-04 14:56:56,245 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.30 Median:160.00 stddev:1.83 (1.1%) stddev-sans-first:1.36 14:56:56 INFO - 2016-01-04 14:56:56,245 DEBUG : BROWSER_OUTPUT: Values: 164.0 160.0 158.0 160.0 160.0 159.0 160.0 163.0 160.0 159.0 14:56:56 INFO - 2016-01-04 14:56:56,245 DEBUG : BROWSER_OUTPUT: 14:56:56 INFO - 2016-01-04 14:56:56,246 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.10 Median:71.50 stddev:35.52 (49.7%) stddev-sans-first:37.19 14:56:56 INFO - 2016-01-04 14:56:56,246 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 72.0 146.0 71.0 72.0 72.0 71.0 164.0 70.0 14:56:56 INFO - 2016-01-04 14:56:56,246 DEBUG : BROWSER_OUTPUT: 14:56:56 INFO - 2016-01-04 14:56:56,246 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.90 Median:89.00 stddev:22.94 (25.8%) stddev-sans-first:23.78 14:56:56 INFO - 2016-01-04 14:56:56,246 DEBUG : BROWSER_OUTPUT: Values: 89.0 88.0 145.0 89.0 90.0 89.0 130.0 88.0 89.0 132.0 14:56:56 INFO - 2016-01-04 14:56:56,246 DEBUG : BROWSER_OUTPUT: 14:56:56 INFO - 2016-01-04 14:56:56,247 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.40 Median:109.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 14:56:56 INFO - 2016-01-04 14:56:56,247 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 110.0 109.0 109.0 109.0 110.0 110.0 109.0 109.0 14:56:56 INFO - 2016-01-04 14:56:56,247 DEBUG : BROWSER_OUTPUT: 14:56:56 INFO - 2016-01-04 14:56:56,247 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 14:56:56 INFO - 2016-01-04 14:56:56,247 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 146.0 145.0 145.0 146.0 145.0 146.0 146.0 146.0 14:56:56 INFO - 2016-01-04 14:56:56,247 DEBUG : BROWSER_OUTPUT: 14:56:56 INFO - 2016-01-04 14:56:56,248 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 14:56:56 INFO - 2016-01-04 14:56:56,248 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 96.0 95.0 96.0 96.0 97.0 96.0 95.0 14:56:56 INFO - 2016-01-04 14:56:56,248 DEBUG : BROWSER_OUTPUT: 14:56:56 INFO - 2016-01-04 14:56:56,248 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.30 Median:56.00 stddev:1.70 (3.0%) stddev-sans-first:1.80 14:56:56 INFO - 2016-01-04 14:56:56,249 DEBUG : BROWSER_OUTPUT: Values: 56.0 56.0 56.0 58.0 56.0 56.0 59.0 58.0 53.0 55.0 14:56:56 INFO - 2016-01-04 14:56:56,249 DEBUG : BROWSER_OUTPUT: 14:56:56 INFO - 2016-01-04 14:56:56,249 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.40 Median:65.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 14:56:56 INFO - 2016-01-04 14:56:56,249 DEBUG : BROWSER_OUTPUT: Values: 64.0 65.0 63.0 64.0 65.0 64.0 65.0 65.0 64.0 65.0 14:56:56 INFO - 2016-01-04 14:56:56,249 DEBUG : BROWSER_OUTPUT: 14:56:56 INFO - 2016-01-04 14:56:56,249 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:80.00 stddev:4.37 (5.5%) stddev-sans-first:4.43 14:56:56 INFO - 2016-01-04 14:56:56,249 DEBUG : BROWSER_OUTPUT: Values: 77.0 78.0 80.0 80.0 86.0 79.0 90.0 77.0 83.0 77.0 14:56:56 INFO - 2016-01-04 14:56:56,249 DEBUG : BROWSER_OUTPUT: 14:56:56 INFO - 2016-01-04 14:56:56,250 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:110.90 Median:113.50 stddev:4.07 (3.6%) stddev-sans-first:4.03 14:56:56 INFO - 2016-01-04 14:56:56,250 DEBUG : BROWSER_OUTPUT: Values: 115.0 108.0 106.0 107.0 113.0 117.0 107.0 114.0 108.0 114.0 14:56:56 INFO - 2016-01-04 14:56:56,250 DEBUG : BROWSER_OUTPUT: 14:56:56 INFO - 2016-01-04 14:56:56,250 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:135.90 Median:138.00 stddev:5.55 (4.0%) stddev-sans-first:5.09 14:56:56 INFO - 2016-01-04 14:56:56,250 DEBUG : BROWSER_OUTPUT: Values: 128.0 138.0 144.0 131.0 138.0 136.0 139.0 141.0 137.0 127.0 14:56:56 INFO - 2016-01-04 14:56:56,250 DEBUG : BROWSER_OUTPUT: 14:56:56 INFO - 2016-01-04 14:56:56,250 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.40 Median:61.50 stddev:1.51 (2.4%) stddev-sans-first:1.58 14:56:56 INFO - 2016-01-04 14:56:56,250 DEBUG : BROWSER_OUTPUT: Values: 62.0 62.0 65.0 61.0 61.0 60.0 62.0 60.0 60.0 61.0 14:56:56 INFO - 2016-01-04 14:56:56,250 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:56:56 INFO - 2016-01-04 14:56:56,250 DEBUG : BROWSER_OUTPUT: 14:56:56 INFO - 2016-01-04 14:56:56,921 INFO : Browser exited with error code: 0 14:56:56 INFO - 2016-01-04 14:56:56,975 INFO : Completed test kraken (00:00:57) 14:56:56 INFO - 2016-01-04 14:56:56,975 INFO : Starting test v8_7 14:56:56 INFO - 2016-01-04 14:56:56,975 DEBUG : operating with platform_type : linux_ 14:56:56 INFO - 2016-01-04 14:56:56,976 INFO : Initialising browser for v8_7 test... 14:56:56 INFO - 2016-01-04 14:56:56,982 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppdWYsp/profile http://localhost:37720/getInfo.html 14:57:00 INFO - 2016-01-04 14:57:00,605 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:57:00 INFO - 2016-01-04 14:57:00,605 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:57:00 INFO - 2016-01-04 14:57:00,605 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:57:00 INFO - 2016-01-04 14:57:00,605 DEBUG : BROWSER_OUTPUT: __metrics 14:57:00 INFO - 2016-01-04 14:57:00,762 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] 14:57:01 INFO - 2016-01-04 14:57:01,981 INFO : Browser initialized. 14:57:01 INFO - 2016-01-04 14:57:01,982 INFO : Running cycle 1/1 for v8_7 test... 14:57:01 INFO - 2016-01-04 14:57:01,983 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppdWYsp/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:57:13 INFO - 2016-01-04 14:57:13,481 DEBUG : BROWSER_OUTPUT: RSS: Main: 128421888 14:57:13 INFO - 2016-01-04 14:57:13,481 DEBUG : BROWSER_OUTPUT: 14:57:34 INFO - 2016-01-04 14:57:34,876 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37720/tests/v8_7/run.html (next: http://localhost:37720/tests/v8_7/run.html) 14:57:35 INFO - 2016-01-04 14:57:35,120 DEBUG : BROWSER_OUTPUT: RSS: Main: 429588480 14:57:35 INFO - 2016-01-04 14:57:35,120 DEBUG : BROWSER_OUTPUT: 14:57:35 INFO - 2016-01-04 14:57:35,121 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:57:35 INFO - 2016-01-04 14:57:35,121 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:57:35 INFO - 2016-01-04 14:57:35,122 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:57:35 INFO - 2016-01-04 14:57:35,122 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:57:35 INFO - 2016-01-04 14:57:35,122 DEBUG : BROWSER_OUTPUT: |0;Richards;163.55904481517828 14:57:35 INFO - 2016-01-04 14:57:35,122 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.8233686368295 14:57:35 INFO - 2016-01-04 14:57:35,122 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.09572983533354 14:57:35 INFO - 2016-01-04 14:57:35,122 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5065.656565656565 14:57:35 INFO - 2016-01-04 14:57:35,123 DEBUG : BROWSER_OUTPUT: |4;RayTrace;944.2870632672333 14:57:35 INFO - 2016-01-04 14:57:35,123 DEBUG : BROWSER_OUTPUT: |5;Earley;551.5719801434087 14:57:35 INFO - 2016-01-04 14:57:35,123 DEBUG : BROWSER_OUTPUT: |6;Boyer;10892.47311827957 14:57:35 INFO - 2016-01-04 14:57:35,123 DEBUG : BROWSER_OUTPUT: |7;RegExp;23744.18604651163 14:57:35 INFO - 2016-01-04 14:57:35,123 DEBUG : BROWSER_OUTPUT: |8;Splay;554.3237250554324 14:57:35 INFO - 2016-01-04 14:57:35,123 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 14:57:35 INFO - 2016-01-04 14:57:35,124 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:57:35 INFO - 2016-01-04 14:57:35,124 DEBUG : BROWSER_OUTPUT: __startTimestamp1451948255121__endTimestamp 14:57:35 INFO - 2016-01-04 14:57:35,124 DEBUG : BROWSER_OUTPUT: 14:57:35 INFO - 2016-01-04 14:57:35,124 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:57:35 INFO - 2016-01-04 14:57:35,124 DEBUG : BROWSER_OUTPUT: Number of tests: 10 14:57:35 INFO - 2016-01-04 14:57:35,125 DEBUG : BROWSER_OUTPUT: 14:57:35 INFO - 2016-01-04 14:57:35,125 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.56 Median:163.56 stddev:0.00 (0.0%) 14:57:35 INFO - 2016-01-04 14:57:35,125 DEBUG : BROWSER_OUTPUT: Values: 163.6 14:57:35 INFO - 2016-01-04 14:57:35,125 DEBUG : BROWSER_OUTPUT: 14:57:35 INFO - 2016-01-04 14:57:35,125 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.82 Median:170.82 stddev:0.00 (0.0%) 14:57:35 INFO - 2016-01-04 14:57:35,127 DEBUG : BROWSER_OUTPUT: Values: 170.8 14:57:35 INFO - 2016-01-04 14:57:35,127 DEBUG : BROWSER_OUTPUT: 14:57:35 INFO - 2016-01-04 14:57:35,127 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.10 Median:279.10 stddev:0.00 (0.0%) 14:57:35 INFO - 2016-01-04 14:57:35,127 DEBUG : BROWSER_OUTPUT: Values: 279.1 14:57:35 INFO - 2016-01-04 14:57:35,127 DEBUG : BROWSER_OUTPUT: 14:57:35 INFO - 2016-01-04 14:57:35,127 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5065.66 Median:5065.66 stddev:0.00 (0.0%) 14:57:35 INFO - 2016-01-04 14:57:35,128 DEBUG : BROWSER_OUTPUT: Values: 5065.7 14:57:35 INFO - 2016-01-04 14:57:35,128 DEBUG : BROWSER_OUTPUT: 14:57:35 INFO - 2016-01-04 14:57:35,128 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:944.29 Median:944.29 stddev:0.00 (0.0%) 14:57:35 INFO - 2016-01-04 14:57:35,128 DEBUG : BROWSER_OUTPUT: Values: 944.3 14:57:35 INFO - 2016-01-04 14:57:35,128 DEBUG : BROWSER_OUTPUT: 14:57:35 INFO - 2016-01-04 14:57:35,128 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:551.57 Median:551.57 stddev:0.00 (0.0%) 14:57:35 INFO - 2016-01-04 14:57:35,128 DEBUG : BROWSER_OUTPUT: Values: 551.6 14:57:35 INFO - 2016-01-04 14:57:35,128 DEBUG : BROWSER_OUTPUT: 14:57:35 INFO - 2016-01-04 14:57:35,129 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10892.47 Median:10892.47 stddev:0.00 (0.0%) 14:57:35 INFO - 2016-01-04 14:57:35,129 DEBUG : BROWSER_OUTPUT: Values: 10892.5 14:57:35 INFO - 2016-01-04 14:57:35,129 DEBUG : BROWSER_OUTPUT: 14:57:35 INFO - 2016-01-04 14:57:35,129 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23744.19 Median:23744.19 stddev:0.00 (0.0%) 14:57:35 INFO - 2016-01-04 14:57:35,129 DEBUG : BROWSER_OUTPUT: Values: 23744.2 14:57:35 INFO - 2016-01-04 14:57:35,129 DEBUG : BROWSER_OUTPUT: 14:57:35 INFO - 2016-01-04 14:57:35,129 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:554.32 Median:554.32 stddev:0.00 (0.0%) 14:57:35 INFO - 2016-01-04 14:57:35,129 DEBUG : BROWSER_OUTPUT: Values: 554.3 14:57:35 INFO - 2016-01-04 14:57:35,129 DEBUG : BROWSER_OUTPUT: 14:57:35 INFO - 2016-01-04 14:57:35,129 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 14:57:35 INFO - 2016-01-04 14:57:35,129 DEBUG : BROWSER_OUTPUT: Values: 4826.9 14:57:35 INFO - 2016-01-04 14:57:35,130 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:57:35 INFO - 2016-01-04 14:57:35,130 DEBUG : BROWSER_OUTPUT: 14:57:35 INFO - 2016-01-04 14:57:35,798 INFO : Browser exited with error code: 0 14:57:35 INFO - 2016-01-04 14:57:35,814 INFO : Completed test v8_7 (00:00:38) 14:57:36 INFO - 2016-01-04 14:57:36,145 INFO : Completed test suite (00:20:05) 14:57:36 INFO - 2016-01-04 14:57:36,145 DEBUG : Working with test: dromaeo_css 14:57:36 INFO - 2016-01-04 14:57:36,145 DEBUG : Generating results file: dromaeo_css 14:57:36 INFO - 2016-01-04 14:57:36,147 DEBUG : Working with test: kraken 14:57:36 INFO - 2016-01-04 14:57:36,147 DEBUG : Generating results file: kraken 14:57:36 INFO - 2016-01-04 14:57:36,148 INFO : javascript benchmark 14:57:36 INFO - 2016-01-04 14:57:36,148 DEBUG : Working with test: v8_7 14:57:36 INFO - 2016-01-04 14:57:36,148 DEBUG : Generating results file: v8_7 14:57:36 INFO - 2016-01-04 14:57:36,149 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:57:36 INFO - 2016-01-04 14:57:36,199 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 14:57:36 INFO - 2016-01-04 14:57:36,200 DEBUG : process_Request line: dromaeo_css 7186.04 graph.html#tests=[[72,53,35]] 14:57:36 INFO - 2016-01-04 14:57:36,200 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:57:36 INFO - 2016-01-04 14:57:36,232 DEBUG : process_Request line: kraken 1461.40 graph.html#tests=[[232,53,35]] 14:57:36 INFO - 2016-01-04 14:57:36,232 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:57:36 INFO - 2016-01-04 14:57:36,262 DEBUG : process_Request line: v8_7 23122.68 graph.html#tests=[[230,53,35]] 14:57:36 INFO - 2016-01-04 14:57:36,263 INFO : javascript benchmark 14:57:36 INFO - 2016-01-04 14:57:36,267 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3236.7410607151423, 3312.7846689965168, 3340.148262193142, 3347.715444187244, 3366.7979921204724, 5601.131824567188, 5646.18130371096, 5649.689267090308, 5663.093904975205, 5675.971620141904, 5661.348944871341, 5677.460641239086, 5683.232763576916, 5691.743871525781, 5692.743826527806, 5021.497850214986, 5123.205903085028, 5166.483351664822, 5219.869503262411, 5247.449017853128, 5086.542211200991, 5102.464241254671, 5141.794328226866, 5215.0, 5235.1885457753915, 5235.345581802275, 5271.15661494161, 5281.788728450857, 5290.365156181264, 5292.126799078147, 5203.167493201107, 5222.738863056832, 5283.841484755463, 5284.524392804647, 5304.867378315535, 4981.1532039553, 5026.723530205837, 5083.491650834924, 5112.182050871843, 5177.792888284464, 5550.805721799758, 5556.972215138899, 5570.554355651579, 5574.498295153456, 5579.497845193952, 5954.761809527614, 6117.755289788403, 6122.663253521055, 6151.5386346024225, 6188.721507532172, 6070.02879539276, 6165.0, 6166.167567378391, 6167.506599472032, 6170.413810687977, 5761.366249712528, 5766.682832444214, 5772.163036359746, 5790.247267855194, 5797.101449275327, 6071.969640151771, 6175.042868355412, 6226.782062627786, 6233.438990490878, 6237.282712488031, 5937.52499800015, 6000.279966404059, 6026.6986650667295, 6043.637381757021, 6047.455728984412, 6158.230221222348, 6162.106494558264, 6163.013917773224, 6167.044108163242, 6184.226971628546, 5981.132735753292, 6066.575339726265, 6080.665563394055, 6130.632162070201, 6136.631802091889, 6131.835460087213, 6163.1679723237685, 6169.0, 6170.753169873155, 6175.536834737323, 4720.409948756405, 4720.457147428021, 4722.362481065081, 4722.905541889143, 4727.527247275314, 4103.630673239423, 4112.3420252759415, 4181.121964387513, 4201.2017716633745, 4209.1792100540715, 5834.737436815353, 5879.52963762898, 5881.529477641779, 5886.352501224862, 5888.646681199141, 6235.781747638902, 6248.343923888017, 6252.812415627538, 6253.343398943137, 6260.561760676709, 5626.156076588462, 5664.801731939362, 5720.971395143081, 5745.798897038667, 5757.021306377842, 5299.072662284085, 5365.517103460707, 5384.380796208487, 5392.137258038773, 5395.433479484676, 6139.272759974883, 6396.616203027833, 6404.423601875854, 6407.295197528269, 6412.102305677209, 6060.484858787053, 6096.177016102858, 6145.754169833157, 6148.1699970503405, 6155.538334624832, 6308.211473565804, 6356.586821856565, 6359.427651511386, 6360.650164241011, 6361.968190159019, 5943.108533719804, 6073.362296958932, 6100.908486372619, 6107.541934354852, 6115.0, 6018.247719035121, 6118.715069835384, 6120.767567621542, 6127.853107063011, 6130.5402094842175, 6035.305939816802, 6047.576669633259, 6058.57589968698, 6060.787872424444, 6061.787837425669, 5816.302043754777, 5932.584719069624, 6040.577159598962, 6042.90935635957, 6055.2430946131735, 5917.112433135064, 6019.849503762441, 6023.728932197974, 6026.397360263939, 6037.516998640186, 6036.426539478743, 6053.636781793107, 6065.423784740619, 6068.969655151696, 6074.179985701874, 5699.658020518782, 5795.681237531976, 5912.881742365214, 5932.851678708067, 5946.167536544974], "unit": "ms", "name": "dojo.html", "value": 5646.723055005583}, {"lowerIsBetter": false, "replicates": [571.2796857961725, 598.4583951523871, 604.1481511069392, 616.5221952986437, 617.5461036138435, 43386.0, 44357.33463998035, 44497.110057798825, 44581.0, 44648.33027504586, 46757.532424675745, 47603.0, 47613.0477390452, 47644.28533571999, 47733.0, 8132.715354962577, 8228.670853165866, 9191.080891910806, 9410.858837117448, 10769.246152769309, 10984.945075274612, 11110.500027498781, 11111.111111111131, 11111.444427778619, 11114.277571957797, 11080.556777728882, 11164.162687798407, 11182.105431565455, 11184.496697648625, 11191.2725672831, 10940.288881222696, 10941.890581094167, 11045.889541104647, 11046.0, 11058.557657693682, 10897.400642964634, 11002.944985275102, 11010.50452729629, 11035.172362072797, 11037.448127593669, 44639.553604463865, 44915.55084449147, 45011.54988450139, 45095.0, 45210.32184517235, 3639.3631114554846, 3804.486394336757, 4116.526599441073, 4221.134667393177, 4522.819087236507, 33778.0, 33837.6616233837, 33972.660273397196, 34050.0, 34132.31735365304, 3419.1623052352084, 3574.3566158091535, 3582.1223800168864, 3781.2248489059675, 3965.8215380307956, 318699.0, 322438.0, 323606.0, 326609.0, 326890.0, 60069.0, 69292.30707692858, 69806.30193698099, 69814.65092674505, 69827.65086174537, 55985.72007139938, 70298.64850675817, 72218.27781722115, 72443.27556724365, 72699.27300727031, 17937.910310448366, 18130.909345453187, 18182.18180181895, 18206.908965455354, 18239.179236934106, 13896.027278090638, 13973.371198296101, 13976.74209321166, 14008.649783755487, 14013.719725605431, 22199.445013874458, 22552.21067262638, 22563.323100307018, 22682.092716291165, 23354.64968025481, 8570.614322355508, 8724.476531408136, 8729.738107856774, 8755.168259015383, 8792.164744349277, 1011.4841430870162, 1072.710368200597, 1147.4148184426003, 1159.860816702001, 1173.3839734139644, 30404.847975759978, 30424.239394015327, 30912.69087309098, 31394.686053139176, 31776.0, 1130.7512347283584, 1168.6844551970921, 1184.6860581945791, 1259.508791571288, 1276.5978716704208, 13923.860761392256, 16020.519384418487, 16047.759283610756, 16230.918845405933, 16262.268197930885, 284366.0, 289417.0, 289449.0, 290005.0, 290138.0, 53915.460845391826, 54543.45456545463, 54570.0, 54575.45424545704, 54611.726941365036, 1324.6820763016806, 1415.2782081138694, 1485.1683057487842, 1503.2408633640161, 1610.5973506623345, 28994.56508152337, 30509.694903050684, 33804.0, 35358.46962295516, 35440.64559354477, 56631.0, 57097.71451142718, 57154.42845571491, 57197.42802571921, 57345.42654573401, 344860.0, 346783.0, 349054.0, 349156.0, 350169.0, 47477.0, 47519.287210691175, 47907.760461198865, 48737.75631121964, 48932.266016009075, 50524.24213636872, 51408.0, 51695.741521292155, 51892.221616676536, 52088.47911520988, 42536.36195457132, 42550.57449425466, 42772.786136069124, 43164.0, 44148.33777493276, 42068.78965605152, 42199.57800421956, 42462.363064553436, 42504.362434562885, 42928.57071429246, 41111.7944410276, 41448.58551414447, 42751.3587296197, 42788.0, 42818.0, 37106.6289337114, 38462.0, 38531.5645240421, 38707.61292387153, 39067.21865562729, 27392.315192120357, 27887.302817429725, 27953.72046279511, 28118.859405702842, 28170.718292816808], "unit": "ms", "name": "ext.html", "value": 21239.00165422323}, {"lowerIsBetter": false, "replicates": [377.78277490443, 380.41796052040377, 381.9484369610104, 389.52867030892617, 389.91811719538896, 15486.851492459129, 17926.282948682052, 18214.908925455355, 19708.408747737572, 19732.40802775919, 13228.206307621549, 13526.120802147854, 13563.32183390829, 18925.148368323426, 19232.807671923278, 4879.341288926003, 4996.400431948171, 5002.12462819007, 5003.39959204896, 5003.624728145385, 3904.882853514385, 3997.240524300388, 4930.827421040264, 5319.787208511655, 5441.374241962186, 5357.892842143175, 5377.919331210035, 5378.273933019051, 5388.0570900092525, 5397.46025397461, 5228.058949389108, 5302.920456193122, 5339.893202135935, 5345.919811202854, 5352.464753524636, 4641.0, 4641.048585040093, 4643.8142474300985, 4648.418947631546, 4654.883627909296, 13883.583492495241, 14323.713525729427, 14353.067050641675, 14398.280085995762, 14409.495667651581, 2152.300502336747, 2184.7815218478186, 2266.263464374085, 2270.0692715986365, 2380.47629521505, 10653.094486968617, 10668.359898406121, 10855.131589472901, 11065.336079835235, 11376.488058037408, 1697.9660406791918, 1779.217144456435, 1783.785945686513, 1793.3364655077573, 1813.8911665300125, 66063.0, 67001.32998670048, 67766.66116669386, 68700.6564967177, 69153.65423172903, 7841.255080767318, 7846.254605812553, 7865.095514015961, 7890.881636775338, 7905.486143400661, 9174.403663761834, 9197.678081267124, 9215.53922303882, 9220.723378298662, 9265.861012084824, 6408.134901788292, 6488.351164883474, 6493.902591461132, 6532.738690452328, 6542.182227221598, 6928.099347084847, 6932.445404367639, 6934.791956241321, 6975.372216500539, 6995.300469953066, 3667.2665466906724, 3709.3137769512728, 3711.777293362407, 3734.066483379155, 3742.9064273393383, 1250.1311588445942, 1269.9111062225556, 1283.1146508908823, 1315.8684131586765, 1323.6168204226606, 430.45762339452517, 433.0710625707902, 556.5964675610151, 557.0474488624417, 579.5276849367751, 10891.945540272249, 11135.109191264679, 11267.267627604344, 11704.414779261173, 11872.0, 549.4697616799807, 556.0907915558091, 561.472216116857, 588.52917665868, 589.239880554089, 7020.613866237406, 7079.646017699094, 7144.964275178695, 7273.0181425507935, 7278.636068196569, 572364.0, 592126.0, 597943.0205697975, 599124.0, 605058.0, 85490.57254713686, 87842.12157878467, 88015.55992219997, 88022.0, 88318.0, 619.9535034872403, 622.3061286665426, 622.7291128359178, 624.8406656302614, 624.9500039996881, 11715.414229288672, 12176.452059657513, 12230.449629766505, 12343.75312493763, 12356.320402377596, 34965.30069398547, 38148.61851381451, 38366.0, 39513.2097358057, 39673.0, 49681.50318496769, 49828.0, 51381.743091284305, 51489.0, 51698.22452663288, 7792.532448053135, 7902.525848449111, 7912.762617121495, 7921.722739704082, 7922.603869806602, 12925.612231633066, 13329.266890321125, 13577.32113394346, 13597.592072237849, 13721.0, 11773.470193841127, 11818.349990750592, 11979.221350612183, 12078.51685932553, 12125.514979400725, 11310.717232068935, 11722.0, 11858.407079646156, 11907.583234586747, 12264.75470490603, 9956.950215248877, 10324.896751032695, 12211.328376939353, 12322.507099715911, 12471.625851224479, 6994.790156295319, 7699.3840492761565, 7793.688252469838, 7797.337226335825, 7810.609469526615, 12207.877921220674, 12310.384480776105, 12449.439775209956, 12524.123311368117, 12531.0], "unit": "ms", "name": "jquery.html", "value": 7656.165530633511}, {"lowerIsBetter": false, "replicates": [1574.2207607234418, 1609.139110575842, 1640.1389270632917, 1831.5238038110087, 1929.4211736479062, 7319.780406587811, 7469.4024478041765, 7743.070831500221, 7892.408069394797, 7925.801854953622, 7750.651220695068, 7846.489978151416, 7936.127026027147, 7940.483868548555, 7952.801179970504, 2392.9162479313227, 2420.1287536486852, 2422.39440139965, 2422.878856057199, 2423.442608200115, 2140.9250676226334, 2178.455386153462, 2367.8460900041528, 2397.9640305395346, 2399.928002159938, 2348.3542025942834, 2349.5418393413206, 2350.459394339303, 2358.3160883343808, 2359.7994170495526, 2385.8807059646947, 2408.0969636386353, 2412.155745489082, 2413.384586930339, 2414.5291668124637, 2382.7617238276207, 2420.056178090546, 2425.611902095656, 2425.684660994077, 2426.1265944260053, 8036.07585127712, 8091.757247282591, 8109.391795615294, 8109.553974531398, 8110.107888132301, 4930.087933732271, 4996.7001979881325, 5000.549950504435, 5002.5997920166665, 5004.374453193351, 3271.3293774776353, 3301.636819949804, 3369.1913940654313, 3380.4760262159402, 3400.0, 1032.8450732390127, 1038.5430410619304, 1043.8956104389576, 1053.752368193474, 1066.4081434803682, 10855.837162442569, 10886.67339979802, 10894.128469722404, 10895.455227238606, 10901.672949811518, 4450.5104438511735, 4472.731636101845, 4544.182047231464, 4546.636269098464, 4547.522510136454, 9287.674931377369, 9324.393914395543, 9326.72019839406, 9329.766755831159, 9334.71995840126, 5005.349304590452, 5009.922866583629, 5010.849674509771, 5015.699058056528, 5017.67385119966, 4592.219322715079, 4604.148232576984, 4604.953950460519, 4606.170889239902, 4608.078384323148, 3608.0619039050034, 3836.5779764225913, 3848.692104631623, 3874.1476875087437, 3885.417187421909, 1209.673388185185, 1275.3431982529005, 1283.2941881964882, 1295.4688577683105, 1298.3702904091508, 1200.6338066889507, 1420.7939848721878, 1433.5914264434793, 1458.4093442155943, 1458.7009663019055, 7698.923010769932, 7755.650995705207, 7803.843923121619, 7825.295723384809, 7834.451588388758, 1193.5285562203055, 1203.524607779922, 1215.1251099208555, 1227.9508819647292, 1242.211195890598, 6148.200733904653, 6175.62946223228, 6276.717547710273, 6280.466160376419, 6282.403171698681, 9526.476043817656, 9762.46306453152, 9809.950950245204, 9821.459819709984, 9833.26250531213, 9538.52307384628, 9704.805903881883, 9721.951390243144, 9736.756581085388, 9739.513024348755, 7491.288327608869, 7547.547147171187, 7556.282153195548, 7563.978862853444, 7566.205548417337, 7278.599677017814, 7358.705651773869, 7360.595167265852, 7371.226021267689, 7381.261873812576, 8582.0, 8708.0, 8736.344774141837, 8740.694075707319, 8798.604062817061, 8732.869006964773, 8900.554972251231, 8903.777405564913, 8913.465192088495, 9023.684171053981, 7121.216666166614, 7165.498415111101, 7205.387542058984, 7252.31103045231, 7259.0, 7771.533707977539, 7816.0, 7864.095629002738, 7898.605069746604, 7899.644515996909, 7378.520396174446, 7445.4415918805225, 7459.328660420588, 7515.624218788929, 7542.547447153188, 7202.495825292389, 7344.122319300947, 7376.557406555624, 7435.405167586472, 7446.739364122446, 7450.637777389525, 7481.17707052213, 7509.3617042552, 7516.849663006818, 7521.323080922743, 6864.141982252218, 6912.896306555506, 6924.203716572659, 6924.61914594682, 6926.134233220848, 6914.377706006282, 6955.269696681776, 6958.686859091252, 6958.895616565655, 6960.338767817049], "unit": "ms", "name": "mootools.html", "value": 4740.627856108407}, {"lowerIsBetter": false, "replicates": [362.55224797375234, 364.21329927356913, 365.82806081141865, 367.82160652083735, 373.9682127019203, 5907.85230369241, 5993.940060599392, 6031.547633927451, 6038.909416358752, 6041.939580604193, 5827.184194212803, 5871.9412805871925, 5917.763289468416, 5926.051831706928, 5926.903522848276, 4659.53404659533, 4664.486906440289, 4669.463011753658, 4671.85984420468, 4672.97663511682, 4556.54434556545, 4620.653450991189, 4635.2120139576355, 4669.579737823612, 4721.0321884013865, 4640.793393717641, 4643.93034104487, 4649.023705021949, 4686.08621318843, 4687.460941991681, 4629.6990695604845, 4640.280756482751, 4641.466231383401, 4645.674802763808, 4661.7902194400995, 4159.750414975111, 4159.9792001040105, 4161.895952601179, 4167.2082304362075, 4175.770332631704, 5632.9436705632825, 5743.0811070228565, 5761.596688231825, 5769.0192667246665, 5790.218320526759, 5506.531944784698, 5509.449055094498, 5509.752061157257, 5509.972450137724, 5511.366192887829, 6377.2666143393635, 6387.393197646216, 6394.104825324458, 6401.263854656728, 6404.647744374058, 4381.38660587518, 4418.9337159942625, 4669.252919532859, 4669.813207471697, 4672.345871577982, 6307.117003619497, 6413.5189860760165, 6439.742410303629, 6500.707468163897, 6509.44669703071, 4520.344550040226, 4586.243269860435, 4596.977015114903, 4599.977000114978, 4617.58441740245, 4630.58324750774, 4633.351330813689, 4636.420447444069, 4642.373279607277, 4643.210654188796, 5214.296070030573, 5253.44838791929, 5295.867603309871, 5299.33758280215, 5365.839024829262, 6325.65208913505, 6363.840903977345, 6363.840903977438, 6365.267994180728, 6408.839779005562, 1662.5677323895875, 1663.7005339038847, 1664.0681218517668, 1668.6329007618303, 1671.707451196036, 1109.5949978257877, 1111.3109871879326, 1120.2158489057529, 1147.5581900968186, 1153.7692461507731, 6185.195924529782, 6230.688465576703, 6231.40801623845, 6231.9688401557705, 6232.3144454110015, 6571.539992200595, 6584.967075164594, 6620.635865027373, 6625.138731964814, 6627.304133066055, 6050.606710563799, 6142.262928448526, 6146.815595532052, 6180.752769889244, 6200.81397558074, 3598.676119149289, 3614.674679278825, 3615.367310720666, 3618.7285953553587, 3628.0748409155494, 6576.342365763481, 6591.703373348211, 6635.469162466991, 6640.103586015923, 6645.169353830771, 6597.703103360361, 6629.138212032405, 6632.5688830226845, 6637.236717777518, 6639.668016599151, 6659.334066593302, 6741.056252124707, 6754.324567543206, 6761.966190169116, 6767.221769496571, 6606.405423511907, 6610.966945165435, 6613.735450582115, 6617.933820661732, 6619.205695316593, 6279.466245369194, 6308.716107775253, 6520.021996700533, 6528.37980391862, 6530.183727034121, 6717.798466046026, 6723.361280678328, 6733.629650369081, 6747.595144291358, 6749.898751518829, 4563.269876819693, 4596.931046034245, 4600.516945720651, 4602.4477062752685, 4603.217453033059, 6435.903461447989, 6555.475561955128, 6567.343265673586, 6567.540272180901, 6569.96715016441, 6387.201599800025, 6391.712372943136, 6400.295967443484, 6400.647964362005, 6424.357564243725, 6475.384838440341, 6477.352264773485, 6482.027695845472, 6487.935120648922, 6491.123698300669, 6268.260821874176, 6343.841403964938, 6352.174217351715, 6359.141515895295, 6364.6316042051185, 6526.0, 6526.510511711545, 6531.444827189742, 6534.052562378333, 6542.771003014872, 6364.045393191059, 6371.872562548816, 6378.266499352634, 6378.585391949508, 6381.489480841615], "unit": "ms", "name": "prototype.html", "value": 4857.978727414539}, {"lowerIsBetter": false, "replicates": [787.181331415328, 812.7521106062652, 943.8914524829646, 964.8855571814554, 968.293146003418, 11119.22165448418, 11536.0, 15941.681166376666, 15961.0, 15969.68060638788, 12078.456469458873, 12081.637550873465, 12085.335306558136, 12108.0, 12113.212641178317, 760.3309088002578, 769.4114002787871, 911.6307895302413, 922.603280589348, 938.6104766521885, 932.2448816458656, 932.4824722279133, 936.9344145909789, 940.6613619097119, 943.5942544705774, 978.3591747405461, 985.4973963278744, 985.7781999050228, 987.3582171588489, 987.4420952162005, 789.2620399926059, 797.7247849491945, 958.6644674363987, 966.6133546581353, 970.5001924009141, 713.750187434399, 716.1477841368754, 717.6375930155291, 718.5796309159148, 718.8921661750779, 14601.123932563974, 14671.33978970949, 14695.338709758087, 14701.852981470262, 14705.0, 11796.587119450864, 12192.0, 12281.631551053482, 12290.078244131637, 12494.06294527914, 9887.110160085555, 9911.20710343178, 9949.10458058771, 10001.249906256988, 10151.898481015243, 3511.087117349482, 3551.1654761131113, 3552.0942159749357, 3553.8045407502577, 3558.0, 52661.0, 52681.47318526804, 52837.735811320694, 53320.46679533233, 53670.46329536733, 9533.23734101284, 9584.329096963285, 9585.185259252901, 9632.133108020313, 9690.273229507675, 19282.903585482265, 19289.03554822253, 19322.903385482983, 19362.128704208346, 19378.224871005285, 7665.501742386727, 7743.4966727162555, 7806.843863122705, 7859.882101768478, 8105.27052565272, 4210.578942105765, 4227.7463352198965, 4228.957710422918, 4253.383259427365, 4278.08021275421, 3426.7087297579487, 3462.082548124749, 3474.3572439098343, 3479.739019573542, 3490.127468132967, 485.75712143928035, 502.0536289095009, 506.4707380787086, 507.73851466494784, 509.35566508366946, 472.97398643074274, 516.7752027867889, 528.2419727690776, 570.8515785895697, 594.4738906068161, 8343.749687509384, 8522.57387130641, 8593.95703021481, 8604.0965698602, 8637.784055398666, 447.1526457363278, 557.6319629044892, 595.9314678811905, 603.5774957529659, 615.6983078291695, 4038.495188101487, 4192.119654872511, 4194.756554307104, 4224.957750422518, 4276.914461710749, 69123.65438172877, 70318.64840675764, 70414.0, 70562.64718676476, 70590.2940970594, 61189.0, 61519.0, 61657.38342616606, 64254.35745642577, 64656.03015954764, 56279.718601406734, 56430.717846411324, 56464.43535564674, 56637.43362566404, 56889.14666280009, 8114.797130071795, 8176.632051557575, 8183.181681831769, 8380.0, 8523.616437260214, 41121.1775764489, 41384.793076034424, 41399.670313201976, 42099.0, 42347.57652423436, 51855.48144518506, 54652.0, 55082.72458637681, 55201.0, 55204.44795551993, 10398.324108932895, 10681.786364272826, 10744.355338679705, 10779.353238805697, 10976.560937562408, 11046.116310695286, 11166.609168679057, 11239.606613768807, 11253.212275140662, 11500.194986350874, 10625.840612390975, 10764.300320479144, 10818.350898945773, 10847.566097356017, 11060.281081729661, 10502.737431564272, 10574.894251057698, 10870.891291087304, 10892.40091794959, 10956.780864382508, 10166.644167454104, 10368.948155259175, 10442.269041167045, 10490.475476226005, 10650.254482186172, 9394.483303418105, 9528.857067144138, 9566.1868741155, 9901.455419951972, 9958.402495850272, 8446.915530844613, 9411.623535058521, 9912.553935072794, 10213.0, 10272.178225741773], "unit": "ms", "name": "yui.html", "value": 6511.269378670406}], "name": "dromaeo_css", "value": 7186.041165286454}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 104.0, 109.0, 109.0, 111.0, 100.0, 109.0, 106.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 107.6}, {"lowerIsBetter": true, "replicates": [144.0, 121.0, 156.0, 161.0, 124.0, 149.0, 147.0, 123.0, 173.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.2}, {"lowerIsBetter": true, "replicates": [164.0, 160.0, 158.0, 160.0, 160.0, 159.0, 160.0, 163.0, 160.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 160.3}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 72.0, 146.0, 71.0, 72.0, 72.0, 71.0, 164.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 88.1}, {"lowerIsBetter": true, "replicates": [89.0, 88.0, 145.0, 89.0, 90.0, 89.0, 130.0, 88.0, 89.0, 132.0], "unit": "ms", "name": "audio-oscillator", "value": 102.9}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 109.0, 109.0, 109.0, 110.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.4}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0, 97.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [56.0, 56.0, 56.0, 58.0, 56.0, 56.0, 59.0, 58.0, 53.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 56.3}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 63.0, 64.0, 65.0, 64.0, 65.0, 65.0, 64.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.4}, {"lowerIsBetter": true, "replicates": [77.0, 78.0, 80.0, 80.0, 86.0, 79.0, 90.0, 77.0, 83.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 80.7}, {"lowerIsBetter": true, "replicates": [115.0, 108.0, 106.0, 107.0, 113.0, 117.0, 107.0, 114.0, 108.0, 114.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 110.9}, {"lowerIsBetter": true, "replicates": [128.0, 138.0, 144.0, 131.0, 138.0, 136.0, 139.0, 141.0, 137.0, 127.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 135.9}, {"lowerIsBetter": true, "replicates": [62.0, 62.0, 65.0, 61.0, 61.0, 60.0, 62.0, 60.0, 60.0, 61.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.4}], "name": "kraken", "value": 1461.4000000000003}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.55904481517828], "unit": "score", "name": "Richards", "value": 215.83642799999996}, {"lowerIsBetter": false, "replicates": [170.8233686368295], "unit": "score", "name": "DeltaBlue", "value": 387.05477199999996}, {"lowerIsBetter": false, "replicates": [279.09572983533354], "unit": "score", "name": "Encrypt", "value": 953.7265229999995}, {"lowerIsBetter": false, "replicates": [5065.656565656565], "unit": "score", "name": "Decrypt", "value": 52.54619940179463}, {"lowerIsBetter": false, "replicates": [944.2870632672333], "unit": "score", "name": "RayTrace", "value": 783.6483509999999}, {"lowerIsBetter": false, "replicates": [551.5719801434087], "unit": "score", "name": "Earley", "value": 1208.2974190000004}, {"lowerIsBetter": false, "replicates": [10892.47311827957], "unit": "score", "name": "Boyer", "value": 61.185645607107574}, {"lowerIsBetter": false, "replicates": [23744.18604651163], "unit": "score", "name": "RegExp", "value": 38.36665523996084}, {"lowerIsBetter": false, "replicates": [554.3237250554324], "unit": "score", "name": "Splay", "value": 147.00976400000002}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 23122.683166905197}]} 14:57:36 INFO - RETURN: dromaeo_css: 7186.04 14:57:36 INFO - RETURN: kraken: 1461.40 14:57:36 INFO - RETURN: v8_7: 23122.68 14:57:36 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23122.68"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1461.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7186.04"}}} 14:57:36 INFO - Return code: 0 14:57:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 14:57:36 INFO - # TBPL SUCCESS # 14:57:36 INFO - Running post-action listener: _resource_record_post_action 14:57:36 INFO - Running post-run listener: _resource_record_post_run 14:57:37 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 319488; Write bytes: 332120064; Read time: 96; Write time: 242652 14:57:37 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 69984256; Read time: 0; Write time: 45460 14:57:37 INFO - run-tests - Wall time: 1207s; CPU: 13.0%; Read bytes: 319488; Write bytes: 262135808; Read time: 96; Write time: 197192 14:57:37 INFO - Running post-run listener: _upload_blobber_files 14:57:37 INFO - Blob upload gear active. 14:57:37 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 14:57:37 INFO - Copying logs to upload dir... 14:57:37 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1267.019755 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-01-04 14:57:37.182431) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-04 14:57:37.189529) ========= 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=b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009935 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451938350/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-04 14:57:37.242230) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:57:37.242569) ========= 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=b5f16d05e5ea93ddf675e67e000001bf-1451946609.546491-1962237974 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004483 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 14:57:37.292493) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-04 14:57:37.292846) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-04 14:57:37.293190) ========= ========= Total master_lag: 0.23 =========