builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-047 starttime: 1451949989.19 results: success (0) buildid: 20160104131105 builduid: 81dc67e1246f4b5da83f170d54814056 revision: 692ee3b8ba0a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:29.191158) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:29.191750) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:29.192067) ========= 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=03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.005393 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:29.237901) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:29.238219) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:29.298523) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:29.298807) ========= 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=03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.027214 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:29.348496) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:29.348764) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:29.349131) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:29.349467) ========= 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=03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-04 15:26: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% 40.5M=0s 2016-01-04 15:26:29 (40.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.168824 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:29.531179) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:29.531530) ========= 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=03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.093875 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:29.637065) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-04 15:26:29.637456) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 692ee3b8ba0a --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 692ee3b8ba0a --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=03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-04 15:26:29,635 Setting DEBUG logging. 2016-01-04 15:26:29,635 attempt 1/10 2016-01-04 15:26:29,635 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/692ee3b8ba0a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-04 15:26:30,421 unpacking tar archive at: mozilla-beta-692ee3b8ba0a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.056389 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-04 15:26:30.706614) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:30.706923) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:30.714295) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:30.714806) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-04 15:26:30.715432) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-01-04 15:26:30.715908) ========= /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=03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:26:30 INFO - MultiFileLogger online at 20160104 15:26:30 in /builds/slave/test-pgo 15:26: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 15:26:30 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 15:26:30 INFO - {'append_to_log': False, 15:26:30 INFO - 'base_work_dir': '/builds/slave/test-pgo', 15:26:30 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:26:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 15:26:30 INFO - 'branch': 'Mozilla-Beta', 15:26:30 INFO - 'buildbot_json_path': 'buildprops.json', 15:26:30 INFO - 'config_files': ('talos/linux_config.py',), 15:26:30 INFO - 'default_actions': ('clobber', 15:26:30 INFO - 'read-buildbot-config', 15:26:30 INFO - 'download-and-extract', 15:26:30 INFO - 'populate-webroot', 15:26:30 INFO - 'create-virtualenv', 15:26:30 INFO - 'install', 15:26:30 INFO - 'run-tests'), 15:26:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:26:30 INFO - 'download_minidump_stackwalk': True, 15:26:30 INFO - 'download_symbols': 'ondemand', 15:26:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:26:30 INFO - 'tooltool.py': '/tools/tooltool.py', 15:26:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:26:30 INFO - '/tools/misc-python/virtualenv.py')}, 15:26:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:26:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:26:30 INFO - 'installer_path': 'installer.exe', 15:26:30 INFO - 'log_level': 'info', 15:26:30 INFO - 'log_name': 'talos', 15:26:30 INFO - 'log_to_console': True, 15:26:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:26:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:26:30 INFO - 'opt_config_files': (), 15:26:30 INFO - 'pip_index': False, 15:26:30 INFO - 'sps_profile': False, 15:26:30 INFO - 'sps_profile_interval': 0, 15:26:30 INFO - 'suite': 'dromaeojs', 15:26:30 INFO - 'system_bits': '32', 15:26:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:26:30 INFO - 'title': 'talos-linux64-ix-047', 15:26:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:26:30 INFO - 'use_talos_json': True, 15:26:30 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 15:26:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:26:30 INFO - 'work_dir': 'build'} 15:26:30 INFO - ##### 15:26:30 INFO - ##### Running clobber step. 15:26:30 INFO - ##### 15:26:30 INFO - Running pre-action listener: _resource_record_pre_action 15:26:30 INFO - Running main action method: clobber 15:26:30 INFO - rmtree: /builds/slave/test-pgo/build 15:26:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 15:26:34 INFO - Running post-action listener: _resource_record_post_action 15:26:34 INFO - ##### 15:26:34 INFO - ##### Running read-buildbot-config step. 15:26:34 INFO - ##### 15:26:34 INFO - Running pre-action listener: _resource_record_pre_action 15:26:34 INFO - Running main action method: read_buildbot_config 15:26:34 INFO - Using buildbot properties: 15:26:34 INFO - { 15:26:34 INFO - "properties": { 15:26:34 INFO - "buildnumber": 62, 15:26:34 INFO - "product": "firefox", 15:26:34 INFO - "script_repo_revision": "production", 15:26:34 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 15:26:34 INFO - "repository": "", 15:26:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 15:26:34 INFO - "buildid": "20160104131105", 15:26:34 INFO - "slavename": "talos-linux64-ix-047", 15:26:34 INFO - "pgo_build": "True", 15:26:34 INFO - "basedir": "/builds/slave/test-pgo", 15:26:34 INFO - "project": "", 15:26:34 INFO - "platform": "ubuntu64_hw", 15:26:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:26:34 INFO - "slavebuilddir": "test", 15:26:34 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 15:26:34 INFO - "branch": "mozilla-beta", 15:26:34 INFO - "repo_path": "releases/mozilla-beta", 15:26:34 INFO - "stage_platform": "linux64-pgo", 15:26:34 INFO - "builduid": "81dc67e1246f4b5da83f170d54814056", 15:26:34 INFO - "revision": "692ee3b8ba0a" 15:26:34 INFO - }, 15:26:34 INFO - "sourcestamp": { 15:26:34 INFO - "repository": "", 15:26:34 INFO - "hasPatch": false, 15:26:34 INFO - "project": "", 15:26:34 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 15:26:34 INFO - "changes": [ 15:26:34 INFO - { 15:26:34 INFO - "category": null, 15:26:34 INFO - "files": [ 15:26:34 INFO - { 15:26:34 INFO - "url": null, 15:26:34 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/firefox-44.0.en-US.linux-x86_64.tar.bz2" 15:26:34 INFO - } 15:26:34 INFO - ], 15:26:34 INFO - "repository": "", 15:26:34 INFO - "rev": "692ee3b8ba0a", 15:26:34 INFO - "who": "sendchange", 15:26:34 INFO - "when": 1451949973, 15:26:34 INFO - "number": 7414979, 15:26:34 INFO - "comments": "Submitted via self-serve", 15:26:34 INFO - "project": "", 15:26:34 INFO - "at": "Mon 04 Jan 2016 15:26:13", 15:26:34 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 15:26:34 INFO - "revlink": "", 15:26:34 INFO - "properties": [ 15:26:34 INFO - [ 15:26:34 INFO - "buildid", 15:26:34 INFO - "20160104131105", 15:26:34 INFO - "Change" 15:26:34 INFO - ], 15:26:34 INFO - [ 15:26:34 INFO - "builduid", 15:26:34 INFO - "81dc67e1246f4b5da83f170d54814056", 15:26:34 INFO - "Change" 15:26:34 INFO - ], 15:26:34 INFO - [ 15:26:34 INFO - "pgo_build", 15:26:34 INFO - "True", 15:26:34 INFO - "Change" 15:26:34 INFO - ] 15:26:34 INFO - ], 15:26:34 INFO - "revision": "692ee3b8ba0a" 15:26:34 INFO - } 15:26:34 INFO - ], 15:26:34 INFO - "revision": "692ee3b8ba0a" 15:26:34 INFO - } 15:26:34 INFO - } 15:26:34 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/firefox-44.0.en-US.linux-x86_64.tar.bz2. 15:26:34 INFO - Running post-action listener: _resource_record_post_action 15:26:34 INFO - ##### 15:26:34 INFO - ##### Running download-and-extract step. 15:26:34 INFO - ##### 15:26:34 INFO - Running pre-action listener: _resource_record_pre_action 15:26:34 INFO - Running main action method: download_and_extract 15:26:34 INFO - mkdir: /builds/slave/test-pgo/build/tests 15:26:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:26:34 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/test_packages.json 15:26:34 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 15:26:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 15:26:34 INFO - Downloaded 1270 bytes. 15:26:34 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 15:26:34 INFO - Using the following test package requirements: 15:26:34 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 15:26:34 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:26:34 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 15:26:34 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:26:34 INFO - u'jsshell-linux-x86_64.zip'], 15:26:34 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:26:34 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 15:26:34 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 15:26:34 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:26:34 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 15:26:34 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:26:34 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 15:26:34 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:26:34 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 15:26:34 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 15:26:34 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:26:34 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 15:26:34 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:26:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:26:34 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:26:34 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/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 15:26:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/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 15:26:38 INFO - Downloaded 22573273 bytes. 15:26:38 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 15:26:38 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:26:38 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 15:26:40 INFO - Return code: 0 15:26:40 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:26: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')]} 15:26:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:26:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/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 15:26:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/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 15:26:40 INFO - Downloaded 22573273 bytes. 15:26:40 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 15:26:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:26:40 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 15:26:41 INFO - Return code: 0 15:26:41 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')]} 15:26:41 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 15:26:41 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/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 15:26:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/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 15:26:44 INFO - Downloaded 11131970 bytes. 15:26:44 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 15:26:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 15:26:44 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 15:26:44 INFO - Return code: 0 15:26:44 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')]} 15:26:44 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/firefox-44.0.en-US.linux-x86_64.tar.bz2 15:26:44 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/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 15:26:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/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 15:26:48 INFO - Downloaded 56617261 bytes. 15:26:48 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/firefox-44.0.en-US.linux-x86_64.tar.bz2 15:26:48 INFO - mkdir: /builds/slave/test-pgo/properties 15:26:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 15:26:48 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 15:26:48 INFO - Contents: 15:26:48 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/firefox-44.0.en-US.linux-x86_64.tar.bz2 15:26:48 INFO - Running post-action listener: _resource_record_post_action 15:26:48 INFO - Running post-action listener: set_extra_try_arguments 15:26:48 INFO - ##### 15:26:48 INFO - ##### Running populate-webroot step. 15:26:48 INFO - ##### 15:26:48 INFO - Running pre-action listener: _resource_record_pre_action 15:26:48 INFO - Running main action method: populate_webroot 15:26:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:26:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:26:48 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 15:26:48 INFO - '../fennec_ids.txt'], 15:26:48 INFO - 'tests': ['tcheck2']}, 15:26:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:26:48 INFO - '--tppagecycles', 15:26:48 INFO - '7'], 15:26:48 INFO - 'tests': ['tsvgm']}}, 15:26:48 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 15:26:48 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 15:26:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:26:48 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:26:48 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 15:26:48 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:26:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:26:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:48 INFO - 'talos_options': ['--e10s'], 15:26:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:26:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:48 INFO - 'tests': ['damp', 'tps']}, 15:26:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:48 INFO - 'talos_options': ['--e10s'], 15:26:48 INFO - 'tests': ['damp', 'tps']}, 15:26:48 INFO - 'g3': {'tests': ['dromaeo_dom']}, 15:26:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:26:48 INFO - 'other': {'tests': ['a11yr', 15:26:48 INFO - 'ts_paint', 15:26:48 INFO - 'tpaint', 15:26:48 INFO - 'sessionrestore', 15:26:48 INFO - 'sessionrestore_no_auto_restore']}, 15:26:48 INFO - 'other-e10s': {'talos_options': ['--e10s'], 15:26:48 INFO - 'tests': ['a11yr', 15:26:48 INFO - 'ts_paint', 15:26:48 INFO - 'tpaint', 15:26:48 INFO - 'sessionrestore', 15:26:48 INFO - 'sessionrestore_no_auto_restore']}, 15:26:48 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 15:26:48 INFO - 'tests': ['a11yr', 15:26:48 INFO - 'ts_paint', 15:26:48 INFO - 'tpaint', 15:26:48 INFO - 'sessionrestore', 15:26:48 INFO - 'sessionrestore_no_auto_restore']}, 15:26:48 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 15:26:48 INFO - 'tests': ['a11yr', 15:26:48 INFO - 'ts_paint', 15:26:48 INFO - 'tpaint', 15:26:48 INFO - 'sessionrestore', 15:26:48 INFO - 'sessionrestore_no_auto_restore']}, 15:26:48 INFO - 'other_l64': {'tests': ['a11yr', 15:26:48 INFO - 'ts_paint', 15:26:48 INFO - 'tpaint', 15:26:48 INFO - 'sessionrestore', 15:26:48 INFO - 'sessionrestore_no_auto_restore']}, 15:26:48 INFO - 'other_nol64': {'tests': ['a11yr', 15:26:48 INFO - 'ts_paint', 15:26:48 INFO - 'tpaint', 15:26:48 INFO - 'sessionrestore', 15:26:48 INFO - 'sessionrestore_no_auto_restore']}, 15:26:48 INFO - 'svgr': {'tests': ['tsvgx', 15:26:48 INFO - 'tsvgr_opacity', 15:26:48 INFO - 'tart', 15:26:48 INFO - 'tscrollx', 15:26:48 INFO - 'cart']}, 15:26:48 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 15:26:48 INFO - 'tests': ['tsvgx', 15:26:48 INFO - 'tsvgr_opacity', 15:26:48 INFO - 'tart', 15:26:48 INFO - 'tscrollx', 15:26:48 INFO - 'cart']}, 15:26:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:48 INFO - 'tests': ['tp5o']}, 15:26:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:48 INFO - 'talos_options': ['--e10s'], 15:26:48 INFO - 'tests': ['tp5o']}, 15:26:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:48 INFO - 'talos_options': ['--xperf_path', 15:26:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:26:48 INFO - 'tests': ['tp5n']}, 15:26:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:48 INFO - 'talos_options': ['--e10s', 15:26:48 INFO - '--xperf_path', 15:26:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:26:48 INFO - 'tests': ['tp5n']}}, 15:26:48 INFO - 'talos.zip': {'path': '', 15:26:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:26:48 INFO - Running post-action listener: _resource_record_post_action 15:26:48 INFO - ##### 15:26:48 INFO - ##### Running create-virtualenv step. 15:26:48 INFO - ##### 15:26:48 INFO - Running pre-action listener: _resource_record_pre_action 15:26:48 INFO - Running main action method: create_virtualenv 15:26:48 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 15:26:48 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 15:26:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 15:26:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:26:48 INFO - Using real prefix '/usr' 15:26:48 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 15:26:49 INFO - Installing distribute.............................................................................................................................................................................................done. 15:26:51 INFO - Installing pip.................done. 15:26:51 INFO - Return code: 0 15:26:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 15:26:51 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')]} 15:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:51 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')]} 15:26:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:51 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 0x7fe67c3adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ad600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223c350>, '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 0x223d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223d6e0>, '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': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', '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 15:26:51 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 15:26:51 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 15:26:51 INFO - Using env: {'DISPLAY': ':0', 15:26:51 INFO - 'HOME': '/home/cltbld', 15:26:51 INFO - 'LANG': 'en_US.UTF-8', 15:26:51 INFO - 'LANGUAGE': 'en_US:en', 15:26:51 INFO - 'LOGNAME': 'cltbld', 15:26:51 INFO - 'MAIL': '/var/mail/cltbld', 15:26:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:51 INFO - 'MOZ_NO_REMOTE': '1', 15:26:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:51 INFO - 'NO_EM_RESTART': '1', 15:26:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:26:51 INFO - 'PWD': '/builds/slave/test-pgo', 15:26:51 INFO - 'SHELL': '/bin/bash', 15:26:51 INFO - 'SHLVL': '1', 15:26:51 INFO - 'TERM': 'linux', 15:26:51 INFO - 'TMOUT': '86400', 15:26:51 INFO - 'USER': 'cltbld', 15:26:51 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', 15:26:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:51 INFO - '_': '/tools/buildbot/bin/python'} 15:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:51 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 15:26:51 INFO - Cleaning up... 15:26:51 INFO - Return code: 0 15:26:51 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 15:26:51 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')]} 15:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:51 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')]} 15:26:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:51 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 0x7fe67c3adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ad600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223c350>, '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 0x223d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223d6e0>, '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': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', '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 15:26:51 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 15:26:51 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 15:26:51 INFO - Using env: {'DISPLAY': ':0', 15:26:51 INFO - 'HOME': '/home/cltbld', 15:26:51 INFO - 'LANG': 'en_US.UTF-8', 15:26:51 INFO - 'LANGUAGE': 'en_US:en', 15:26:51 INFO - 'LOGNAME': 'cltbld', 15:26:51 INFO - 'MAIL': '/var/mail/cltbld', 15:26:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:51 INFO - 'MOZ_NO_REMOTE': '1', 15:26:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:51 INFO - 'NO_EM_RESTART': '1', 15:26:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:26:51 INFO - 'PWD': '/builds/slave/test-pgo', 15:26:51 INFO - 'SHELL': '/bin/bash', 15:26:51 INFO - 'SHLVL': '1', 15:26:51 INFO - 'TERM': 'linux', 15:26:51 INFO - 'TMOUT': '86400', 15:26:51 INFO - 'USER': 'cltbld', 15:26:51 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', 15:26:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:51 INFO - '_': '/tools/buildbot/bin/python'} 15:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:51 INFO - Downloading/unpacking psutil>=0.7.1 15:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:53 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 15:26:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 15:26:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 15:26:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:26:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:26:53 INFO - Installing collected packages: psutil 15:26:53 INFO - Running setup.py install for psutil 15:26:53 INFO - building 'psutil._psutil_linux' extension 15:26:53 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 15:26:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:26:54 INFO - building 'psutil._psutil_posix' extension 15:26:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:26:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:26:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:26:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:26:55 INFO - Successfully installed psutil 15:26:55 INFO - Cleaning up... 15:26:55 INFO - Return code: 0 15:26:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 15:26:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:26:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:55 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe67c3adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ad600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223c350>, '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 0x223d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223d6e0>, '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': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', '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 15:26:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 15:26:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 15:26:55 INFO - Using env: {'DISPLAY': ':0', 15:26:55 INFO - 'HOME': '/home/cltbld', 15:26:55 INFO - 'LANG': 'en_US.UTF-8', 15:26:55 INFO - 'LANGUAGE': 'en_US:en', 15:26:55 INFO - 'LOGNAME': 'cltbld', 15:26:55 INFO - 'MAIL': '/var/mail/cltbld', 15:26:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:55 INFO - 'MOZ_NO_REMOTE': '1', 15:26:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:55 INFO - 'NO_EM_RESTART': '1', 15:26:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:26:55 INFO - 'PWD': '/builds/slave/test-pgo', 15:26:55 INFO - 'SHELL': '/bin/bash', 15:26:55 INFO - 'SHLVL': '1', 15:26:55 INFO - 'TERM': 'linux', 15:26:55 INFO - 'TMOUT': '86400', 15:26:55 INFO - 'USER': 'cltbld', 15:26:55 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', 15:26:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:55 INFO - '_': '/tools/buildbot/bin/python'} 15:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:26:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 15:26:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:26:57 INFO - Installing collected packages: mozsystemmonitor 15:26:57 INFO - Running setup.py install for mozsystemmonitor 15:26:57 INFO - Successfully installed mozsystemmonitor 15:26:57 INFO - Cleaning up... 15:26:57 INFO - Return code: 0 15:26:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 15:26:57 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')]} 15:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:57 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')]} 15:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:57 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 0x7fe67c3adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ad600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223c350>, '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 0x223d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223d6e0>, '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': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', '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 15:26:57 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 15:26:57 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 15:26:57 INFO - Using env: {'DISPLAY': ':0', 15:26:57 INFO - 'HOME': '/home/cltbld', 15:26:57 INFO - 'LANG': 'en_US.UTF-8', 15:26:57 INFO - 'LANGUAGE': 'en_US:en', 15:26:57 INFO - 'LOGNAME': 'cltbld', 15:26:57 INFO - 'MAIL': '/var/mail/cltbld', 15:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:57 INFO - 'MOZ_NO_REMOTE': '1', 15:26:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:57 INFO - 'NO_EM_RESTART': '1', 15:26:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:26:57 INFO - 'PWD': '/builds/slave/test-pgo', 15:26:57 INFO - 'SHELL': '/bin/bash', 15:26:57 INFO - 'SHLVL': '1', 15:26:57 INFO - 'TERM': 'linux', 15:26:57 INFO - 'TMOUT': '86400', 15:26:57 INFO - 'USER': 'cltbld', 15:26:57 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', 15:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:57 INFO - '_': '/tools/buildbot/bin/python'} 15:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:57 INFO - Downloading/unpacking blobuploader==1.2.4 15:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:59 INFO - Downloading blobuploader-1.2.4.tar.gz 15:26:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 15:26:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:26:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:26: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 15:26: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 15:26: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 15:26: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 15:26: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 15:26: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 15:26:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 15:26:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 15:26:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:26: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 15:26: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 15:26: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 15:26: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 15:26: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 15:26: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 15:26:59 INFO - Downloading docopt-0.6.1.tar.gz 15:26:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 15:26:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 15:26:59 INFO - Installing collected packages: blobuploader, requests, docopt 15:26:59 INFO - Running setup.py install for blobuploader 15:26:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:26:59 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 15:26:59 INFO - Running setup.py install for requests 15:27:00 INFO - Running setup.py install for docopt 15:27:00 INFO - Successfully installed blobuploader requests docopt 15:27:00 INFO - Cleaning up... 15:27:00 INFO - Return code: 0 15:27:00 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:00 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 0x7fe67c3adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ad600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223c350>, '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 0x223d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223d6e0>, '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': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', '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 15:27:00 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 15:27:00 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 15:27:00 INFO - Using env: {'DISPLAY': ':0', 15:27:00 INFO - 'HOME': '/home/cltbld', 15:27:00 INFO - 'LANG': 'en_US.UTF-8', 15:27:00 INFO - 'LANGUAGE': 'en_US:en', 15:27:00 INFO - 'LOGNAME': 'cltbld', 15:27:00 INFO - 'MAIL': '/var/mail/cltbld', 15:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:00 INFO - 'MOZ_NO_REMOTE': '1', 15:27:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:00 INFO - 'NO_EM_RESTART': '1', 15:27:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:27:00 INFO - 'PWD': '/builds/slave/test-pgo', 15:27:00 INFO - 'SHELL': '/bin/bash', 15:27:00 INFO - 'SHLVL': '1', 15:27:00 INFO - 'TERM': 'linux', 15:27:00 INFO - 'TMOUT': '86400', 15:27:00 INFO - 'USER': 'cltbld', 15:27:00 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', 15:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:00 INFO - '_': '/tools/buildbot/bin/python'} 15:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:27:00 INFO - Running setup.py (path:/tmp/pip-lcAFLV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:27:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:27:00 INFO - Running setup.py (path:/tmp/pip-iMk8xh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:27:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:27:00 INFO - Running setup.py (path:/tmp/pip-Bqcj90-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:27:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:27:00 INFO - Running setup.py (path:/tmp/pip-RsegcR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:27:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:27:00 INFO - Running setup.py (path:/tmp/pip-U6OmuY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:27:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:27:00 INFO - Running setup.py (path:/tmp/pip-CGFGFy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:27:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:27:01 INFO - Running setup.py (path:/tmp/pip-X9P5SI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:27:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:27:01 INFO - Running setup.py (path:/tmp/pip-8MeYzc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:27:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:27:01 INFO - Running setup.py (path:/tmp/pip-CS6dMa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:27:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:27:01 INFO - Running setup.py (path:/tmp/pip-zQ5vz9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:27:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:27:01 INFO - Running setup.py (path:/tmp/pip-uAjJ5L-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:27:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:27:01 INFO - Running setup.py (path:/tmp/pip-Fa3M_w-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:27:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:27:01 INFO - Running setup.py (path:/tmp/pip-8Gpfj6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:27:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:27:01 INFO - Running setup.py (path:/tmp/pip-48bF9o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:27:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:27:01 INFO - Running setup.py (path:/tmp/pip-vwPFzf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:27:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:27:01 INFO - Running setup.py (path:/tmp/pip-1J1Ogm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:27:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:27:01 INFO - Running setup.py (path:/tmp/pip-t53rGM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:27:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:27:01 INFO - Running setup.py install for manifestparser 15:27:01 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 15:27:01 INFO - Running setup.py install for mozcrash 15:27:01 INFO - Running setup.py install for mozdebug 15:27:02 INFO - Running setup.py install for mozdevice 15:27:02 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 15:27:02 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 15:27:02 INFO - Running setup.py install for mozfile 15:27:02 INFO - Running setup.py install for mozhttpd 15:27:02 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 15:27:02 INFO - Running setup.py install for mozinfo 15:27:02 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 15:27:02 INFO - Running setup.py install for mozInstall 15:27:02 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 15:27:02 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 15:27:02 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 15:27:02 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 15:27:02 INFO - Running setup.py install for mozleak 15:27:02 INFO - Running setup.py install for mozlog 15:27:02 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 15:27:02 INFO - Running setup.py install for moznetwork 15:27:02 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 15:27:02 INFO - Running setup.py install for mozprocess 15:27:03 INFO - Running setup.py install for mozprofile 15:27:03 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 15:27:03 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 15:27:03 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 15:27:03 INFO - Running setup.py install for mozrunner 15:27:03 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 15:27:03 INFO - Running setup.py install for mozscreenshot 15:27:03 INFO - Running setup.py install for moztest 15:27:03 INFO - Running setup.py install for mozversion 15:27:03 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 15:27:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:27:03 INFO - Cleaning up... 15:27:03 INFO - Return code: 0 15:27:03 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:27:03 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')]} 15:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:03 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')]} 15:27:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:03 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 0x7fe67c3adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ad600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223c350>, '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 0x223d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223d6e0>, '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': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', '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 15:27:03 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 15:27:03 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 15:27:03 INFO - Using env: {'DISPLAY': ':0', 15:27:03 INFO - 'HOME': '/home/cltbld', 15:27:03 INFO - 'LANG': 'en_US.UTF-8', 15:27:03 INFO - 'LANGUAGE': 'en_US:en', 15:27:03 INFO - 'LOGNAME': 'cltbld', 15:27:03 INFO - 'MAIL': '/var/mail/cltbld', 15:27:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:03 INFO - 'MOZ_NO_REMOTE': '1', 15:27:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:03 INFO - 'NO_EM_RESTART': '1', 15:27:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:27:03 INFO - 'PWD': '/builds/slave/test-pgo', 15:27:03 INFO - 'SHELL': '/bin/bash', 15:27:03 INFO - 'SHLVL': '1', 15:27:03 INFO - 'TERM': 'linux', 15:27:03 INFO - 'TMOUT': '86400', 15:27:03 INFO - 'USER': 'cltbld', 15:27:03 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', 15:27:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:03 INFO - '_': '/tools/buildbot/bin/python'} 15:27:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:27:03 INFO - Running setup.py (path:/tmp/pip-x3kzG5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:27:03 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)) 15:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:27:03 INFO - Running setup.py (path:/tmp/pip-iPv12l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:27:03 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)) 15:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:27:03 INFO - Running setup.py (path:/tmp/pip-eFLjjP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:27:03 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)) 15:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:27:03 INFO - Running setup.py (path:/tmp/pip-2wSZgM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:27:04 INFO - Running setup.py (path:/tmp/pip-FyLY7H-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:27:04 INFO - Running setup.py (path:/tmp/pip-hrsqaW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:27:04 INFO - Running setup.py (path:/tmp/pip-aA3xQT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:27:04 INFO - Running setup.py (path:/tmp/pip-N7LgHr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:27:04 INFO - Running setup.py (path:/tmp/pip-ZCYgX3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:27:04 INFO - Running setup.py (path:/tmp/pip-hII1hD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:27:04 INFO - Running setup.py (path:/tmp/pip-RXAZ3A-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:27:04 INFO - Running setup.py (path:/tmp/pip-O252lT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:27:04 INFO - Running setup.py (path:/tmp/pip-9F4khn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:27:04 INFO - Running setup.py (path:/tmp/pip-b_qczT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:27:04 INFO - Running setup.py (path:/tmp/pip-yUkiKg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:27:04 INFO - Running setup.py (path:/tmp/pip-F43M46-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:27:04 INFO - Running setup.py (path:/tmp/pip-VaJ7GJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:27:04 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)) 15:27:04 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)) 15:27:04 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)) 15:27:04 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)) 15:27:04 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)) 15:27:04 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)) 15:27:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 15:27:05 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 15:27:05 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 15:27:05 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 15:27:05 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 15:27:05 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 15:27:05 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 15:27:06 INFO - Downloading blessings-1.5.1.tar.gz 15:27:06 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 15:27:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 15:27:06 INFO - Installing collected packages: blessings 15:27:06 INFO - Running setup.py install for blessings 15:27:06 INFO - Successfully installed blessings 15:27:06 INFO - Cleaning up... 15:27:06 INFO - Return code: 0 15:27:06 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 15:27:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:27:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:27:07 INFO - Reading from file tmpfile_stdout 15:27:07 INFO - Current package versions: 15:27:07 INFO - argparse == 1.2.1 15:27:07 INFO - blessings == 1.5.1 15:27:07 INFO - blobuploader == 1.2.4 15:27:07 INFO - docopt == 0.6.1 15:27:07 INFO - manifestparser == 1.1 15:27:07 INFO - mozInstall == 1.12 15:27:07 INFO - mozcrash == 0.16 15:27:07 INFO - mozdebug == 0.1 15:27:07 INFO - mozdevice == 0.46 15:27:07 INFO - mozfile == 1.2 15:27:07 INFO - mozhttpd == 0.7 15:27:07 INFO - mozinfo == 0.8 15:27:07 INFO - mozleak == 0.1 15:27:07 INFO - mozlog == 3.0 15:27:07 INFO - moznetwork == 0.27 15:27:07 INFO - mozprocess == 0.22 15:27:07 INFO - mozprofile == 0.27 15:27:07 INFO - mozrunner == 6.11 15:27:07 INFO - mozscreenshot == 0.1 15:27:07 INFO - mozsystemmonitor == 0.0 15:27:07 INFO - moztest == 0.7 15:27:07 INFO - mozversion == 1.4 15:27:07 INFO - psutil == 3.1.1 15:27:07 INFO - requests == 1.2.3 15:27:07 INFO - wsgiref == 0.1.2 15:27:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:27:07 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')]} 15:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:07 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')]} 15:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:07 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 0x7fe67c3adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ad600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223c350>, '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 0x223d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223d6e0>, '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': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', '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 15:27:07 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 15:27:07 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 15:27:07 INFO - Using env: {'DISPLAY': ':0', 15:27:07 INFO - 'HOME': '/home/cltbld', 15:27:07 INFO - 'LANG': 'en_US.UTF-8', 15:27:07 INFO - 'LANGUAGE': 'en_US:en', 15:27:07 INFO - 'LOGNAME': 'cltbld', 15:27:07 INFO - 'MAIL': '/var/mail/cltbld', 15:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:07 INFO - 'MOZ_NO_REMOTE': '1', 15:27:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:07 INFO - 'NO_EM_RESTART': '1', 15:27:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:27:07 INFO - 'PWD': '/builds/slave/test-pgo', 15:27:07 INFO - 'SHELL': '/bin/bash', 15:27:07 INFO - 'SHLVL': '1', 15:27:07 INFO - 'TERM': 'linux', 15:27:07 INFO - 'TMOUT': '86400', 15:27:07 INFO - 'USER': 'cltbld', 15:27:07 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', 15:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:07 INFO - '_': '/tools/buildbot/bin/python'} 15:27:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 INFO - Cleaning up... 15:27:07 INFO - Return code: 0 15:27:07 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 15:27:07 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')]} 15:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:07 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')]} 15:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:07 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 0x7fe67c3adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ad600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223c350>, '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 0x223d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223d6e0>, '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': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', '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 15:27:07 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 15:27:07 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 15:27:07 INFO - Using env: {'DISPLAY': ':0', 15:27:07 INFO - 'HOME': '/home/cltbld', 15:27:07 INFO - 'LANG': 'en_US.UTF-8', 15:27:07 INFO - 'LANGUAGE': 'en_US:en', 15:27:07 INFO - 'LOGNAME': 'cltbld', 15:27:07 INFO - 'MAIL': '/var/mail/cltbld', 15:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:07 INFO - 'MOZ_NO_REMOTE': '1', 15:27:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:07 INFO - 'NO_EM_RESTART': '1', 15:27:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:27:07 INFO - 'PWD': '/builds/slave/test-pgo', 15:27:07 INFO - 'SHELL': '/bin/bash', 15:27:07 INFO - 'SHLVL': '1', 15:27:07 INFO - 'TERM': 'linux', 15:27:07 INFO - 'TMOUT': '86400', 15:27:07 INFO - 'USER': 'cltbld', 15:27:07 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', 15:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:07 INFO - '_': '/tools/buildbot/bin/python'} 15:27:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:07 INFO - Downloading/unpacking jsonschema 15:27:07 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 15:27:07 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 15:27:07 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 15:27:07 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 15:27:07 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 15:27:07 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 15:27:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:27:09 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 15:27:09 INFO - Downloading/unpacking functools32 (from jsonschema) 15:27: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 15:27: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 15:27: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 15:27: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 15:27: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 15:27: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 15:27:09 INFO - Downloading functools32-3.2.3-2.tar.gz 15:27:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 15:27:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 15:27:09 INFO - warning: no files found matching '*.txt' 15:27:09 INFO - no previously-included directories found matching 'build' 15:27:09 INFO - no previously-included directories found matching 'dist' 15:27:09 INFO - no previously-included directories found matching '.git*' 15:27:09 INFO - Installing collected packages: jsonschema, functools32 15:27:09 INFO - Running setup.py install for functools32 15:27:09 INFO - warning: no files found matching '*.txt' 15:27:09 INFO - no previously-included directories found matching 'build' 15:27:09 INFO - no previously-included directories found matching 'dist' 15:27:09 INFO - no previously-included directories found matching '.git*' 15:27:09 INFO - Successfully installed jsonschema functools32 15:27:09 INFO - Cleaning up... 15:27:10 INFO - Return code: 0 15:27:10 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 15:27:10 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')]} 15:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:10 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')]} 15:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:10 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 0x7fe67c3adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ad600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223c350>, '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 0x223d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223d6e0>, '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': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', '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 15:27:10 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 15:27:10 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 15:27:10 INFO - Using env: {'DISPLAY': ':0', 15:27:10 INFO - 'HOME': '/home/cltbld', 15:27:10 INFO - 'LANG': 'en_US.UTF-8', 15:27:10 INFO - 'LANGUAGE': 'en_US:en', 15:27:10 INFO - 'LOGNAME': 'cltbld', 15:27:10 INFO - 'MAIL': '/var/mail/cltbld', 15:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:10 INFO - 'MOZ_NO_REMOTE': '1', 15:27:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:10 INFO - 'NO_EM_RESTART': '1', 15:27:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:27:10 INFO - 'PWD': '/builds/slave/test-pgo', 15:27:10 INFO - 'SHELL': '/bin/bash', 15:27:10 INFO - 'SHLVL': '1', 15:27:10 INFO - 'TERM': 'linux', 15:27:10 INFO - 'TMOUT': '86400', 15:27:10 INFO - 'USER': 'cltbld', 15:27:10 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', 15:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:10 INFO - '_': '/tools/buildbot/bin/python'} 15:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:10 INFO - Downloading/unpacking flake8 15:27:10 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 15:27:10 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 15:27:10 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 15:27:10 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 15:27:10 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 15:27:10 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 15:27:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:27:11 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 15:27:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:27: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 15:27: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 15:27: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 15:27: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 15:27: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 15:27: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 15:27:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:27:12 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 15:27:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:27:12 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 15:27:12 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 15:27:12 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 15:27:12 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 15:27:12 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 15:27:12 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 15:27:12 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 15:27:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:27:12 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 15:27:12 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 15:27:12 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 15:27:12 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 15:27:12 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 15:27:12 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 15:27:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 15:27:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 15:27:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:27:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:27:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:27:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:27:12 INFO - no previously-included directories found matching 'docs/_build' 15:27:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:27:12 INFO - Running setup.py install for pep8 15:27:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:27:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:27:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:27:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:27:12 INFO - no previously-included directories found matching 'docs/_build' 15:27:12 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 15:27:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:27:12 INFO - Cleaning up... 15:27:12 INFO - Return code: 0 15:27:12 INFO - Running post-action listener: _resource_record_post_action 15:27:12 INFO - Running post-action listener: _start_resource_monitoring 15:27:12 INFO - Starting resource monitoring. 15:27:12 INFO - ##### 15:27:12 INFO - ##### Running install step. 15:27:12 INFO - ##### 15:27:12 INFO - Running pre-action listener: _resource_record_pre_action 15:27:12 INFO - Running main action method: install 15:27:12 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:27:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:27:13 INFO - Reading from file tmpfile_stdout 15:27:13 INFO - Detecting whether we're running mozinstall >=1.0... 15:27:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 15:27:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 15:27:13 INFO - Reading from file tmpfile_stdout 15:27:13 INFO - Output received: 15:27:13 INFO - Usage: mozinstall [options] installer 15:27:13 INFO - Options: 15:27:13 INFO - -h, --help show this help message and exit 15:27:13 INFO - -d DEST, --destination=DEST 15:27:13 INFO - Directory to install application into. [default: 15:27:13 INFO - "/builds/slave/test-pgo"] 15:27:13 INFO - --app=APP Application being installed. [default: firefox] 15:27:13 INFO - mkdir: /builds/slave/test-pgo/build/application 15:27:13 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'] 15:27:13 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 15:27:27 INFO - Reading from file tmpfile_stdout 15:27:27 INFO - Output received: 15:27:27 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 15:27:27 INFO - Running post-action listener: _resource_record_post_action 15:27:27 INFO - ##### 15:27:27 INFO - ##### Running run-tests step. 15:27:27 INFO - ##### 15:27:27 INFO - Running pre-action listener: _resource_record_pre_action 15:27:27 INFO - Running main action method: run_tests 15:27:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 15:27:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 15:27:27 INFO - Python 2.7.3 15:27:27 INFO - Return code: 0 15:27:27 INFO - grabbing minidump binary from tooltool 15:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:27 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 0x223c350>, '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 0x223d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x223d6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 15:27:27 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 15:27:27 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 15:27:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:27:27 INFO - Return code: 0 15:27:27 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 15:27:27 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 15:27:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 15:27:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 15:27:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 15:27:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 15:27:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 15:27:28 INFO - Return code: 0 15:27:28 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/1451941865/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 15:27:28 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/1451941865/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --webServer localhost 15:27:28 INFO - Using env: {'DISPLAY': ':0', 15:27:28 INFO - 'HOME': '/home/cltbld', 15:27:28 INFO - 'LANG': 'en_US.UTF-8', 15:27:28 INFO - 'LANGUAGE': 'en_US:en', 15:27:28 INFO - 'LOGNAME': 'cltbld', 15:27:28 INFO - 'MAIL': '/var/mail/cltbld', 15:27:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:27:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 15:27:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:28 INFO - 'MOZ_NO_REMOTE': '1', 15:27:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:27:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:28 INFO - 'NO_EM_RESTART': '1', 15:27:28 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:27:28 INFO - 'PWD': '/builds/slave/test-pgo', 15:27:28 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 15:27:28 INFO - 'SHELL': '/bin/bash', 15:27:28 INFO - 'SHLVL': '1', 15:27:28 INFO - 'TERM': 'linux', 15:27:28 INFO - 'TMOUT': '86400', 15:27:28 INFO - 'USER': 'cltbld', 15:27:28 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204', 15:27:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:28 INFO - '_': '/tools/buildbot/bin/python'} 15:27:28 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/1451941865/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost'] with output_timeout 3600 15:27:28 INFO - mozversion INFO | application_buildid: 20160104131105 15:27:28 INFO - mozversion INFO | application_changeset: 692ee3b8ba0ab463fda9876c8b4a04f04830c891 15:27:28 INFO - mozversion INFO | application_display_name: Nightly 15:27:28 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:27:28 INFO - mozversion INFO | application_name: Firefox 15:27:28 INFO - mozversion INFO | application_remotingname: firefox 15:27:28 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:27:28 INFO - mozversion INFO | application_vendor: Mozilla 15:27:28 INFO - mozversion INFO | application_version: 44.0 15:27:28 INFO - mozversion INFO | platform_buildid: 20160104131105 15:27:28 INFO - mozversion INFO | platform_changeset: 692ee3b8ba0ab463fda9876c8b4a04f04830c891 15:27:28 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:27:28 INFO - mozversion INFO | platform_version: 44.0 15:27:28 INFO - 2016-01-04 15:27:28,557 DEBUG : using testdate: 1451950048 15:27:28 INFO - 2016-01-04 15:27:28,557 DEBUG : actual date: 1451950048 15:27:28 INFO - 2016-01-04 15:27:28,572 INFO : starting webserver on 'localhost:36161' 15:27:28 INFO - 2016-01-04 15:27:28,574 INFO : Starting test suite talos-linux64-ix-047 15:27:28 INFO - 2016-01-04 15:27:28,574 INFO : Starting test dromaeo_css 15:27:28 INFO - 2016-01-04 15:27:28,574 DEBUG : operating with platform_type : linux_ 15:27:28 INFO - 2016-01-04 15:27:28,575 INFO : Initialising browser for dromaeo_css test... 15:27:28 INFO - 2016-01-04 15:27:28,591 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpt8Jv5_/profile http://localhost:36161/getInfo.html 15:27:33 INFO - 2016-01-04 15:27:33,425 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:27:33 INFO - 2016-01-04 15:27:33,425 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:27:33 INFO - 2016-01-04 15:27:33,425 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:27:33 INFO - 2016-01-04 15:27:33,425 DEBUG : BROWSER_OUTPUT: __metrics 15:27:33 INFO - 2016-01-04 15:27:33,487 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] 15:27:34 INFO - 2016-01-04 15:27:34,100 DEBUG : BROWSER_OUTPUT: console.error: 15:27:34 INFO - 2016-01-04 15:27:34,101 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction. 15:27:34 INFO - 2016-01-04 15:27:34,101 DEBUG : BROWSER_OUTPUT: Stack: 15:27:34 INFO - 2016-01-04 15:27:34,101 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23 15:27:34 INFO - 2016-01-04 15:27:34,101 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 15:27:34 INFO - 2016-01-04 15:27:34,101 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.scheduleWalkerLoop/<@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:1 15:27:34 INFO - 2016-01-04 15:27:34,102 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:529:9 15:27:34 INFO - 2016-01-04 15:27:34,102 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownBarrier.prototype.wait/<@resource://gre/components/nsAsyncShutdown.js:215:7 15:27:34 INFO - 2016-01-04 15:27:34,102 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:529:9 15:27:34 INFO - 2016-01-04 15:27:34,102 DEBUG : BROWSER_OUTPUT: 15:27:34 INFO - 2016-01-04 15:27:34,657 INFO : Browser initialized. 15:27:34 INFO - 2016-01-04 15:27:34,658 INFO : Running cycle 1/1 for dromaeo_css test... 15:27:34 INFO - 2016-01-04 15:27:34,658 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpt8Jv5_/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:27:46 INFO - 2016-01-04 15:27:46,234 DEBUG : BROWSER_OUTPUT: RSS: Main: 128401408 15:27:46 INFO - 2016-01-04 15:27:46,234 DEBUG : BROWSER_OUTPUT: 15:28:37 INFO - 2016-01-04 15:28:37,098 DEBUG : BROWSER_OUTPUT: 1451950117095 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] 15:30:44 INFO - 2016-01-04 15:30:44,951 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36161/tests/dromaeo/cssquery-ext.html) 15:30:46 INFO - 2016-01-04 15:30:46,036 DEBUG : BROWSER_OUTPUT: RSS: Main: 237715456 15:30:46 INFO - 2016-01-04 15:30:46,036 DEBUG : BROWSER_OUTPUT: 15:33:48 INFO - 2016-01-04 15:33:48,066 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/dromaeo/cssquery-ext.html (next: http://localhost:36161/tests/dromaeo/cssquery-jquery.html) 15:33:48 INFO - 2016-01-04 15:33:48,627 DEBUG : BROWSER_OUTPUT: RSS: Main: 206659584 15:33:48 INFO - 2016-01-04 15:33:48,628 DEBUG : BROWSER_OUTPUT: 15:36:50 INFO - 2016-01-04 15:36:50,316 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36161/tests/dromaeo/cssquery-mootools.html) 15:36:50 INFO - 2016-01-04 15:36:50,883 DEBUG : BROWSER_OUTPUT: RSS: Main: 216064000 15:36:50 INFO - 2016-01-04 15:36:50,883 DEBUG : BROWSER_OUTPUT: 15:39:52 INFO - 2016-01-04 15:39:52,626 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36161/tests/dromaeo/cssquery-prototype.html) 15:39:53 INFO - 2016-01-04 15:39:53,195 DEBUG : BROWSER_OUTPUT: RSS: Main: 192630784 15:39:53 INFO - 2016-01-04 15:39:53,196 DEBUG : BROWSER_OUTPUT: 15:42:55 INFO - 2016-01-04 15:42:55,578 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36161/tests/dromaeo/cssquery-yui.html) 15:42:56 INFO - 2016-01-04 15:42:56,131 DEBUG : BROWSER_OUTPUT: RSS: Main: 193236992 15:42:56 INFO - 2016-01-04 15:42:56,131 DEBUG : BROWSER_OUTPUT: 15:45:58 INFO - 2016-01-04 15:45:58,121 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/dromaeo/cssquery-yui.html (next: http://localhost:36161/tests/dromaeo/cssquery-dojo.html) 15:45:58 INFO - 2016-01-04 15:45:58,371 DEBUG : BROWSER_OUTPUT: RSS: Main: 233984000 15:45:58 INFO - 2016-01-04 15:45:58,371 DEBUG : BROWSER_OUTPUT: 15:45:58 INFO - 2016-01-04 15:45:58,373 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:45:58 INFO - 2016-01-04 15:45:58,373 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:45:58 INFO - 2016-01-04 15:45:58,373 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:45:58 INFO - 2016-01-04 15:45:58,373 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:45:58 INFO - 2016-01-04 15:45:58,377 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3216.163797412673;3284.261041265714;3302.306515631717;3310.122817453374;3338.0820274424536;5547.861303467415;5577.135543990683;5586.078297080987;5609.074502707053;5610.607257491977;5577.553795696344;5606.299212598426;5613.270274864272;5621.7751289948455;5632.408597097297;4949.727764972943;5078.771455284503;5118.232265160219;5197.246399272103;5199.766010529516;4971.477994810529;4983.800647974077;5005.6746311489815;5094.184930411135;5114.335136432259;5121.308623335839;5178.352705911761;5181.922271165936;5186.299849520305;5213.843584692447;4894.36373271476;5110.6422550421485;5121.257417674454;5168.689878607295;5182.3262975813095;4914.238293064581;4991.401031876162;4996;5015.072211640858;5017.724025178613;5411.431799661058;5434.565234781208;5465.289512363407;5468.48049435303;5478.698671573062;5856.531477481792;5996.730147143346;6034.336223015464;6043.848903777441;6045.697715114226;5835.883282334329;5897.498712609433;5922.911156332659;5926.377730338296;5933.139694744281;5557.666540007572;5594;5594.412586678422;5617.831465056055;5624.775008999676;5980.372060933626;6055.878882422327;6065.696715164224;6074.605150665193;6109.633421994695;5944.108383742387;5946.583739138306;5950.732217050243;5951.5833891628035;5956.940430595725;6022.427869352405;6024.759009639653;6030.547708921848;6031.638101713911;6035.245594300713;5739.827805165852;5804.65172089676;5872.911906321408;5894.6757928314355;5986.670733109634;5948.881022379528;6001.06983417571;6005.159277701126;6005.759769609254;6011.849703757353;4603.562661547147;4609.815607375667;4610.216263235258;4613.261878099488;4640.419947506562;4001.679865610744;4017.5379831319187;4069;4111.691623128278;4112.383142528644;5616.466435688603;5717.742701578439;5744.4829965303325;5747.712614369265;5750.654960702372;5789.652620842763;6025.424278240709;6052.213212282463;6052.54605904559;6081.996470582393;5452.836414907559;5474.91787623186;5496.1755736639825;5569.66582005081;5569.888602227933;5196.740162991835;5211.478852114834;5213.504717051874;5214.9478505214465;5243.528082472596;5998.280206375263;6116.694165291807;6158.1686472326555;6163.96918015407;6166.445019948226;5944.464998150101;6034.818955431344;6042.84892877664;6043.12374705674;6058.697065146813;6160.199174107516;6164.840970028073;6180.381961803783;6197.349278325711;6201.782937597162;5699.914501282401;5769.278840144982;5802.245708057925;5931.762729490944;5986.281646202484;6063.514918806573;6069.51443884497;6069.878602428015;6070.332263451105;6082.422169893853;5917.91123133162;5929.377415371324;5931.9406805931385;5932.4957378621575;5933.762649493972;5642.689652069175;5738.110592858114;5798.884022319446;5902.675352855634;5944.405559444021;5767.163761254511;5769.048107062287;5775.162601422686;5776.162456443708;5778.219940308173;5826.76692932278;5828.94171058284;5872.060470324727;5887.0580707086665;5890.882182356415;5606.607537472338;5658.292713410824;5734.283214598176;5747.798827041033;5781.884362312646 15:45:58 INFO - 2016-01-04 15:45:58,378 DEBUG : BROWSER_OUTPUT: |1;ext.html;573.7418161827179;607.7751232044144;614.9600275982061;617.4072890025576;624.7063879976413;34770.130746731316;42457.15085698288;42970.570294297046;42972.14055718884;43028.78485607571;46119.30821037687;46757.76621116898;46858;46872.531274687244;46874.76562617182;8222.671093156296;8358.582070896462;9217.677381291656;9370.344075914689;10471.3193642413;10789;10889.346639201673;10907.072898803612;10907.890921090806;10917.508712107934;10908.563657453693;10980.450977451135;10998.780024399508;11011.61459348923;11014.44927753613;10893.128549716004;10957.780844383147;10969.670909872717;10984.231103822736;10987.725306867313;10707.946460267727;10854.131669466502;10868.45657717111;10870.347779133277;10880.510377033053;43385;43439.34840977388;43741.12517749627;43809;43907.78046109781;3584.480250363709;3688.0779805048737;3732.402815549526;4309.892252693677;4419.4033805436175;32720.836395817867;32810.671893281;33343.49984750231;33382.66617333844;33447.6655233447;3690.852365905361;3707.9814600927093;3770.754900931458;3817.064819119306;3860.305145073886;316618;317628;318139;447515;458712.65149393986;69193.65403173052;70753.64623176954;70770.2922970774;70882;71000.28999710041;65901;65905.34094659088;65921.67039164774;65944;66101.33898661048;18132.63734725298;18484.260629574936;18492.630147396976;18494.260229590665;18496.537586560444;13972.231527266098;14107.294635268401;14117.294135293194;14118.50585229512;14120.576382708536;20430.38708838737;20735.27426540094;20754.896225518776;20755.896220519102;20770.688439673417;8098.716544921017;8153.347732181412;8218.876716849252;8244.35112977407;8324.916750832414;1083.6044843632017;1098.319042193845;1111.4664960818852;1165.155262434728;1172.9882701172878;27570.448591028467;30841;30973.380532389678;30983.225419364695;31888.362232755215;1152.7694461107644;1175.2360965372404;1205.7467931734259;1230.993845030769;1246.6945598328468;13951.302434878215;15322.157281349633;15338.233088345538;15356.257992327268;15406.614834629;367618;372377;372609;373366;376029;54307.72846135744;54539.45460545344;54545.454545454035;54630.18054729182;54659;1347.7102422979106;1416.3358054286377;1478;1499.0930487055393;1522.375825911371;31822.36355272835;32100.518492222167;32180.67819321777;32431.675683242865;32599.837000814845;50835;51445;51452.742736286076;51595.22607160971;51603;330985;337835;337977;339092;340969;35952.64047359597;36211.27577448489;36322;36634.8168259157;36936.07659808526;48333.75833120952;48478.27282590693;50627.746861265456;52549;55879;42123.7893810529;42134;42934.141317172856;43563.12873742571;43824.780876095414;42199;42572.57427425686;42617.78691106525;42835.571644284406;43112;41935.16129677328;42047.15905681808;42460;42463.3630495549;42495.78752106219;36093.27813443769;36837;37266.25467490689;37790;38166;28607.42785144327;28865.278368040967;28957.56563651589;29205.7079429203;29306.267343316587 15:45:58 INFO - 2016-01-04 15:45:58,379 DEBUG : BROWSER_OUTPUT: |2;jquery.html;376.04484609092907;379.9810009499526;380.1901948848952;381.3973921204497;382.3022983055924;15463.768043479356;17017.319307227695;17888.284468621256;18965.62068758626;19006.429807105793;18764;19062.237510499563;19248.807511924875;19343.70984435231;19417.514562135955;5879.8530036749;6079.817605471821;6083.209182806251;6102.542309326796;6102.633841969474;4474.6196573291145;4614.723116613014;4615.861524154264;4617.053504031683;5965.164876917213;6409.903851442232;6454.419102280794;6609.801705948805;6614.8677026459445;6626.801195964104;6492.513061520382;6635.203775546917;6638.336166383371;6639.668016599151;6641.535092543524;5876.97061514694;5909.704514774244;5927.851803704899;5930.199423077873;6001.06983417571;23723.406914827094;24239.272821815375;24561.14036008749;24730;24733.381665458328;2489.639002344668;2639.5248855206055;2654.32314759737;2679.397135644484;2704.2022603332066;14967.550973470814;15018.624534386618;15151.242437878062;15368.692626147526;15603.765943510856;1980.8217260446486;1987.2547794577033;1995.800419958007;2006.7290915726337;2051.8153366196966;60869.391306086814;69072.65463672699;70650;71748.28251717468;72247.63876180637;7672.884906726404;7696.461247712718;7932.484388514844;7936.365090792723;7980.561069141253;9445.622175112918;9462.148406643435;9472.431654100776;9503.714888553355;9574.569144388519;6306.49548036156;6347.777827776005;6348.174737284124;6350.047492876106;6415.615063096229;6536.607803531803;6564.081028655992;6712.630805305755;6754.493412994045;6945.791626251221;3688.0779805048737;3716.832742526593;3718.2563487302646;3726.31063253299;3747.3067482515276;1262.8547717012477;1276.6361586947676;1282.4677758730022;1290.4064130500076;1293.8770816772392;518.4659800405589;519.4026869100564;535.333509780326;540.3326891289255;560.2520634952302;11521.539138434537;13017.47930082805;13088.803667944989;13419.530316438877;13461.326933653474;545.258448510026;549.1241469855574;557.1447827584719;559.2478116932718;575.8905807896487;7164.641767911584;7289.854202915912;7330.230325815819;7468.5145465544565;7476.813079672943;595185;599837;606522;609340.62637494;653388;80028.59985700034;81032.59483702543;81707;82260;82653;621.869407424446;626.4393367935672;627.1157667688537;627.8869803435425;629.4429429954523;11955.880441195477;12180.939095304468;12258.14193006481;12331.260124392566;12355.444005019615;34951;35460.64539354573;35671.28657426785;36429.089272767334;36549.26901462009;50121.74939125281;51199.2320115206;51426.48573514217;51504.74247628738;52072.73963630309;7364.3372096511575;7729.1884352142215;7738.7678369649;7744.457887948015;7746.883796742941;13438;13627.795583066063;13711.588652340446;13859.514916977858;14035.859641403455;12002.159848810848;12020.879791201976;12107.939460302641;12255.325957072446;12280.754384912074;9094.772630684021;11720.941395292968;11938.403079846146;12039.4582243801;12155;9934.913668129773;11116.944415277872;11638.185327027366;11708.824367634323;11983.580574679845;7704.884426733715;7705.807354816175;7722.768316950501;7727;7735.6518956648215;11856.70358241051;11989.82015269789;12016.879831201577;12219.633410997685;12269 15:45:58 INFO - 2016-01-04 15:45:58,381 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1585.1202582566684;1599.7360435528137;1658.1294820219387;1858.3310008396975;1924.8652594318385;6604.86790264195;6732.495062870286;6736.360045795642;6959.965200174005;7098.041764361809;7594.772156835304;7750.263724946135;7796.727114550992;7811.375089992787;7815.882761758578;2358.2453614843257;2388.3909603051284;2392.162743039931;2393.4854006388623;2393.8563686178886;2047.488127968008;2094.8428867834973;2353.305774796431;2382.2257766225957;2434.330559096245;2362.350353652742;2366.7869891709743;2378.7264464586533;2378.7740164684415;2380;2350.682657841195;2390.008146619151;2390.509945461185;2390.641403789437;2391.1272385579314;2380.4048987753063;2403.807695384365;2430.6961629796274;2431.0518897629763;2433.537627850703;8085;8143.877841832377;8174.305184059363;8183.6726530938695;8236.670533178725;4869.050535145648;4926;4928.457869634337;4929.65492415531;4932.605391568666;3113.828739419331;3165.1859213004777;3204.7916885402606;3214.164317277501;3236.336551007038;1077.3624091381134;1078.7033565769398;2038.7349644546262;2115.841311901598;2167.3931299236237;11179.720506987309;11272.718182045515;11309.264897781617;11315.434228288635;11325.093992480664;4382;4415.51429342772;4442.9777851111185;4488.079943611617;4490.2142125127975;8793.824123517621;8802.207801297915;8812.735617931472;8816.559172041501;8819.603117859711;4967.105920934267;4979.90040199194;4985.675931064469;4987.426445958761;4993.7503124843615;4607.585317321457;4616.815327386867;4629.606483448944;4630.86107416778;4631.95368046322;3340.1983523954077;3610.2598967211657;3624.619414961391;3674.1365779041903;3690.538682664667;1057.841323801436;1059.8039362717768;1168.0830548019765;1168.3865970365625;1177.7879981603223;1186.2882270637722;1189.5182451107141;1234.0127897681818;1467.4423718986716;1484.6140003599141;7735.729249476241;7811.80470488231;7936.404769642185;7949.880751788728;7950.880736788953;763.7097902796902;787.8384931089113;799.4124318625805;802.4904185842032;897.1297841094129;6221.5333849961435;6319.399657032574;6321.431071203614;6332.651704156316;6335.493160547145;8832.955835220911;8852.8672069919;8881.600327985256;8882.022977472472;8885.42244754102;9459.810803783887;9578.473183974947;9650.806983860284;9847.704568862802;9853.852192217122;7542.47202695817;7603.543787372665;7610.733624323122;7613.733519326797;7616.352610028099;7448.292412220831;7520.586367749826;7706.306432421109;7792.727254546064;7795.142534321333;8585.957070214608;8750.781230469289;8779.956100219459;8823.955880220557;8824.647014119364;8768.079351668239;8914.821703566022;8949.731508054769;8963.731088067369;9000.099990000948;7444.069491313586;7501.587412692136;7524.17234104259;7539.849203016019;7541.698332066656;6782.423494003065;7177.031100801325;7761.223877612194;7952.880706789287;8093.635786389745;7320.341169294789;7395.260473952561;7413.66638501258;7415.405687777154;7554.206808285271;7330.450216233698;7392.519486233377;7400.074990626172;7471.364933980455;7486.663100160397;7513.774586762406;7572.167061623327;7612.7716168515035;7613.809654758676;7628;6716.093327400738;6758.256591774999;6758.324167583202;6768.56004359715;6780.118584583973;6833.624150671761;6843.110395648534;6845.623490708059;6846.554973926679;6846.554973926679 15:45:58 INFO - 2016-01-04 15:45:58,382 DEBUG : BROWSER_OUTPUT: |4;prototype.html;356.5365025466894;358.9407747721626;361.98914032579034;365.4426998826787;366.8880991297654;5736.856578585537;5809.564282678795;5842.357340692526;5882.617629854062;5888.381719919405;5690.57320700947;5782.681952492611;5796.5652576056855;5805.158252053449;5805.680687562182;4561.543845615445;4564.315352697089;4574.268117101265;4576.267797152456;4578.084383123389;4368.584984426474;4432.113577284556;4503.211937910869;4573.656975726817;4577.038821847416;4514.0746147039945;4529.433820772403;4530.886727831814;4533.818647254106;4537.160625284333;4534.6372290216705;4535.433070866142;4542.818287268506;4543.341215523764;4548.5906268436;4023.4568333274924;4032.5362583303004;4036.1322315702264;4039.0508230565792;4042.514898212204;5491.807786727485;5542.2795036644975;5552.250446189793;5586.776528938837;5610.354809196954;5226.555742761831;5232.686038837682;5234.110201265794;5234.738263086831;5241.109011468059;5895.056790913433;6100.542459315525;6172.22847144107;6174.876502469971;6183.072539119124;4175.164967006581;4310.3103503439615;4360.672949528798;4361.084172323815;4368.388425620416;6198.969005154945;6233.1273621693;6241.469475094623;6244.12582238487;6260.123582698426;4445.377647129404;4466.754328511898;4468.173387923244;4470.307102399133;4473.3066374711325;4444.11117776446;4474.172278128557;4506.143832671847;4506.81972721094;4507.81968721254;5151.5363617274625;5160.509751573594;5161.483851614808;5162.354705661793;5166.638335316567;6104.816855494342;6106.938930610726;6107.144999700045;6107.877842443127;6111.052786818051;1630.9347626094823;1658.4444211189386;1660.2362913059885;1660.7757952676475;1662.1938359895441;1117.636768050387;1118.5190368141614;1122.315387613555;1123.393367581497;1144.0104309771987;6102.481289090389;6104.175936248638;6104.755809767648;6110.633361998294;6110.7861224856915;6377.7767778127545;6379.936200638027;6424.614523128534;6435.710393032325;6449.193850768654;5955.166276721263;6081.361457046946;6085.330613632497;6086.452219300196;6090.725917333731;3503.4394496880377;3562.109472631842;3563;3566.964330356663;3567.161716996504;6522.282548919615;6564.212294524687;6569.408753212234;6572.07990881277;6618.040384144272;6600.141981542465;6601.471882249409;6605.57063790852;6608.867822643617;6610.140681711347;6491.67541622917;6536.248331441945;6623.701933412912;6624.536282460278;6626.370494802986;6209.068639704081;6303.527235457267;6417.807465776034;6423.389777971272;6427.132337134427;6258.311585725475;6353.396427339396;6363.331850155667;6373.74505019794;6375.521835862236;6523.478121750154;6528.020796880506;6568.507361947969;6595;6599.505037122139;4442.48911375196;4476.08240310742;4503.954960450485;4506.7070640408265;4509.2559727644575;6399.968000159969;6403;6403.3276505966205;6406.23125224976;6406.391392817675;6227.501799856092;6230.283517395557;6236.438720515175;6245.28179259391;6252.781152659635;6221.09794079865;6228.22147231596;6229.750809967551;6238.781642642486;6250.906236406367;6195.597286176384;6199.132121502903;6201.224846894138;6203.2245969253845;6204.038374052029;6316.336784637547;6322.3045464997895;6324.905126423199;6329;6332.0818481320875;6083.7870674526175;6164.321924588381;6165.352637972948;6179.443850053517;6184.257889053342 15:45:58 INFO - 2016-01-04 15:45:58,383 DEBUG : BROWSER_OUTPUT: |5;yui.html;764.9273319034692;882.7925437522183;956.5217391304348;960.6205548808225;963.1331801378763;15289.464868729598;15595.688086238268;15643.217839108042;15658.608534786636;15665.92167039166;14185.929070354634;14254.216018119;14267.0726402784;14269.571912842606;14271.215083170448;766.84279722657;776.596169991604;922.8707980882682;930.9906900931005;941.7975135345899;933.4259430450292;938.1462868789403;939.9671011514598;941.7174847545743;942.189716843514;816.4611356504709;987.7283746969568;990.5195979949755;990.5938565188275;991.5488452754016;813.2436833744615;964.3876138651972;972.3290929258789;972.7713987212999;975.3562648651856;723.5803234124248;723.6237156678498;724.1455083002055;725.6263024542365;728.7522242437543;14212.147271163763;14337.28313584327;14343.856561434357;14345.928270358685;14353.78469322961;13581.253031083286;14375.496857610038;14395.928020359832;14456.710865782625;14531.636709082251;11033.613823516138;11389.886101138965;11456;11587.884121158764;11604.477798499087;3477.2697733476;3478.7738796978115;3522.5420695309704;3536.3634545781747;3570.357335679577;50232.49767502315;50426.24360634593;50598.24102638463;50852.745736271085;50976.49023509792;8270.669173233004;8328.042275138316;8358.247757701714;8378.24595786382;8393.076761556224;13771.311434428238;13799.2410417428;13869.583912482642;14192;14263;7384.040074790243;7448.180700122982;7533.698652053966;7556.848863022708;7822.4524283299625;4740.974566959035;4793.18515852306;4826.734529600905;4861.611071114373;4861.635377346643;2902.1003488918504;2913.1115009922178;2918.5184444566407;2923.4591600553968;2928.4143171365813;435.9324304732708;448.49320268097495;450.0301849514302;453.75270477589794;456.4818930513891;718.4611541343992;757.1217387830201;761.5012167030604;769.6575024114327;777.5762209595783;9564;9943.801123977624;9991.200703943667;10035.347702399467;10090.44502552352;648.7632014314836;744.5383862005591;746.3653303837463;756.587659725451;760.9429292803062;4575.931361029588;4588.197065513522;4616.584507394351;4674.57928786404;4843.491433399513;65535.34464655388;66964.66517667478;67088;67348.6632566834;67732.66133669399;61520;61749;61924.69037654783;62133;62166.37833621696;54826;54973.45026549683;55240.44759552353;55268.894622107335;56650.43349566534;9574.090461406155;9789.559469824018;9854.237540662072;10069.69790906274;10075.596976120873;39097.2180556393;40236.59763402329;41286.1742765137;41411.7929410351;41527.792361038;52330.47669523256;52612;53357;54134.72932635312;54146.72926635342;8823.161799629024;9101.954490227507;9268.675596354095;9271.165595096474;9311;13797.379117939918;13824.447022119404;13888.236147012012;14010.929945350208;14073.225972571607;12016.939915300367;12132.575359862363;12169.939150304192;12333.93833030829;12690.492380305053;12993.155444896052;13135.474581017035;13159.802602961156;13217.339133043502;13280;12922.870771292166;13039.152455090769;13106.868931310564;13271.402786874422;13339.866601333862;11228.157888158585;11336.489857956576;11521.88478115208;11568.479418426023;11692.123090768056;10507.684769456928;10595.258331916692;10602.416867072385;10637.255392122477;10641 15:45:58 INFO - 2016-01-04 15:45:58,383 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:45:58 INFO - 2016-01-04 15:45:58,383 DEBUG : BROWSER_OUTPUT: __start_cc_report 15:45:58 INFO - 2016-01-04 15:45:58,383 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,833 15:45:58 INFO - 2016-01-04 15:45:58,383 DEBUG : BROWSER_OUTPUT: __end_cc_report 15:45:58 INFO - 2016-01-04 15:45:58,383 DEBUG : BROWSER_OUTPUT: __startTimestamp1451951158372__endTimestamp 15:45:58 INFO - 2016-01-04 15:45:58,384 DEBUG : BROWSER_OUTPUT: 15:45:58 INFO - 2016-01-04 15:45:58,384 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:45:58 INFO - 2016-01-04 15:45:58,384 DEBUG : BROWSER_OUTPUT: Number of tests: 6 15:45:58 INFO - 2016-01-04 15:45:58,384 DEBUG : BROWSER_OUTPUT: 15:45:58 INFO - 2016-01-04 15:45:58,384 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5550.27 Median:5750.65 stddev:622.78 (10.8%) stddev-sans-first:597.17 15:45:58 INFO - 2016-01-04 15:45:58,384 DEBUG : BROWSER_OUTPUT: Values: 3216.2 3284.3 3302.3 3310.1 3338.1 5547.9 5577.1 5586.1 5609.1 5610.6 5577.6 5606.3 5613.3 5621.8 5632.4 4949.7 5078.8 5118.2 5197.2 5199.8 4971.5 4983.8 5005.7 5094.2 5114.3 5121.3 5178.4 5181.9 5186.3 5213.8 4894.4 5110.6 5121.3 5168.7 5182.3 4914.2 4991.4 4996.0 5015.1 5017.7 5411.4 5434.6 5465.3 5468.5 5478.7 5856.5 5996.7 6034.3 6043.8 6045.7 5835.9 5897.5 5922.9 5926.4 5933.1 5557.7 5594.0 5594.4 5617.8 5624.8 5980.4 6055.9 6065.7 6074.6 6109.6 5944.1 5946.6 5950.7 5951.6 5956.9 6022.4 6024.8 6030.5 6031.6 6035.2 5739.8 5804.7 5872.9 5894.7 5986.7 5948.9 6001.1 6005.2 6005.8 6011.8 4603.6 4609.8 4610.2 4613.3 4640.4 4001.7 4017.5 4069.0 4111.7 4112.4 5616.5 5717.7 5744.5 5747.7 5750.7 5789.7 6025.4 6052.2 6052.5 6082.0 5452.8 5474.9 5496.2 5569.7 5569.9 5196.7 5211.5 5213.5 5214.9 5243.5 5998.3 6116.7 6158.2 6164.0 6166.4 5944.5 6034.8 6042.8 6043.1 6058.7 6160.2 6164.8 6180.4 6197.3 6201.8 5699.9 5769.3 5802.2 5931.8 5986.3 6063.5 6069.5 6069.9 6070.3 6082.4 5917.9 5929.4 5931.9 5932.5 5933.8 5642.7 5738.1 5798.9 5902.7 5944.4 5767.2 5769.0 5775.2 5776.2 5778.2 5826.8 5828.9 5872.1 5887.1 5890.9 5606.6 5658.3 5734.3 5747.8 5781.9 15:45:58 INFO - 2016-01-04 15:45:58,384 DEBUG : BROWSER_OUTPUT: 15:45:58 INFO - 2016-01-04 15:45:58,384 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:54842.87 Median:202126.41 stddev:95153.15 (47.1%) stddev-sans-first:95332.83 15:45:58 INFO - 2016-01-04 15:45:58,385 DEBUG : BROWSER_OUTPUT: Values: 573.7 607.8 615.0 617.4 624.7 34770.1 42457.2 42970.6 42972.1 43028.8 46119.3 46757.8 46858.0 46872.5 46874.8 8222.7 8358.6 9217.7 9370.3 10471.3 10789.0 10889.3 10907.1 10907.9 10917.5 10908.6 10980.5 10998.8 11011.6 11014.4 10893.1 10957.8 10969.7 10984.2 10987.7 10707.9 10854.1 10868.5 10870.3 10880.5 43385.0 43439.3 43741.1 43809.0 43907.8 3584.5 3688.1 3732.4 4309.9 4419.4 32720.8 32810.7 33343.5 33382.7 33447.7 3690.9 3708.0 3770.8 3817.1 3860.3 316618.0 317628.0 318139.0 447515.0 458712.7 69193.7 70753.6 70770.3 70882.0 71000.3 65901.0 65905.3 65921.7 65944.0 66101.3 18132.6 18484.3 18492.6 18494.3 18496.5 13972.2 14107.3 14117.3 14118.5 14120.6 20430.4 20735.3 20754.9 20755.9 20770.7 8098.7 8153.3 8218.9 8244.4 8324.9 1083.6 1098.3 1111.5 1165.2 1173.0 27570.4 30841.0 30973.4 30983.2 31888.4 1152.8 1175.2 1205.7 1231.0 1246.7 13951.3 15322.2 15338.2 15356.3 15406.6 367618.0 372377.0 372609.0 373366.0 376029.0 54307.7 54539.5 54545.5 54630.2 54659.0 1347.7 1416.3 1478.0 1499.1 1522.4 31822.4 32100.5 32180.7 32431.7 32599.8 50835.0 51445.0 51452.7 51595.2 51603.0 330985.0 337835.0 337977.0 339092.0 340969.0 35952.6 36211.3 36322.0 36634.8 36936.1 48333.8 48478.3 50627.7 52549.0 55879.0 42123.8 42134.0 42934.1 43563.1 43824.8 42199.0 42572.6 42617.8 42835.6 43112.0 41935.2 42047.2 42460.0 42463.4 42495.8 36093.3 36837.0 37266.3 37790.0 38166.0 28607.4 28865.3 28957.6 29205.7 29306.3 15:45:58 INFO - 2016-01-04 15:45:58,385 DEBUG : BROWSER_OUTPUT: 15:45:58 INFO - 2016-01-04 15:45:58,385 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30761.42 Median:26011.60 stddev:100344.68 (385.8%) stddev-sans-first:100600.23 15:45:58 INFO - 2016-01-04 15:45:58,386 DEBUG : BROWSER_OUTPUT: Values: 376.0 380.0 380.2 381.4 382.3 15463.8 17017.3 17888.3 18965.6 19006.4 18764.0 19062.2 19248.8 19343.7 19417.5 5879.9 6079.8 6083.2 6102.5 6102.6 4474.6 4614.7 4615.9 4617.1 5965.2 6409.9 6454.4 6609.8 6614.9 6626.8 6492.5 6635.2 6638.3 6639.7 6641.5 5877.0 5909.7 5927.9 5930.2 6001.1 23723.4 24239.3 24561.1 24730.0 24733.4 2489.6 2639.5 2654.3 2679.4 2704.2 14967.6 15018.6 15151.2 15368.7 15603.8 1980.8 1987.3 1995.8 2006.7 2051.8 60869.4 69072.7 70650.0 71748.3 72247.6 7672.9 7696.5 7932.5 7936.4 7980.6 9445.6 9462.1 9472.4 9503.7 9574.6 6306.5 6347.8 6348.2 6350.0 6415.6 6536.6 6564.1 6712.6 6754.5 6945.8 3688.1 3716.8 3718.3 3726.3 3747.3 1262.9 1276.6 1282.5 1290.4 1293.9 518.5 519.4 535.3 540.3 560.3 11521.5 13017.5 13088.8 13419.5 13461.3 545.3 549.1 557.1 559.2 575.9 7164.6 7289.9 7330.2 7468.5 7476.8 595185.0 599837.0 606522.0 609340.6 653388.0 80028.6 81032.6 81707.0 82260.0 82653.0 621.9 626.4 627.1 627.9 629.4 11955.9 12180.9 12258.1 12331.3 12355.4 34951.0 35460.6 35671.3 36429.1 36549.3 50121.7 51199.2 51426.5 51504.7 52072.7 7364.3 7729.2 7738.8 7744.5 7746.9 13438.0 13627.8 13711.6 13859.5 14035.9 12002.2 12020.9 12107.9 12255.3 12280.8 9094.8 11720.9 11938.4 12039.5 12155.0 9934.9 11116.9 11638.2 11708.8 11983.6 7704.9 7705.8 7722.8 7727.0 7735.7 11856.7 11989.8 12016.9 12219.6 12269.0 15:45:58 INFO - 2016-01-04 15:45:58,386 DEBUG : BROWSER_OUTPUT: 15:45:58 INFO - 2016-01-04 15:45:58,386 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5661.19 Median:6763.44 stddev:2875.01 (42.5%) stddev-sans-first:2866.75 15:45:58 INFO - 2016-01-04 15:45:58,387 DEBUG : BROWSER_OUTPUT: Values: 1585.1 1599.7 1658.1 1858.3 1924.9 6604.9 6732.5 6736.4 6960.0 7098.0 7594.8 7750.3 7796.7 7811.4 7815.9 2358.2 2388.4 2392.2 2393.5 2393.9 2047.5 2094.8 2353.3 2382.2 2434.3 2362.4 2366.8 2378.7 2378.8 2380.0 2350.7 2390.0 2390.5 2390.6 2391.1 2380.4 2403.8 2430.7 2431.1 2433.5 8085.0 8143.9 8174.3 8183.7 8236.7 4869.1 4926.0 4928.5 4929.7 4932.6 3113.8 3165.2 3204.8 3214.2 3236.3 1077.4 1078.7 2038.7 2115.8 2167.4 11179.7 11272.7 11309.3 11315.4 11325.1 4382.0 4415.5 4443.0 4488.1 4490.2 8793.8 8802.2 8812.7 8816.6 8819.6 4967.1 4979.9 4985.7 4987.4 4993.8 4607.6 4616.8 4629.6 4630.9 4632.0 3340.2 3610.3 3624.6 3674.1 3690.5 1057.8 1059.8 1168.1 1168.4 1177.8 1186.3 1189.5 1234.0 1467.4 1484.6 7735.7 7811.8 7936.4 7949.9 7950.9 763.7 787.8 799.4 802.5 897.1 6221.5 6319.4 6321.4 6332.7 6335.5 8833.0 8852.9 8881.6 8882.0 8885.4 9459.8 9578.5 9650.8 9847.7 9853.9 7542.5 7603.5 7610.7 7613.7 7616.4 7448.3 7520.6 7706.3 7792.7 7795.1 8586.0 8750.8 8780.0 8824.0 8824.6 8768.1 8914.8 8949.7 8963.7 9000.1 7444.1 7501.6 7524.2 7539.8 7541.7 6782.4 7177.0 7761.2 7952.9 8093.6 7320.3 7395.3 7413.7 7415.4 7554.2 7330.5 7392.5 7400.1 7471.4 7486.7 7513.8 7572.2 7612.8 7613.8 7628.0 6716.1 6758.3 6758.3 6768.6 6780.1 6833.6 6843.1 6845.6 6846.6 6846.6 15:45:58 INFO - 2016-01-04 15:45:58,387 DEBUG : BROWSER_OUTPUT: 15:45:58 INFO - 2016-01-04 15:45:58,387 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5216.09 Median:5925.11 stddev:1536.01 (25.9%) stddev-sans-first:1496.39 15:45:58 INFO - 2016-01-04 15:45:58,387 DEBUG : BROWSER_OUTPUT: Values: 356.5 358.9 362.0 365.4 366.9 5736.9 5809.6 5842.4 5882.6 5888.4 5690.6 5782.7 5796.6 5805.2 5805.7 4561.5 4564.3 4574.3 4576.3 4578.1 4368.6 4432.1 4503.2 4573.7 4577.0 4514.1 4529.4 4530.9 4533.8 4537.2 4534.6 4535.4 4542.8 4543.3 4548.6 4023.5 4032.5 4036.1 4039.1 4042.5 5491.8 5542.3 5552.3 5586.8 5610.4 5226.6 5232.7 5234.1 5234.7 5241.1 5895.1 6100.5 6172.2 6174.9 6183.1 4175.2 4310.3 4360.7 4361.1 4368.4 6199.0 6233.1 6241.5 6244.1 6260.1 4445.4 4466.8 4468.2 4470.3 4473.3 4444.1 4474.2 4506.1 4506.8 4507.8 5151.5 5160.5 5161.5 5162.4 5166.6 6104.8 6106.9 6107.1 6107.9 6111.1 1630.9 1658.4 1660.2 1660.8 1662.2 1117.6 1118.5 1122.3 1123.4 1144.0 6102.5 6104.2 6104.8 6110.6 6110.8 6377.8 6379.9 6424.6 6435.7 6449.2 5955.2 6081.4 6085.3 6086.5 6090.7 3503.4 3562.1 3563.0 3567.0 3567.2 6522.3 6564.2 6569.4 6572.1 6618.0 6600.1 6601.5 6605.6 6608.9 6610.1 6491.7 6536.2 6623.7 6624.5 6626.4 6209.1 6303.5 6417.8 6423.4 6427.1 6258.3 6353.4 6363.3 6373.7 6375.5 6523.5 6528.0 6568.5 6595.0 6599.5 4442.5 4476.1 4504.0 4506.7 4509.3 6400.0 6403.0 6403.3 6406.2 6406.4 6227.5 6230.3 6236.4 6245.3 6252.8 6221.1 6228.2 6229.8 6238.8 6250.9 6195.6 6199.1 6201.2 6203.2 6204.0 6316.3 6322.3 6324.9 6329.0 6332.1 6083.8 6164.3 6165.4 6179.4 6184.3 15:45:58 INFO - 2016-01-04 15:45:58,387 DEBUG : BROWSER_OUTPUT: 15:45:58 INFO - 2016-01-04 15:45:58,387 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15423.48 Median:4835.11 stddev:18665.79 (386.0%) stddev-sans-first:18685.70 15:45:58 INFO - 2016-01-04 15:45:58,387 DEBUG : BROWSER_OUTPUT: Values: 764.9 882.8 956.5 960.6 963.1 15289.5 15595.7 15643.2 15658.6 15665.9 14185.9 14254.2 14267.1 14269.6 14271.2 766.8 776.6 922.9 931.0 941.8 933.4 938.1 940.0 941.7 942.2 816.5 987.7 990.5 990.6 991.5 813.2 964.4 972.3 972.8 975.4 723.6 723.6 724.1 725.6 728.8 14212.1 14337.3 14343.9 14345.9 14353.8 13581.3 14375.5 14395.9 14456.7 14531.6 11033.6 11389.9 11456.0 11587.9 11604.5 3477.3 3478.8 3522.5 3536.4 3570.4 50232.5 50426.2 50598.2 50852.7 50976.5 8270.7 8328.0 8358.2 8378.2 8393.1 13771.3 13799.2 13869.6 14192.0 14263.0 7384.0 7448.2 7533.7 7556.8 7822.5 4741.0 4793.2 4826.7 4861.6 4861.6 2902.1 2913.1 2918.5 2923.5 2928.4 435.9 448.5 450.0 453.8 456.5 718.5 757.1 761.5 769.7 777.6 9564.0 9943.8 9991.2 10035.3 10090.4 648.8 744.5 746.4 756.6 760.9 4575.9 4588.2 4616.6 4674.6 4843.5 65535.3 66964.7 67088.0 67348.7 67732.7 61520.0 61749.0 61924.7 62133.0 62166.4 54826.0 54973.5 55240.4 55268.9 56650.4 9574.1 9789.6 9854.2 10069.7 10075.6 39097.2 40236.6 41286.2 41411.8 41527.8 52330.5 52612.0 53357.0 54134.7 54146.7 8823.2 9102.0 9268.7 9271.2 9311.0 13797.4 13824.4 13888.2 14010.9 14073.2 12016.9 12132.6 12169.9 12333.9 12690.5 12993.2 13135.5 13159.8 13217.3 13280.0 12922.9 13039.2 13106.9 13271.4 13339.9 11228.2 11336.5 11521.9 11568.5 11692.1 10507.7 10595.3 10602.4 10637.3 10641.0 15:45:58 INFO - 2016-01-04 15:45:58,387 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:45:58 INFO - 2016-01-04 15:45:58,387 DEBUG : BROWSER_OUTPUT: 15:45:58 INFO - 2016-01-04 15:45:58,874 INFO : Browser exited with error code: 0 15:45:58 INFO - 2016-01-04 15:45:58,897 INFO : Completed test dromaeo_css (00:18:30) 15:45:58 INFO - 2016-01-04 15:45:58,897 INFO : Starting test kraken 15:45:58 INFO - 2016-01-04 15:45:58,897 DEBUG : operating with platform_type : linux_ 15:45:58 INFO - 2016-01-04 15:45:58,897 INFO : Initialising browser for kraken test... 15:45:58 INFO - 2016-01-04 15:45:58,903 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpseYwgE/profile http://localhost:36161/getInfo.html 15:46:02 INFO - 2016-01-04 15:46:02,401 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:46:02 INFO - 2016-01-04 15:46:02,401 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:46:02 INFO - 2016-01-04 15:46:02,401 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:46:02 INFO - 2016-01-04 15:46:02,402 DEBUG : BROWSER_OUTPUT: __metrics 15:46:02 INFO - 2016-01-04 15:46:02,503 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] 15:46:03 INFO - 2016-01-04 15:46:03,741 INFO : Browser initialized. 15:46:03 INFO - 2016-01-04 15:46:03,742 INFO : Running cycle 1/1 for kraken test... 15:46:03 INFO - 2016-01-04 15:46:03,742 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpseYwgE/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:46:15 INFO - 2016-01-04 15:46:15,291 DEBUG : BROWSER_OUTPUT: RSS: Main: 129302528 15:46:15 INFO - 2016-01-04 15:46:15,291 DEBUG : BROWSER_OUTPUT: 15:46:17 INFO - 2016-01-04 15:46:17,727 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36161/tests/kraken/driver.html?testName=audio-beat-detection) 15:46:18 INFO - 2016-01-04 15:46:18,294 DEBUG : BROWSER_OUTPUT: RSS: Main: 163131392 15:46:18 INFO - 2016-01-04 15:46:18,294 DEBUG : BROWSER_OUTPUT: 15:46:20 INFO - 2016-01-04 15:46:20,802 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36161/tests/kraken/driver.html?testName=audio-dft) 15:46:21 INFO - 2016-01-04 15:46:21,368 DEBUG : BROWSER_OUTPUT: RSS: Main: 160997376 15:46:21 INFO - 2016-01-04 15:46:21,368 DEBUG : BROWSER_OUTPUT: 15:46:24 INFO - 2016-01-04 15:46:24,167 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36161/tests/kraken/driver.html?testName=audio-fft) 15:46:24 INFO - 2016-01-04 15:46:24,737 DEBUG : BROWSER_OUTPUT: RSS: Main: 158785536 15:46:24 INFO - 2016-01-04 15:46:24,737 DEBUG : BROWSER_OUTPUT: 15:46:26 INFO - 2016-01-04 15:46:26,702 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36161/tests/kraken/driver.html?testName=audio-oscillator) 15:46:27 INFO - 2016-01-04 15:46:27,263 DEBUG : BROWSER_OUTPUT: RSS: Main: 158277632 15:46:27 INFO - 2016-01-04 15:46:27,263 DEBUG : BROWSER_OUTPUT: 15:46:29 INFO - 2016-01-04 15:46:29,396 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36161/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:46:29 INFO - 2016-01-04 15:46:29,945 DEBUG : BROWSER_OUTPUT: RSS: Main: 155881472 15:46:29 INFO - 2016-01-04 15:46:29,945 DEBUG : BROWSER_OUTPUT: 15:46:32 INFO - 2016-01-04 15:46:32,784 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36161/tests/kraken/driver.html?testName=imaging-darkroom) 15:46:33 INFO - 2016-01-04 15:46:33,347 DEBUG : BROWSER_OUTPUT: RSS: Main: 155987968 15:46:33 INFO - 2016-01-04 15:46:33,347 DEBUG : BROWSER_OUTPUT: 15:46:36 INFO - 2016-01-04 15:46:36,677 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36161/tests/kraken/driver.html?testName=imaging-desaturate) 15:46:37 INFO - 2016-01-04 15:46:37,243 DEBUG : BROWSER_OUTPUT: RSS: Main: 158498816 15:46:37 INFO - 2016-01-04 15:46:37,244 DEBUG : BROWSER_OUTPUT: 15:46:39 INFO - 2016-01-04 15:46:39,929 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36161/tests/kraken/driver.html?testName=json-parse-financial) 15:46:40 INFO - 2016-01-04 15:46:40,495 DEBUG : BROWSER_OUTPUT: RSS: Main: 156721152 15:46:40 INFO - 2016-01-04 15:46:40,495 DEBUG : BROWSER_OUTPUT: 15:46:42 INFO - 2016-01-04 15:46:42,015 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36161/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:46:42 INFO - 2016-01-04 15:46:42,577 DEBUG : BROWSER_OUTPUT: RSS: Main: 162713600 15:46:42 INFO - 2016-01-04 15:46:42,577 DEBUG : BROWSER_OUTPUT: 15:46:44 INFO - 2016-01-04 15:46:44,287 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36161/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:46:44 INFO - 2016-01-04 15:46:44,873 DEBUG : BROWSER_OUTPUT: RSS: Main: 160694272 15:46:44 INFO - 2016-01-04 15:46:44,873 DEBUG : BROWSER_OUTPUT: 15:46:46 INFO - 2016-01-04 15:46:46,824 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36161/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:46:47 INFO - 2016-01-04 15:46:47,386 DEBUG : BROWSER_OUTPUT: RSS: Main: 166023168 15:46:47 INFO - 2016-01-04 15:46:47,386 DEBUG : BROWSER_OUTPUT: 15:46:49 INFO - 2016-01-04 15:46:49,692 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36161/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:46:50 INFO - 2016-01-04 15:46:50,273 DEBUG : BROWSER_OUTPUT: RSS: Main: 172150784 15:46:50 INFO - 2016-01-04 15:46:50,273 DEBUG : BROWSER_OUTPUT: 15:46:52 INFO - 2016-01-04 15:46:52,688 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36161/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:46:53 INFO - 2016-01-04 15:46:53,250 DEBUG : BROWSER_OUTPUT: RSS: Main: 165789696 15:46:53 INFO - 2016-01-04 15:46:53,250 DEBUG : BROWSER_OUTPUT: 15:46:54 INFO - 2016-01-04 15:46:54,859 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36161/tests/kraken/driver.html?testName=ai-astar) 15:46:55 INFO - 2016-01-04 15:46:55,109 DEBUG : BROWSER_OUTPUT: RSS: Main: 225370112 15:46:55 INFO - 2016-01-04 15:46:55,110 DEBUG : BROWSER_OUTPUT: 15:46:55 INFO - 2016-01-04 15:46:55,111 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:46:55 INFO - 2016-01-04 15:46:55,111 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:46:55 INFO - 2016-01-04 15:46:55,111 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:46:55 INFO - 2016-01-04 15:46:55,112 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:46:55 INFO - 2016-01-04 15:46:55,112 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;105;108;111;110;100;109;106;110 15:46:55 INFO - 2016-01-04 15:46:55,112 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;145;120;145;150;122;149;141;121;159;120 15:46:55 INFO - 2016-01-04 15:46:55,112 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;160;159;160;159;159;159;164;161;158 15:46:55 INFO - 2016-01-04 15:46:55,112 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;72;70;156;71;70;70;70;145;76 15:46:55 INFO - 2016-01-04 15:46:55,113 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;90;135;89;89;90;134;88;88;129 15:46:55 INFO - 2016-01-04 15:46:55,113 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;110;110;110;110;109;110;110;110 15:46:55 INFO - 2016-01-04 15:46:55,113 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;147;146;146;145;146;146;145;145;144 15:46:55 INFO - 2016-01-04 15:46:55,113 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;95;96;95;95;96 15:46:55 INFO - 2016-01-04 15:46:55,113 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;54;59;55;55;54;54;55;54;54 15:46:55 INFO - 2016-01-04 15:46:55,113 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;63;63;63;64;63;63;63;64;63 15:46:55 INFO - 2016-01-04 15:46:55,113 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;76;78;78;79;78;77;77;78;78 15:46:55 INFO - 2016-01-04 15:46:55,113 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;118;111;105;107;109;124;113;107;113;109 15:46:55 INFO - 2016-01-04 15:46:55,113 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;133;139;136;136;147;135;138;133;129;147 15:46:55 INFO - 2016-01-04 15:46:55,114 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;63;61;64;63;62;62;64;63;62 15:46:55 INFO - 2016-01-04 15:46:55,114 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:46:55 INFO - 2016-01-04 15:46:55,114 DEBUG : BROWSER_OUTPUT: __start_cc_report 15:46:55 INFO - 2016-01-04 15:46:55,114 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,859 15:46:55 INFO - 2016-01-04 15:46:55,114 DEBUG : BROWSER_OUTPUT: __end_cc_report 15:46:55 INFO - 2016-01-04 15:46:55,114 DEBUG : BROWSER_OUTPUT: __startTimestamp1451951215110__endTimestamp 15:46:55 INFO - 2016-01-04 15:46:55,114 DEBUG : BROWSER_OUTPUT: 15:46:55 INFO - 2016-01-04 15:46:55,117 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:46:55 INFO - 2016-01-04 15:46:55,117 DEBUG : BROWSER_OUTPUT: Number of tests: 14 15:46:55 INFO - 2016-01-04 15:46:55,117 DEBUG : BROWSER_OUTPUT: 15:46:55 INFO - 2016-01-04 15:46:55,117 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.70 Median:109.50 stddev:3.30 (3.0%) stddev-sans-first:3.50 15:46:55 INFO - 2016-01-04 15:46:55,117 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 105.0 108.0 111.0 110.0 100.0 109.0 106.0 110.0 15:46:55 INFO - 2016-01-04 15:46:55,118 DEBUG : BROWSER_OUTPUT: 15:46:55 INFO - 2016-01-04 15:46:55,118 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:137.20 Median:145.00 stddev:14.91 (10.3%) stddev-sans-first:15.54 15:46:55 INFO - 2016-01-04 15:46:55,118 DEBUG : BROWSER_OUTPUT: Values: 145.0 120.0 145.0 150.0 122.0 149.0 141.0 121.0 159.0 120.0 15:46:55 INFO - 2016-01-04 15:46:55,118 DEBUG : BROWSER_OUTPUT: 15:46:55 INFO - 2016-01-04 15:46:55,118 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:1.79 (1.1%) stddev-sans-first:1.76 15:46:55 INFO - 2016-01-04 15:46:55,118 DEBUG : BROWSER_OUTPUT: Values: 162.0 160.0 159.0 160.0 159.0 159.0 159.0 164.0 161.0 158.0 15:46:55 INFO - 2016-01-04 15:46:55,118 DEBUG : BROWSER_OUTPUT: 15:46:55 INFO - 2016-01-04 15:46:55,118 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.00 Median:70.00 stddev:33.62 (48.0%) stddev-sans-first:35.09 15:46:55 INFO - 2016-01-04 15:46:55,118 DEBUG : BROWSER_OUTPUT: Values: 70.0 72.0 70.0 156.0 71.0 70.0 70.0 70.0 145.0 76.0 15:46:55 INFO - 2016-01-04 15:46:55,119 DEBUG : BROWSER_OUTPUT: 15:46:55 INFO - 2016-01-04 15:46:55,119 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.10 Median:89.00 stddev:21.16 (23.8%) stddev-sans-first:21.90 15:46:55 INFO - 2016-01-04 15:46:55,119 DEBUG : BROWSER_OUTPUT: Values: 89.0 90.0 135.0 89.0 89.0 90.0 134.0 88.0 88.0 129.0 15:46:55 INFO - 2016-01-04 15:46:55,119 DEBUG : BROWSER_OUTPUT: 15:46:55 INFO - 2016-01-04 15:46:55,119 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.80 Median:110.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 15:46:55 INFO - 2016-01-04 15:46:55,119 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 110.0 110.0 110.0 110.0 109.0 110.0 110.0 110.0 15:46:55 INFO - 2016-01-04 15:46:55,119 DEBUG : BROWSER_OUTPUT: 15:46:55 INFO - 2016-01-04 15:46:55,119 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.84 (0.6%) stddev-sans-first:0.88 15:46:55 INFO - 2016-01-04 15:46:55,119 DEBUG : BROWSER_OUTPUT: Values: 146.0 147.0 146.0 146.0 145.0 146.0 146.0 145.0 145.0 144.0 15:46:55 INFO - 2016-01-04 15:46:55,119 DEBUG : BROWSER_OUTPUT: 15:46:55 INFO - 2016-01-04 15:46:55,119 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 15:46:55 INFO - 2016-01-04 15:46:55,119 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 15:46:55 INFO - 2016-01-04 15:46:55,119 DEBUG : BROWSER_OUTPUT: 15:46:55 INFO - 2016-01-04 15:46:55,119 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:54.80 Median:54.50 stddev:1.55 (2.8%) stddev-sans-first:1.62 15:46:55 INFO - 2016-01-04 15:46:55,119 DEBUG : BROWSER_OUTPUT: Values: 54.0 54.0 59.0 55.0 55.0 54.0 54.0 55.0 54.0 54.0 15:46:55 INFO - 2016-01-04 15:46:55,120 DEBUG : BROWSER_OUTPUT: 15:46:55 INFO - 2016-01-04 15:46:55,120 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:63.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 15:46:55 INFO - 2016-01-04 15:46:55,120 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 63.0 63.0 64.0 63.0 63.0 63.0 64.0 63.0 15:46:55 INFO - 2016-01-04 15:46:55,120 DEBUG : BROWSER_OUTPUT: 15:46:55 INFO - 2016-01-04 15:46:55,120 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.20 Median:78.00 stddev:1.87 (2.4%) stddev-sans-first:0.87 15:46:55 INFO - 2016-01-04 15:46:55,120 DEBUG : BROWSER_OUTPUT: Values: 83.0 76.0 78.0 78.0 79.0 78.0 77.0 77.0 78.0 78.0 15:46:55 INFO - 2016-01-04 15:46:55,120 DEBUG : BROWSER_OUTPUT: 15:46:55 INFO - 2016-01-04 15:46:55,120 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:111.60 Median:112.00 stddev:5.76 (5.1%) stddev-sans-first:5.62 15:46:55 INFO - 2016-01-04 15:46:55,120 DEBUG : BROWSER_OUTPUT: Values: 118.0 111.0 105.0 107.0 109.0 124.0 113.0 107.0 113.0 109.0 15:46:55 INFO - 2016-01-04 15:46:55,120 DEBUG : BROWSER_OUTPUT: 15:46:55 INFO - 2016-01-04 15:46:55,120 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.30 Median:137.00 stddev:5.83 (4.3%) stddev-sans-first:5.97 15:46:55 INFO - 2016-01-04 15:46:55,120 DEBUG : BROWSER_OUTPUT: Values: 133.0 139.0 136.0 136.0 147.0 135.0 138.0 133.0 129.0 147.0 15:46:55 INFO - 2016-01-04 15:46:55,120 DEBUG : BROWSER_OUTPUT: 15:46:55 INFO - 2016-01-04 15:46:55,120 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.90 Median:63.00 stddev:1.20 (1.9%) stddev-sans-first:1.00 15:46:55 INFO - 2016-01-04 15:46:55,120 DEBUG : BROWSER_OUTPUT: Values: 65.0 63.0 61.0 64.0 63.0 62.0 62.0 64.0 63.0 62.0 15:46:55 INFO - 2016-01-04 15:46:55,120 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:46:55 INFO - 2016-01-04 15:46:55,121 DEBUG : BROWSER_OUTPUT: 15:46:55 INFO - 2016-01-04 15:46:55,777 INFO : Browser exited with error code: 0 15:46:55 INFO - 2016-01-04 15:46:55,823 INFO : Completed test kraken (00:00:56) 15:46:55 INFO - 2016-01-04 15:46:55,824 INFO : Starting test v8_7 15:46:55 INFO - 2016-01-04 15:46:55,824 DEBUG : operating with platform_type : linux_ 15:46:55 INFO - 2016-01-04 15:46:55,824 INFO : Initialising browser for v8_7 test... 15:46:55 INFO - 2016-01-04 15:46:55,830 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpH9LdNZ/profile http://localhost:36161/getInfo.html 15:46:59 INFO - 2016-01-04 15:46:59,246 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:46:59 INFO - 2016-01-04 15:46:59,246 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:46:59 INFO - 2016-01-04 15:46:59,246 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:46:59 INFO - 2016-01-04 15:46:59,246 DEBUG : BROWSER_OUTPUT: __metrics 15:46:59 INFO - 2016-01-04 15:46:59,343 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] 15:47:00 INFO - 2016-01-04 15:47:00,637 INFO : Browser initialized. 15:47:00 INFO - 2016-01-04 15:47:00,638 INFO : Running cycle 1/1 for v8_7 test... 15:47:00 INFO - 2016-01-04 15:47:00,638 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpH9LdNZ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:47:12 INFO - 2016-01-04 15:47:12,172 DEBUG : BROWSER_OUTPUT: RSS: Main: 128548864 15:47:12 INFO - 2016-01-04 15:47:12,173 DEBUG : BROWSER_OUTPUT: 15:47:33 INFO - 2016-01-04 15:47:33,636 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36161/tests/v8_7/run.html (next: http://localhost:36161/tests/v8_7/run.html) 15:47:33 INFO - 2016-01-04 15:47:33,886 DEBUG : BROWSER_OUTPUT: RSS: Main: 427888640 15:47:33 INFO - 2016-01-04 15:47:33,887 DEBUG : BROWSER_OUTPUT: 15:47:33 INFO - 2016-01-04 15:47:33,887 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:47:33 INFO - 2016-01-04 15:47:33,887 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:47:33 INFO - 2016-01-04 15:47:33,887 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:47:33 INFO - 2016-01-04 15:47:33,887 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:47:33 INFO - 2016-01-04 15:47:33,887 DEBUG : BROWSER_OUTPUT: |0;Richards;163.5322976287817 15:47:33 INFO - 2016-01-04 15:47:33,887 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;172.32465965879717 15:47:33 INFO - 2016-01-04 15:47:33,888 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.01785714285717 15:47:33 INFO - 2016-01-04 15:47:33,888 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5000 15:47:33 INFO - 2016-01-04 15:47:33,888 DEBUG : BROWSER_OUTPUT: |4;RayTrace;952.3809523809524 15:47:33 INFO - 2016-01-04 15:47:33,888 DEBUG : BROWSER_OUTPUT: |5;Earley;579.7101449275362 15:47:33 INFO - 2016-01-04 15:47:33,888 DEBUG : BROWSER_OUTPUT: |6;Boyer;10447.916666666666 15:47:33 INFO - 2016-01-04 15:47:33,888 DEBUG : BROWSER_OUTPUT: |7;RegExp;23604.6511627907 15:47:33 INFO - 2016-01-04 15:47:33,889 DEBUG : BROWSER_OUTPUT: |8;Splay;540.2485143165857 15:47:33 INFO - 2016-01-04 15:47:33,889 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 15:47:33 INFO - 2016-01-04 15:47:33,889 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:47:33 INFO - 2016-01-04 15:47:33,889 DEBUG : BROWSER_OUTPUT: __startTimestamp1451951253885__endTimestamp 15:47:33 INFO - 2016-01-04 15:47:33,889 DEBUG : BROWSER_OUTPUT: 15:47:33 INFO - 2016-01-04 15:47:33,889 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:47:33 INFO - 2016-01-04 15:47:33,890 DEBUG : BROWSER_OUTPUT: Number of tests: 10 15:47:33 INFO - 2016-01-04 15:47:33,890 DEBUG : BROWSER_OUTPUT: 15:47:33 INFO - 2016-01-04 15:47:33,890 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.53 Median:163.53 stddev:0.00 (0.0%) 15:47:33 INFO - 2016-01-04 15:47:33,890 DEBUG : BROWSER_OUTPUT: Values: 163.5 15:47:33 INFO - 2016-01-04 15:47:33,890 DEBUG : BROWSER_OUTPUT: 15:47:33 INFO - 2016-01-04 15:47:33,891 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:172.32 Median:172.32 stddev:0.00 (0.0%) 15:47:33 INFO - 2016-01-04 15:47:33,891 DEBUG : BROWSER_OUTPUT: Values: 172.3 15:47:33 INFO - 2016-01-04 15:47:33,891 DEBUG : BROWSER_OUTPUT: 15:47:33 INFO - 2016-01-04 15:47:33,892 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.02 Median:279.02 stddev:0.00 (0.0%) 15:47:33 INFO - 2016-01-04 15:47:33,892 DEBUG : BROWSER_OUTPUT: Values: 279.0 15:47:33 INFO - 2016-01-04 15:47:33,892 DEBUG : BROWSER_OUTPUT: 15:47:33 INFO - 2016-01-04 15:47:33,893 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5000.00 Median:5000.00 stddev:0.00 (0.0%) 15:47:33 INFO - 2016-01-04 15:47:33,893 DEBUG : BROWSER_OUTPUT: Values: 5000.0 15:47:33 INFO - 2016-01-04 15:47:33,893 DEBUG : BROWSER_OUTPUT: 15:47:33 INFO - 2016-01-04 15:47:33,893 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:952.38 Median:952.38 stddev:0.00 (0.0%) 15:47:33 INFO - 2016-01-04 15:47:33,893 DEBUG : BROWSER_OUTPUT: Values: 952.4 15:47:33 INFO - 2016-01-04 15:47:33,893 DEBUG : BROWSER_OUTPUT: 15:47:33 INFO - 2016-01-04 15:47:33,894 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:579.71 Median:579.71 stddev:0.00 (0.0%) 15:47:33 INFO - 2016-01-04 15:47:33,894 DEBUG : BROWSER_OUTPUT: Values: 579.7 15:47:33 INFO - 2016-01-04 15:47:33,894 DEBUG : BROWSER_OUTPUT: 15:47:33 INFO - 2016-01-04 15:47:33,894 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10447.92 Median:10447.92 stddev:0.00 (0.0%) 15:47:33 INFO - 2016-01-04 15:47:33,894 DEBUG : BROWSER_OUTPUT: Values: 10447.9 15:47:33 INFO - 2016-01-04 15:47:33,894 DEBUG : BROWSER_OUTPUT: 15:47:33 INFO - 2016-01-04 15:47:33,894 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23604.65 Median:23604.65 stddev:0.00 (0.0%) 15:47:33 INFO - 2016-01-04 15:47:33,894 DEBUG : BROWSER_OUTPUT: Values: 23604.7 15:47:33 INFO - 2016-01-04 15:47:33,894 DEBUG : BROWSER_OUTPUT: 15:47:33 INFO - 2016-01-04 15:47:33,894 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:540.25 Median:540.25 stddev:0.00 (0.0%) 15:47:33 INFO - 2016-01-04 15:47:33,894 DEBUG : BROWSER_OUTPUT: Values: 540.2 15:47:33 INFO - 2016-01-04 15:47:33,894 DEBUG : BROWSER_OUTPUT: 15:47:33 INFO - 2016-01-04 15:47:33,894 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 15:47:33 INFO - 2016-01-04 15:47:33,894 DEBUG : BROWSER_OUTPUT: Values: 4822.1 15:47:33 INFO - 2016-01-04 15:47:33,895 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:47:33 INFO - 2016-01-04 15:47:33,895 DEBUG : BROWSER_OUTPUT: 15:47:34 INFO - 2016-01-04 15:47:34,577 INFO : Browser exited with error code: 0 15:47:34 INFO - 2016-01-04 15:47:34,596 INFO : Completed test v8_7 (00:00:38) 15:47:34 INFO - 2016-01-04 15:47:34,844 INFO : Completed test suite (00:20:06) 15:47:34 INFO - 2016-01-04 15:47:34,845 DEBUG : Working with test: dromaeo_css 15:47:34 INFO - 2016-01-04 15:47:34,845 DEBUG : Generating results file: dromaeo_css 15:47:34 INFO - 2016-01-04 15:47:34,846 DEBUG : Working with test: kraken 15:47:34 INFO - 2016-01-04 15:47:34,846 DEBUG : Generating results file: kraken 15:47:34 INFO - 2016-01-04 15:47:34,846 INFO : javascript benchmark 15:47:34 INFO - 2016-01-04 15:47:34,846 DEBUG : Working with test: v8_7 15:47:34 INFO - 2016-01-04 15:47:34,846 DEBUG : Generating results file: v8_7 15:47:34 INFO - 2016-01-04 15:47:34,847 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:47:34 INFO - 2016-01-04 15:47:34,899 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 15:47:34 INFO - 2016-01-04 15:47:34,900 DEBUG : process_Request line: dromaeo_css 7224.99 graph.html#tests=[[72,53,35]] 15:47:34 INFO - 2016-01-04 15:47:34,900 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:47:34 INFO - 2016-01-04 15:47:34,928 DEBUG : process_Request line: kraken 1452.70 graph.html#tests=[[232,53,35]] 15:47:34 INFO - 2016-01-04 15:47:34,929 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:47:34 INFO - 2016-01-04 15:47:34,957 DEBUG : process_Request line: v8_7 23168.20 graph.html#tests=[[230,53,35]] 15:47:34 INFO - 2016-01-04 15:47:34,959 INFO : javascript benchmark 15:47:35 INFO - 2016-01-04 15:47:34,962 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3216.163797412673, 3284.261041265714, 3302.306515631717, 3310.122817453374, 3338.0820274424536, 5547.861303467415, 5577.135543990683, 5586.078297080987, 5609.074502707053, 5610.607257491977, 5577.553795696344, 5606.299212598426, 5613.270274864272, 5621.7751289948455, 5632.408597097297, 4949.727764972943, 5078.771455284503, 5118.232265160219, 5197.246399272103, 5199.766010529516, 4971.477994810529, 4983.800647974077, 5005.6746311489815, 5094.184930411135, 5114.335136432259, 5121.308623335839, 5178.352705911761, 5181.922271165936, 5186.299849520305, 5213.843584692447, 4894.36373271476, 5110.6422550421485, 5121.257417674454, 5168.689878607295, 5182.3262975813095, 4914.238293064581, 4991.401031876162, 4996.0, 5015.072211640858, 5017.724025178613, 5411.431799661058, 5434.565234781208, 5465.289512363407, 5468.48049435303, 5478.698671573062, 5856.531477481792, 5996.730147143346, 6034.336223015464, 6043.848903777441, 6045.697715114226, 5835.883282334329, 5897.498712609433, 5922.911156332659, 5926.377730338296, 5933.139694744281, 5557.666540007572, 5594.0, 5594.412586678422, 5617.831465056055, 5624.775008999676, 5980.372060933626, 6055.878882422327, 6065.696715164224, 6074.605150665193, 6109.633421994695, 5944.108383742387, 5946.583739138306, 5950.732217050243, 5951.5833891628035, 5956.940430595725, 6022.427869352405, 6024.759009639653, 6030.547708921848, 6031.638101713911, 6035.245594300713, 5739.827805165852, 5804.65172089676, 5872.911906321408, 5894.6757928314355, 5986.670733109634, 5948.881022379528, 6001.06983417571, 6005.159277701126, 6005.759769609254, 6011.849703757353, 4603.562661547147, 4609.815607375667, 4610.216263235258, 4613.261878099488, 4640.419947506562, 4001.679865610744, 4017.5379831319187, 4069.0, 4111.691623128278, 4112.383142528644, 5616.466435688603, 5717.742701578439, 5744.4829965303325, 5747.712614369265, 5750.654960702372, 5789.652620842763, 6025.424278240709, 6052.213212282463, 6052.54605904559, 6081.996470582393, 5452.836414907559, 5474.91787623186, 5496.1755736639825, 5569.66582005081, 5569.888602227933, 5196.740162991835, 5211.478852114834, 5213.504717051874, 5214.9478505214465, 5243.528082472596, 5998.280206375263, 6116.694165291807, 6158.1686472326555, 6163.96918015407, 6166.445019948226, 5944.464998150101, 6034.818955431344, 6042.84892877664, 6043.12374705674, 6058.697065146813, 6160.199174107516, 6164.840970028073, 6180.381961803783, 6197.349278325711, 6201.782937597162, 5699.914501282401, 5769.278840144982, 5802.245708057925, 5931.762729490944, 5986.281646202484, 6063.514918806573, 6069.51443884497, 6069.878602428015, 6070.332263451105, 6082.422169893853, 5917.91123133162, 5929.377415371324, 5931.9406805931385, 5932.4957378621575, 5933.762649493972, 5642.689652069175, 5738.110592858114, 5798.884022319446, 5902.675352855634, 5944.405559444021, 5767.163761254511, 5769.048107062287, 5775.162601422686, 5776.162456443708, 5778.219940308173, 5826.76692932278, 5828.94171058284, 5872.060470324727, 5887.0580707086665, 5890.882182356415, 5606.607537472338, 5658.292713410824, 5734.283214598176, 5747.798827041033, 5781.884362312646], "unit": "ms", "name": "dojo.html", "value": 5509.03496880207}, {"lowerIsBetter": false, "replicates": [573.7418161827179, 607.7751232044144, 614.9600275982061, 617.4072890025576, 624.7063879976413, 34770.130746731316, 42457.15085698288, 42970.570294297046, 42972.14055718884, 43028.78485607571, 46119.30821037687, 46757.76621116898, 46858.0, 46872.531274687244, 46874.76562617182, 8222.671093156296, 8358.582070896462, 9217.677381291656, 9370.344075914689, 10471.3193642413, 10789.0, 10889.346639201673, 10907.072898803612, 10907.890921090806, 10917.508712107934, 10908.563657453693, 10980.450977451135, 10998.780024399508, 11011.61459348923, 11014.44927753613, 10893.128549716004, 10957.780844383147, 10969.670909872717, 10984.231103822736, 10987.725306867313, 10707.946460267727, 10854.131669466502, 10868.45657717111, 10870.347779133277, 10880.510377033053, 43385.0, 43439.34840977388, 43741.12517749627, 43809.0, 43907.78046109781, 3584.480250363709, 3688.0779805048737, 3732.402815549526, 4309.892252693677, 4419.4033805436175, 32720.836395817867, 32810.671893281, 33343.49984750231, 33382.66617333844, 33447.6655233447, 3690.852365905361, 3707.9814600927093, 3770.754900931458, 3817.064819119306, 3860.305145073886, 316618.0, 317628.0, 318139.0, 447515.0, 458712.65149393986, 69193.65403173052, 70753.64623176954, 70770.2922970774, 70882.0, 71000.28999710041, 65901.0, 65905.34094659088, 65921.67039164774, 65944.0, 66101.33898661048, 18132.63734725298, 18484.260629574936, 18492.630147396976, 18494.260229590665, 18496.537586560444, 13972.231527266098, 14107.294635268401, 14117.294135293194, 14118.50585229512, 14120.576382708536, 20430.38708838737, 20735.27426540094, 20754.896225518776, 20755.896220519102, 20770.688439673417, 8098.716544921017, 8153.347732181412, 8218.876716849252, 8244.35112977407, 8324.916750832414, 1083.6044843632017, 1098.319042193845, 1111.4664960818852, 1165.155262434728, 1172.9882701172878, 27570.448591028467, 30841.0, 30973.380532389678, 30983.225419364695, 31888.362232755215, 1152.7694461107644, 1175.2360965372404, 1205.7467931734259, 1230.993845030769, 1246.6945598328468, 13951.302434878215, 15322.157281349633, 15338.233088345538, 15356.257992327268, 15406.614834629, 367618.0, 372377.0, 372609.0, 373366.0, 376029.0, 54307.72846135744, 54539.45460545344, 54545.454545454035, 54630.18054729182, 54659.0, 1347.7102422979106, 1416.3358054286377, 1478.0, 1499.0930487055393, 1522.375825911371, 31822.36355272835, 32100.518492222167, 32180.67819321777, 32431.675683242865, 32599.837000814845, 50835.0, 51445.0, 51452.742736286076, 51595.22607160971, 51603.0, 330985.0, 337835.0, 337977.0, 339092.0, 340969.0, 35952.64047359597, 36211.27577448489, 36322.0, 36634.8168259157, 36936.07659808526, 48333.75833120952, 48478.27282590693, 50627.746861265456, 52549.0, 55879.0, 42123.7893810529, 42134.0, 42934.141317172856, 43563.12873742571, 43824.780876095414, 42199.0, 42572.57427425686, 42617.78691106525, 42835.571644284406, 43112.0, 41935.16129677328, 42047.15905681808, 42460.0, 42463.3630495549, 42495.78752106219, 36093.27813443769, 36837.0, 37266.25467490689, 37790.0, 38166.0, 28607.42785144327, 28865.278368040967, 28957.56563651589, 29205.7079429203, 29306.267343316587], "unit": "ms", "name": "ext.html", "value": 21000.379096993867}, {"lowerIsBetter": false, "replicates": [376.04484609092907, 379.9810009499526, 380.1901948848952, 381.3973921204497, 382.3022983055924, 15463.768043479356, 17017.319307227695, 17888.284468621256, 18965.62068758626, 19006.429807105793, 18764.0, 19062.237510499563, 19248.807511924875, 19343.70984435231, 19417.514562135955, 5879.8530036749, 6079.817605471821, 6083.209182806251, 6102.542309326796, 6102.633841969474, 4474.6196573291145, 4614.723116613014, 4615.861524154264, 4617.053504031683, 5965.164876917213, 6409.903851442232, 6454.419102280794, 6609.801705948805, 6614.8677026459445, 6626.801195964104, 6492.513061520382, 6635.203775546917, 6638.336166383371, 6639.668016599151, 6641.535092543524, 5876.97061514694, 5909.704514774244, 5927.851803704899, 5930.199423077873, 6001.06983417571, 23723.406914827094, 24239.272821815375, 24561.14036008749, 24730.0, 24733.381665458328, 2489.639002344668, 2639.5248855206055, 2654.32314759737, 2679.397135644484, 2704.2022603332066, 14967.550973470814, 15018.624534386618, 15151.242437878062, 15368.692626147526, 15603.765943510856, 1980.8217260446486, 1987.2547794577033, 1995.800419958007, 2006.7290915726337, 2051.8153366196966, 60869.391306086814, 69072.65463672699, 70650.0, 71748.28251717468, 72247.63876180637, 7672.884906726404, 7696.461247712718, 7932.484388514844, 7936.365090792723, 7980.561069141253, 9445.622175112918, 9462.148406643435, 9472.431654100776, 9503.714888553355, 9574.569144388519, 6306.49548036156, 6347.777827776005, 6348.174737284124, 6350.047492876106, 6415.615063096229, 6536.607803531803, 6564.081028655992, 6712.630805305755, 6754.493412994045, 6945.791626251221, 3688.0779805048737, 3716.832742526593, 3718.2563487302646, 3726.31063253299, 3747.3067482515276, 1262.8547717012477, 1276.6361586947676, 1282.4677758730022, 1290.4064130500076, 1293.8770816772392, 518.4659800405589, 519.4026869100564, 535.333509780326, 540.3326891289255, 560.2520634952302, 11521.539138434537, 13017.47930082805, 13088.803667944989, 13419.530316438877, 13461.326933653474, 545.258448510026, 549.1241469855574, 557.1447827584719, 559.2478116932718, 575.8905807896487, 7164.641767911584, 7289.854202915912, 7330.230325815819, 7468.5145465544565, 7476.813079672943, 595185.0, 599837.0, 606522.0, 609340.62637494, 653388.0, 80028.59985700034, 81032.59483702543, 81707.0, 82260.0, 82653.0, 621.869407424446, 626.4393367935672, 627.1157667688537, 627.8869803435425, 629.4429429954523, 11955.880441195477, 12180.939095304468, 12258.14193006481, 12331.260124392566, 12355.444005019615, 34951.0, 35460.64539354573, 35671.28657426785, 36429.089272767334, 36549.26901462009, 50121.74939125281, 51199.2320115206, 51426.48573514217, 51504.74247628738, 52072.73963630309, 7364.3372096511575, 7729.1884352142215, 7738.7678369649, 7744.457887948015, 7746.883796742941, 13438.0, 13627.795583066063, 13711.588652340446, 13859.514916977858, 14035.859641403455, 12002.159848810848, 12020.879791201976, 12107.939460302641, 12255.325957072446, 12280.754384912074, 9094.772630684021, 11720.941395292968, 11938.403079846146, 12039.4582243801, 12155.0, 9934.913668129773, 11116.944415277872, 11638.185327027366, 11708.824367634323, 11983.580574679845, 7704.884426733715, 7705.807354816175, 7722.768316950501, 7727.0, 7735.6518956648215, 11856.70358241051, 11989.82015269789, 12016.879831201577, 12219.633410997685, 12269.0], "unit": "ms", "name": "jquery.html", "value": 8139.5983454467505}, {"lowerIsBetter": false, "replicates": [1585.1202582566684, 1599.7360435528137, 1658.1294820219387, 1858.3310008396975, 1924.8652594318385, 6604.86790264195, 6732.495062870286, 6736.360045795642, 6959.965200174005, 7098.041764361809, 7594.772156835304, 7750.263724946135, 7796.727114550992, 7811.375089992787, 7815.882761758578, 2358.2453614843257, 2388.3909603051284, 2392.162743039931, 2393.4854006388623, 2393.8563686178886, 2047.488127968008, 2094.8428867834973, 2353.305774796431, 2382.2257766225957, 2434.330559096245, 2362.350353652742, 2366.7869891709743, 2378.7264464586533, 2378.7740164684415, 2380.0, 2350.682657841195, 2390.008146619151, 2390.509945461185, 2390.641403789437, 2391.1272385579314, 2380.4048987753063, 2403.807695384365, 2430.6961629796274, 2431.0518897629763, 2433.537627850703, 8085.0, 8143.877841832377, 8174.305184059363, 8183.6726530938695, 8236.670533178725, 4869.050535145648, 4926.0, 4928.457869634337, 4929.65492415531, 4932.605391568666, 3113.828739419331, 3165.1859213004777, 3204.7916885402606, 3214.164317277501, 3236.336551007038, 1077.3624091381134, 1078.7033565769398, 2038.7349644546262, 2115.841311901598, 2167.3931299236237, 11179.720506987309, 11272.718182045515, 11309.264897781617, 11315.434228288635, 11325.093992480664, 4382.0, 4415.51429342772, 4442.9777851111185, 4488.079943611617, 4490.2142125127975, 8793.824123517621, 8802.207801297915, 8812.735617931472, 8816.559172041501, 8819.603117859711, 4967.105920934267, 4979.90040199194, 4985.675931064469, 4987.426445958761, 4993.7503124843615, 4607.585317321457, 4616.815327386867, 4629.606483448944, 4630.86107416778, 4631.95368046322, 3340.1983523954077, 3610.2598967211657, 3624.619414961391, 3674.1365779041903, 3690.538682664667, 1057.841323801436, 1059.8039362717768, 1168.0830548019765, 1168.3865970365625, 1177.7879981603223, 1186.2882270637722, 1189.5182451107141, 1234.0127897681818, 1467.4423718986716, 1484.6140003599141, 7735.729249476241, 7811.80470488231, 7936.404769642185, 7949.880751788728, 7950.880736788953, 763.7097902796902, 787.8384931089113, 799.4124318625805, 802.4904185842032, 897.1297841094129, 6221.5333849961435, 6319.399657032574, 6321.431071203614, 6332.651704156316, 6335.493160547145, 8832.955835220911, 8852.8672069919, 8881.600327985256, 8882.022977472472, 8885.42244754102, 9459.810803783887, 9578.473183974947, 9650.806983860284, 9847.704568862802, 9853.852192217122, 7542.47202695817, 7603.543787372665, 7610.733624323122, 7613.733519326797, 7616.352610028099, 7448.292412220831, 7520.586367749826, 7706.306432421109, 7792.727254546064, 7795.142534321333, 8585.957070214608, 8750.781230469289, 8779.956100219459, 8823.955880220557, 8824.647014119364, 8768.079351668239, 8914.821703566022, 8949.731508054769, 8963.731088067369, 9000.099990000948, 7444.069491313586, 7501.587412692136, 7524.17234104259, 7539.849203016019, 7541.698332066656, 6782.423494003065, 7177.031100801325, 7761.223877612194, 7952.880706789287, 8093.635786389745, 7320.341169294789, 7395.260473952561, 7413.66638501258, 7415.405687777154, 7554.206808285271, 7330.450216233698, 7392.519486233377, 7400.074990626172, 7471.364933980455, 7486.663100160397, 7513.774586762406, 7572.167061623327, 7612.7716168515035, 7613.809654758676, 7628.0, 6716.093327400738, 6758.256591774999, 6758.324167583202, 6768.56004359715, 6780.118584583973, 6833.624150671761, 6843.110395648534, 6845.623490708059, 6846.554973926679, 6846.554973926679], "unit": "ms", "name": "mootools.html", "value": 4681.015404324425}, {"lowerIsBetter": false, "replicates": [356.5365025466894, 358.9407747721626, 361.98914032579034, 365.4426998826787, 366.8880991297654, 5736.856578585537, 5809.564282678795, 5842.357340692526, 5882.617629854062, 5888.381719919405, 5690.57320700947, 5782.681952492611, 5796.5652576056855, 5805.158252053449, 5805.680687562182, 4561.543845615445, 4564.315352697089, 4574.268117101265, 4576.267797152456, 4578.084383123389, 4368.584984426474, 4432.113577284556, 4503.211937910869, 4573.656975726817, 4577.038821847416, 4514.0746147039945, 4529.433820772403, 4530.886727831814, 4533.818647254106, 4537.160625284333, 4534.6372290216705, 4535.433070866142, 4542.818287268506, 4543.341215523764, 4548.5906268436, 4023.4568333274924, 4032.5362583303004, 4036.1322315702264, 4039.0508230565792, 4042.514898212204, 5491.807786727485, 5542.2795036644975, 5552.250446189793, 5586.776528938837, 5610.354809196954, 5226.555742761831, 5232.686038837682, 5234.110201265794, 5234.738263086831, 5241.109011468059, 5895.056790913433, 6100.542459315525, 6172.22847144107, 6174.876502469971, 6183.072539119124, 4175.164967006581, 4310.3103503439615, 4360.672949528798, 4361.084172323815, 4368.388425620416, 6198.969005154945, 6233.1273621693, 6241.469475094623, 6244.12582238487, 6260.123582698426, 4445.377647129404, 4466.754328511898, 4468.173387923244, 4470.307102399133, 4473.3066374711325, 4444.11117776446, 4474.172278128557, 4506.143832671847, 4506.81972721094, 4507.81968721254, 5151.5363617274625, 5160.509751573594, 5161.483851614808, 5162.354705661793, 5166.638335316567, 6104.816855494342, 6106.938930610726, 6107.144999700045, 6107.877842443127, 6111.052786818051, 1630.9347626094823, 1658.4444211189386, 1660.2362913059885, 1660.7757952676475, 1662.1938359895441, 1117.636768050387, 1118.5190368141614, 1122.315387613555, 1123.393367581497, 1144.0104309771987, 6102.481289090389, 6104.175936248638, 6104.755809767648, 6110.633361998294, 6110.7861224856915, 6377.7767778127545, 6379.936200638027, 6424.614523128534, 6435.710393032325, 6449.193850768654, 5955.166276721263, 6081.361457046946, 6085.330613632497, 6086.452219300196, 6090.725917333731, 3503.4394496880377, 3562.109472631842, 3563.0, 3566.964330356663, 3567.161716996504, 6522.282548919615, 6564.212294524687, 6569.408753212234, 6572.07990881277, 6618.040384144272, 6600.141981542465, 6601.471882249409, 6605.57063790852, 6608.867822643617, 6610.140681711347, 6491.67541622917, 6536.248331441945, 6623.701933412912, 6624.536282460278, 6626.370494802986, 6209.068639704081, 6303.527235457267, 6417.807465776034, 6423.389777971272, 6427.132337134427, 6258.311585725475, 6353.396427339396, 6363.331850155667, 6373.74505019794, 6375.521835862236, 6523.478121750154, 6528.020796880506, 6568.507361947969, 6595.0, 6599.505037122139, 4442.48911375196, 4476.08240310742, 4503.954960450485, 4506.7070640408265, 4509.2559727644575, 6399.968000159969, 6403.0, 6403.3276505966205, 6406.23125224976, 6406.391392817675, 6227.501799856092, 6230.283517395557, 6236.438720515175, 6245.28179259391, 6252.781152659635, 6221.09794079865, 6228.22147231596, 6229.750809967551, 6238.781642642486, 6250.906236406367, 6195.597286176384, 6199.132121502903, 6201.224846894138, 6203.2245969253845, 6204.038374052029, 6316.336784637547, 6322.3045464997895, 6324.905126423199, 6329.0, 6332.0818481320875, 6083.7870674526175, 6164.321924588381, 6165.352637972948, 6179.443850053517, 6184.257889053342], "unit": "ms", "name": "prototype.html", "value": 4732.538949111181}, {"lowerIsBetter": false, "replicates": [764.9273319034692, 882.7925437522183, 956.5217391304348, 960.6205548808225, 963.1331801378763, 15289.464868729598, 15595.688086238268, 15643.217839108042, 15658.608534786636, 15665.92167039166, 14185.929070354634, 14254.216018119, 14267.0726402784, 14269.571912842606, 14271.215083170448, 766.84279722657, 776.596169991604, 922.8707980882682, 930.9906900931005, 941.7975135345899, 933.4259430450292, 938.1462868789403, 939.9671011514598, 941.7174847545743, 942.189716843514, 816.4611356504709, 987.7283746969568, 990.5195979949755, 990.5938565188275, 991.5488452754016, 813.2436833744615, 964.3876138651972, 972.3290929258789, 972.7713987212999, 975.3562648651856, 723.5803234124248, 723.6237156678498, 724.1455083002055, 725.6263024542365, 728.7522242437543, 14212.147271163763, 14337.28313584327, 14343.856561434357, 14345.928270358685, 14353.78469322961, 13581.253031083286, 14375.496857610038, 14395.928020359832, 14456.710865782625, 14531.636709082251, 11033.613823516138, 11389.886101138965, 11456.0, 11587.884121158764, 11604.477798499087, 3477.2697733476, 3478.7738796978115, 3522.5420695309704, 3536.3634545781747, 3570.357335679577, 50232.49767502315, 50426.24360634593, 50598.24102638463, 50852.745736271085, 50976.49023509792, 8270.669173233004, 8328.042275138316, 8358.247757701714, 8378.24595786382, 8393.076761556224, 13771.311434428238, 13799.2410417428, 13869.583912482642, 14192.0, 14263.0, 7384.040074790243, 7448.180700122982, 7533.698652053966, 7556.848863022708, 7822.4524283299625, 4740.974566959035, 4793.18515852306, 4826.734529600905, 4861.611071114373, 4861.635377346643, 2902.1003488918504, 2913.1115009922178, 2918.5184444566407, 2923.4591600553968, 2928.4143171365813, 435.9324304732708, 448.49320268097495, 450.0301849514302, 453.75270477589794, 456.4818930513891, 718.4611541343992, 757.1217387830201, 761.5012167030604, 769.6575024114327, 777.5762209595783, 9564.0, 9943.801123977624, 9991.200703943667, 10035.347702399467, 10090.44502552352, 648.7632014314836, 744.5383862005591, 746.3653303837463, 756.587659725451, 760.9429292803062, 4575.931361029588, 4588.197065513522, 4616.584507394351, 4674.57928786404, 4843.491433399513, 65535.34464655388, 66964.66517667478, 67088.0, 67348.6632566834, 67732.66133669399, 61520.0, 61749.0, 61924.69037654783, 62133.0, 62166.37833621696, 54826.0, 54973.45026549683, 55240.44759552353, 55268.894622107335, 56650.43349566534, 9574.090461406155, 9789.559469824018, 9854.237540662072, 10069.69790906274, 10075.596976120873, 39097.2180556393, 40236.59763402329, 41286.1742765137, 41411.7929410351, 41527.792361038, 52330.47669523256, 52612.0, 53357.0, 54134.72932635312, 54146.72926635342, 8823.161799629024, 9101.954490227507, 9268.675596354095, 9271.165595096474, 9311.0, 13797.379117939918, 13824.447022119404, 13888.236147012012, 14010.929945350208, 14073.225972571607, 12016.939915300367, 12132.575359862363, 12169.939150304192, 12333.93833030829, 12690.492380305053, 12993.155444896052, 13135.474581017035, 13159.802602961156, 13217.339133043502, 13280.0, 12922.870771292166, 13039.152455090769, 13106.868931310564, 13271.402786874422, 13339.866601333862, 11228.157888158585, 11336.489857956576, 11521.88478115208, 11568.479418426023, 11692.123090768056, 10507.684769456928, 10595.258331916692, 10602.416867072385, 10637.255392122477, 10641.0], "unit": "ms", "name": "yui.html", "value": 6817.75536117087}], "name": "dromaeo_css", "value": 7224.9851808874055}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 105.0, 108.0, 111.0, 110.0, 100.0, 109.0, 106.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 107.7}, {"lowerIsBetter": true, "replicates": [145.0, 120.0, 145.0, 150.0, 122.0, 149.0, 141.0, 121.0, 159.0, 120.0], "unit": "ms", "name": "audio-beat-detection", "value": 137.2}, {"lowerIsBetter": true, "replicates": [162.0, 160.0, 159.0, 160.0, 159.0, 159.0, 159.0, 164.0, 161.0, 158.0], "unit": "ms", "name": "audio-dft", "value": 160.1}, {"lowerIsBetter": true, "replicates": [70.0, 72.0, 70.0, 156.0, 71.0, 70.0, 70.0, 70.0, 145.0, 76.0], "unit": "ms", "name": "audio-fft", "value": 87.0}, {"lowerIsBetter": true, "replicates": [89.0, 90.0, 135.0, 89.0, 89.0, 90.0, 134.0, 88.0, 88.0, 129.0], "unit": "ms", "name": "audio-oscillator", "value": 102.1}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 110.0, 110.0, 110.0, 110.0, 109.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.8}, {"lowerIsBetter": true, "replicates": [146.0, 147.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 144.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [54.0, 54.0, 59.0, 55.0, 55.0, 54.0, 54.0, 55.0, 54.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 54.8}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 63.0, 63.0, 64.0, 63.0, 63.0, 63.0, 64.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.2}, {"lowerIsBetter": true, "replicates": [83.0, 76.0, 78.0, 78.0, 79.0, 78.0, 77.0, 77.0, 78.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.2}, {"lowerIsBetter": true, "replicates": [118.0, 111.0, 105.0, 107.0, 109.0, 124.0, 113.0, 107.0, 113.0, 109.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 111.6}, {"lowerIsBetter": true, "replicates": [133.0, 139.0, 136.0, 136.0, 147.0, 135.0, 138.0, 133.0, 129.0, 147.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 137.3}, {"lowerIsBetter": true, "replicates": [65.0, 63.0, 61.0, 64.0, 63.0, 62.0, 62.0, 64.0, 63.0, 62.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 62.9}], "name": "kraken", "value": 1452.7}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.5322976287817], "unit": "score", "name": "Richards", "value": 215.87172999999993}, {"lowerIsBetter": false, "replicates": [172.32465965879717], "unit": "score", "name": "DeltaBlue", "value": 383.68275399999993}, {"lowerIsBetter": false, "replicates": [279.01785714285717], "unit": "score", "name": "Encrypt", "value": 953.9927040000001}, {"lowerIsBetter": false, "replicates": [5000.0], "unit": "score", "name": "Decrypt", "value": 53.23619999999997}, {"lowerIsBetter": false, "replicates": [952.3809523809524], "unit": "score", "name": "RayTrace", "value": 776.9884500000003}, {"lowerIsBetter": false, "replicates": [579.7101449275362], "unit": "score", "name": "Earley", "value": 1149.6486749999997}, {"lowerIsBetter": false, "replicates": [10447.916666666666], "unit": "score", "name": "Boyer", "value": 63.78908075772688}, {"lowerIsBetter": false, "replicates": [23604.6511627907], "unit": "score", "name": "RegExp", "value": 38.593453201970455}, {"lowerIsBetter": false, "replicates": [540.2485143165857], "unit": "score", "name": "Splay", "value": 150.83984100000006}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23168.19534261519}]} 15:47:35 INFO - RETURN: dromaeo_css: 7224.99 15:47:35 INFO - RETURN: kraken: 1452.70 15:47:35 INFO - RETURN: v8_7: 23168.20 15:47:35 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23168.20"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1452.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7224.99"}}} 15:47:35 INFO - Return code: 0 15:47:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 15:47:35 INFO - # TBPL SUCCESS # 15:47:35 INFO - Running post-action listener: _resource_record_post_action 15:47:35 INFO - Running post-run listener: _resource_record_post_run 15:47:36 INFO - Total resource usage - Wall time: 1222s; CPU: 12.0%; Read bytes: 8196096; Write bytes: 407715840; Read time: 1764; Write time: 312760 15:47:36 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 15806464; Read time: 0; Write time: 3772 15:47:36 INFO - run-tests - Wall time: 1208s; CPU: 12.0%; Read bytes: 6438912; Write bytes: 391909376; Read time: 1728; Write time: 308988 15:47:36 INFO - Running post-run listener: _upload_blobber_files 15:47:36 INFO - Blob upload gear active. 15:47:36 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 15:47:36 INFO - Copying logs to upload dir... 15:47:36 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1265.531606 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-01-04 15:47:36.273864) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-04 15:47:36.277918) ========= 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=03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009970 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451941865/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 15:47:36.329798) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 15:47:36.330165) ========= 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=03627c79f6bb3d1578c4a402000001ba-1451949292.461751-1446647204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004455 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 15:47:36.380632) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-04 15:47:36.380998) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-04 15:47:36.381355) ========= ========= Total master_lag: 0.21 =========