builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-046 starttime: 1451906936.85 results: success (0) buildid: 20160104010306 builduid: 73eb012f495849dcbb64dcab8b6e7df9 revision: 692ee3b8ba0a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:56.855338) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:56.855838) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:56.856137) ========= 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=fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006206 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:56.879104) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:56.879420) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:56.892770) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:56.893107) ========= 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=fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004601 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:56.942796) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:56.943100) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:56.943477) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:56.943787) ========= 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=fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-04 03:28:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.6M=0s 2016-01-04 03:28:58 (43.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.166072 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:57.122138) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:57.122468) ========= 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=fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022540 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:57.172483) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-04 03:28:57.172883) ========= 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=fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-04 03:28:58,190 Setting DEBUG logging. 2016-01-04 03:28:58,190 attempt 1/10 2016-01-04 03:28:58,190 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 03:28:59,157 unpacking tar archive at: mozilla-beta-692ee3b8ba0a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.232090 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-04 03:28:58.418796) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:58.419318) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:58.429610) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:58.429996) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-04 03:28:58.430438) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-01-04 03:28:58.430722) ========= /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=fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:28:59 INFO - MultiFileLogger online at 20160104 03:28:59 in /builds/slave/test-pgo 03:28:59 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 03:28:59 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 03:28:59 INFO - {'append_to_log': False, 03:28:59 INFO - 'base_work_dir': '/builds/slave/test-pgo', 03:28:59 INFO - 'blob_upload_branch': 'Mozilla-Beta', 03:28:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 03:28:59 INFO - 'branch': 'Mozilla-Beta', 03:28:59 INFO - 'buildbot_json_path': 'buildprops.json', 03:28:59 INFO - 'config_files': ('talos/linux_config.py',), 03:28:59 INFO - 'default_actions': ('clobber', 03:28:59 INFO - 'read-buildbot-config', 03:28:59 INFO - 'download-and-extract', 03:28:59 INFO - 'populate-webroot', 03:28:59 INFO - 'create-virtualenv', 03:28:59 INFO - 'install', 03:28:59 INFO - 'run-tests'), 03:28:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:28:59 INFO - 'download_minidump_stackwalk': True, 03:28:59 INFO - 'download_symbols': 'ondemand', 03:28:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:28:59 INFO - 'tooltool.py': '/tools/tooltool.py', 03:28:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:28:59 INFO - '/tools/misc-python/virtualenv.py')}, 03:28:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:28:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:28:59 INFO - 'installer_path': 'installer.exe', 03:28:59 INFO - 'log_level': 'info', 03:28:59 INFO - 'log_name': 'talos', 03:28:59 INFO - 'log_to_console': True, 03:28:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:28:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:28:59 INFO - 'opt_config_files': (), 03:28:59 INFO - 'pip_index': False, 03:28:59 INFO - 'sps_profile': False, 03:28:59 INFO - 'sps_profile_interval': 0, 03:28:59 INFO - 'suite': 'dromaeojs', 03:28:59 INFO - 'system_bits': '32', 03:28:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:28:59 INFO - 'title': 'talos-linux64-ix-046', 03:28:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:28:59 INFO - 'use_talos_json': True, 03:28:59 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 03:28:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:28:59 INFO - 'work_dir': 'build'} 03:28:59 INFO - ##### 03:28:59 INFO - ##### Running clobber step. 03:28:59 INFO - ##### 03:28:59 INFO - Running pre-action listener: _resource_record_pre_action 03:28:59 INFO - Running main action method: clobber 03:28:59 INFO - rmtree: /builds/slave/test-pgo/build 03:28:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 03:28:59 INFO - Running post-action listener: _resource_record_post_action 03:28:59 INFO - ##### 03:28:59 INFO - ##### Running read-buildbot-config step. 03:28:59 INFO - ##### 03:28:59 INFO - Running pre-action listener: _resource_record_pre_action 03:28:59 INFO - Running main action method: read_buildbot_config 03:28:59 INFO - Using buildbot properties: 03:28:59 INFO - { 03:28:59 INFO - "properties": { 03:28:59 INFO - "buildnumber": 59, 03:28:59 INFO - "product": "firefox", 03:28:59 INFO - "script_repo_revision": "production", 03:28:59 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 03:28:59 INFO - "repository": "", 03:28:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 03:28:59 INFO - "buildid": "20160104010306", 03:28:59 INFO - "slavename": "talos-linux64-ix-046", 03:28:59 INFO - "pgo_build": "True", 03:28:59 INFO - "basedir": "/builds/slave/test-pgo", 03:28:59 INFO - "project": "", 03:28:59 INFO - "platform": "ubuntu64_hw", 03:28:59 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:28:59 INFO - "slavebuilddir": "test", 03:28:59 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 03:28:59 INFO - "branch": "mozilla-beta", 03:28:59 INFO - "repo_path": "releases/mozilla-beta", 03:28:59 INFO - "stage_platform": "linux64-pgo", 03:28:59 INFO - "builduid": "73eb012f495849dcbb64dcab8b6e7df9", 03:28:59 INFO - "revision": "692ee3b8ba0a" 03:28:59 INFO - }, 03:28:59 INFO - "sourcestamp": { 03:28:59 INFO - "repository": "", 03:28:59 INFO - "hasPatch": false, 03:28:59 INFO - "project": "", 03:28:59 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 03:28:59 INFO - "changes": [ 03:28:59 INFO - { 03:28:59 INFO - "category": null, 03:28:59 INFO - "files": [ 03:28:59 INFO - { 03:28:59 INFO - "url": null, 03:28:59 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/firefox-44.0.en-US.linux-x86_64.tar.bz2" 03:28:59 INFO - } 03:28:59 INFO - ], 03:28:59 INFO - "repository": "", 03:28:59 INFO - "rev": "692ee3b8ba0a", 03:28:59 INFO - "who": "sendchange", 03:28:59 INFO - "when": 1451906670, 03:28:59 INFO - "number": 7411749, 03:28:59 INFO - "comments": "Bug 1234936 - part 2: always focus tabs when they show an onbeforeunload dialog, r=billm, a=ritu", 03:28:59 INFO - "project": "", 03:28:59 INFO - "at": "Mon 04 Jan 2016 03:24:30", 03:28:59 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 03:28:59 INFO - "revlink": "", 03:28:59 INFO - "properties": [ 03:28:59 INFO - [ 03:28:59 INFO - "buildid", 03:28:59 INFO - "20160104010306", 03:28:59 INFO - "Change" 03:29:00 INFO - ], 03:29:00 INFO - [ 03:29:00 INFO - "builduid", 03:29:00 INFO - "73eb012f495849dcbb64dcab8b6e7df9", 03:29:00 INFO - "Change" 03:29:00 INFO - ], 03:29:00 INFO - [ 03:29:00 INFO - "pgo_build", 03:29:00 INFO - "True", 03:29:00 INFO - "Change" 03:29:00 INFO - ] 03:29:00 INFO - ], 03:29:00 INFO - "revision": "692ee3b8ba0a" 03:29:00 INFO - } 03:29:00 INFO - ], 03:29:00 INFO - "revision": "692ee3b8ba0a" 03:29:00 INFO - } 03:29:00 INFO - } 03:29:00 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/firefox-44.0.en-US.linux-x86_64.tar.bz2. 03:29:00 INFO - Running post-action listener: _resource_record_post_action 03:29:00 INFO - ##### 03:29:00 INFO - ##### Running download-and-extract step. 03:29:00 INFO - ##### 03:29:00 INFO - Running pre-action listener: _resource_record_pre_action 03:29:00 INFO - Running main action method: download_and_extract 03:29:00 INFO - mkdir: /builds/slave/test-pgo/build/tests 03:29: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')]} 03:29:00 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/test_packages.json 03:29:00 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 03:29:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 03:29:00 INFO - Downloaded 1270 bytes. 03:29:00 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 03:29:00 INFO - Using the following test package requirements: 03:29:00 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 03:29:00 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 03:29:00 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 03:29:00 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 03:29:00 INFO - u'jsshell-linux-x86_64.zip'], 03:29:00 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 03:29:00 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 03:29:00 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 03:29:00 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 03:29:00 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 03:29:00 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 03:29:00 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 03:29:00 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 03:29:00 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 03:29:00 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 03:29:00 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 03:29:00 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 03:29:00 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:29: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')]} 03:29:00 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/firefox-44.0.en-US.linux-x86_64.common.tests.zip 03:29:00 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/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 03:29:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/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 03:29:00 INFO - Downloaded 22572237 bytes. 03:29:00 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 03:29:00 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 03:29:00 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 03:29:01 INFO - Return code: 0 03:29:01 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 03:29:01 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')]} 03:29:01 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/firefox-44.0.en-US.linux-x86_64.common.tests.zip 03:29:01 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/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 03:29:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/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 03:29:01 INFO - Downloaded 22572237 bytes. 03:29:01 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 03:29:01 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 03:29:01 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 03:29:03 INFO - Return code: 0 03:29: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')]} 03:29:03 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 03:29:03 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/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 03:29:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/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 03:29:03 INFO - Downloaded 11131970 bytes. 03:29:03 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 03:29:03 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 03:29:03 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 03:29:04 INFO - Return code: 0 03:29:04 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')]} 03:29:04 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/firefox-44.0.en-US.linux-x86_64.tar.bz2 03:29:04 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/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 03:29:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/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 03:29:05 INFO - Downloaded 56621986 bytes. 03:29:05 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/firefox-44.0.en-US.linux-x86_64.tar.bz2 03:29:05 INFO - mkdir: /builds/slave/test-pgo/properties 03:29:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 03:29:05 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 03:29:05 INFO - Contents: 03:29:05 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/firefox-44.0.en-US.linux-x86_64.tar.bz2 03:29:05 INFO - Running post-action listener: _resource_record_post_action 03:29:05 INFO - Running post-action listener: set_extra_try_arguments 03:29:05 INFO - ##### 03:29:05 INFO - ##### Running populate-webroot step. 03:29:05 INFO - ##### 03:29:05 INFO - Running pre-action listener: _resource_record_pre_action 03:29:05 INFO - Running main action method: populate_webroot 03:29:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:29:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:29:05 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 03:29:05 INFO - '../fennec_ids.txt'], 03:29:05 INFO - 'tests': ['tcheck2']}, 03:29:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:29:05 INFO - '--tppagecycles', 03:29:05 INFO - '7'], 03:29:05 INFO - 'tests': ['tsvgm']}}, 03:29:05 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:29:05 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:29:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:29:05 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:29:05 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:29:05 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:29:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:29:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:05 INFO - 'talos_options': ['--e10s'], 03:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:29:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:05 INFO - 'tests': ['damp', 'tps']}, 03:29:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:05 INFO - 'talos_options': ['--e10s'], 03:29:05 INFO - 'tests': ['damp', 'tps']}, 03:29:05 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:29:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:29:05 INFO - 'other': {'tests': ['a11yr', 03:29:05 INFO - 'ts_paint', 03:29:05 INFO - 'tpaint', 03:29:05 INFO - 'sessionrestore', 03:29:05 INFO - 'sessionrestore_no_auto_restore']}, 03:29:05 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:29:05 INFO - 'tests': ['a11yr', 03:29:05 INFO - 'ts_paint', 03:29:05 INFO - 'tpaint', 03:29:05 INFO - 'sessionrestore', 03:29:05 INFO - 'sessionrestore_no_auto_restore']}, 03:29:05 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:29:05 INFO - 'tests': ['a11yr', 03:29:05 INFO - 'ts_paint', 03:29:05 INFO - 'tpaint', 03:29:05 INFO - 'sessionrestore', 03:29:05 INFO - 'sessionrestore_no_auto_restore']}, 03:29:05 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:29:05 INFO - 'tests': ['a11yr', 03:29:05 INFO - 'ts_paint', 03:29:05 INFO - 'tpaint', 03:29:05 INFO - 'sessionrestore', 03:29:05 INFO - 'sessionrestore_no_auto_restore']}, 03:29:05 INFO - 'other_l64': {'tests': ['a11yr', 03:29:05 INFO - 'ts_paint', 03:29:05 INFO - 'tpaint', 03:29:05 INFO - 'sessionrestore', 03:29:05 INFO - 'sessionrestore_no_auto_restore']}, 03:29:05 INFO - 'other_nol64': {'tests': ['a11yr', 03:29:05 INFO - 'ts_paint', 03:29:05 INFO - 'tpaint', 03:29:05 INFO - 'sessionrestore', 03:29:05 INFO - 'sessionrestore_no_auto_restore']}, 03:29:05 INFO - 'svgr': {'tests': ['tsvgx', 03:29:05 INFO - 'tsvgr_opacity', 03:29:05 INFO - 'tart', 03:29:05 INFO - 'tscrollx', 03:29:05 INFO - 'cart']}, 03:29:05 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:29:05 INFO - 'tests': ['tsvgx', 03:29:05 INFO - 'tsvgr_opacity', 03:29:05 INFO - 'tart', 03:29:05 INFO - 'tscrollx', 03:29:05 INFO - 'cart']}, 03:29:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:05 INFO - 'tests': ['tp5o']}, 03:29:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:05 INFO - 'talos_options': ['--e10s'], 03:29:05 INFO - 'tests': ['tp5o']}, 03:29:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:05 INFO - 'talos_options': ['--xperf_path', 03:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:29:05 INFO - 'tests': ['tp5n']}, 03:29:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:05 INFO - 'talos_options': ['--e10s', 03:29:05 INFO - '--xperf_path', 03:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:29:05 INFO - 'tests': ['tp5n']}}, 03:29:05 INFO - 'talos.zip': {'path': '', 03:29:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:29:05 INFO - Running post-action listener: _resource_record_post_action 03:29:05 INFO - ##### 03:29:05 INFO - ##### Running create-virtualenv step. 03:29:05 INFO - ##### 03:29:05 INFO - Running pre-action listener: _resource_record_pre_action 03:29:05 INFO - Running main action method: create_virtualenv 03:29:05 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 03:29:05 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 03:29:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 03:29:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:29:05 INFO - Using real prefix '/usr' 03:29:05 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 03:29:05 INFO - Installing distribute.............................................................................................................................................................................................done. 03:29:07 INFO - Installing pip.................done. 03:29:07 INFO - Return code: 0 03:29:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 03:29: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')]} 03:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29: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')]} 03:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9eeb4ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xebd350>, '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 0xebe250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebe6e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', '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 03:29: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', 'pip>=1.5'] in /builds/slave/test-pgo/build 03:29: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 pip>=1.5 03:29:07 INFO - Using env: {'DISPLAY': ':0', 03:29:07 INFO - 'HOME': '/home/cltbld', 03:29:07 INFO - 'LANG': 'en_US.UTF-8', 03:29:07 INFO - 'LANGUAGE': 'en_US:en', 03:29:07 INFO - 'LOGNAME': 'cltbld', 03:29:07 INFO - 'MAIL': '/var/mail/cltbld', 03:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:07 INFO - 'MOZ_NO_REMOTE': '1', 03:29:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:07 INFO - 'NO_EM_RESTART': '1', 03:29:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:29:07 INFO - 'PWD': '/builds/slave/test-pgo', 03:29:07 INFO - 'SHELL': '/bin/bash', 03:29:07 INFO - 'SHLVL': '1', 03:29:07 INFO - 'TERM': 'linux', 03:29:07 INFO - 'TMOUT': '86400', 03:29:07 INFO - 'USER': 'cltbld', 03:29:07 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', 03:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:07 INFO - '_': '/tools/buildbot/bin/python'} 03:29:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:07 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 03:29:07 INFO - Cleaning up... 03:29:07 INFO - Return code: 0 03:29:07 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 03:29: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')]} 03:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29: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')]} 03:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29: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', '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 0x7fa9eeb4ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xebd350>, '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 0xebe250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebe6e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', '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 03:29: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', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 03:29: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 psutil>=0.7.1 03:29:07 INFO - Using env: {'DISPLAY': ':0', 03:29:07 INFO - 'HOME': '/home/cltbld', 03:29:07 INFO - 'LANG': 'en_US.UTF-8', 03:29:07 INFO - 'LANGUAGE': 'en_US:en', 03:29:07 INFO - 'LOGNAME': 'cltbld', 03:29:07 INFO - 'MAIL': '/var/mail/cltbld', 03:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:07 INFO - 'MOZ_NO_REMOTE': '1', 03:29:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:07 INFO - 'NO_EM_RESTART': '1', 03:29:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:29:07 INFO - 'PWD': '/builds/slave/test-pgo', 03:29:07 INFO - 'SHELL': '/bin/bash', 03:29:07 INFO - 'SHLVL': '1', 03:29:07 INFO - 'TERM': 'linux', 03:29:07 INFO - 'TMOUT': '86400', 03:29:07 INFO - 'USER': 'cltbld', 03:29:07 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', 03:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:07 INFO - '_': '/tools/buildbot/bin/python'} 03:29:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:08 INFO - Downloading/unpacking psutil>=0.7.1 03:29:08 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 03:29:08 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 03:29:08 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 03:29:08 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 03:29:08 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 03:29:08 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 03:29:09 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 03:29:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 03:29:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 03:29:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:29:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:29:09 INFO - Installing collected packages: psutil 03:29:09 INFO - Running setup.py install for psutil 03:29:09 INFO - building 'psutil._psutil_linux' extension 03:29:09 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 03:29:10 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 03:29:10 INFO - building 'psutil._psutil_posix' extension 03:29:10 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 03:29:10 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 03:29:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:29:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:29:10 INFO - Successfully installed psutil 03:29:10 INFO - Cleaning up... 03:29:10 INFO - Return code: 0 03:29:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 03:29: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')]} 03:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29: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')]} 03:29:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29: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', '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 0x7fa9eeb4ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xebd350>, '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 0xebe250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebe6e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', '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 03:29: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 03:29: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 mozsystemmonitor==0.0.0 03:29:10 INFO - Using env: {'DISPLAY': ':0', 03:29:10 INFO - 'HOME': '/home/cltbld', 03:29:10 INFO - 'LANG': 'en_US.UTF-8', 03:29:10 INFO - 'LANGUAGE': 'en_US:en', 03:29:10 INFO - 'LOGNAME': 'cltbld', 03:29:10 INFO - 'MAIL': '/var/mail/cltbld', 03:29:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:10 INFO - 'MOZ_NO_REMOTE': '1', 03:29:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:10 INFO - 'NO_EM_RESTART': '1', 03:29:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:29:10 INFO - 'PWD': '/builds/slave/test-pgo', 03:29:10 INFO - 'SHELL': '/bin/bash', 03:29:10 INFO - 'SHLVL': '1', 03:29:10 INFO - 'TERM': 'linux', 03:29:10 INFO - 'TMOUT': '86400', 03:29:10 INFO - 'USER': 'cltbld', 03:29:10 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', 03:29:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:10 INFO - '_': '/tools/buildbot/bin/python'} 03:29:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:29: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 03:29: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 03:29: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 03:29: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 03:29: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 03:29: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 03:29:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:29:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 03:29:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:29:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:29:12 INFO - Installing collected packages: mozsystemmonitor 03:29:12 INFO - Running setup.py install for mozsystemmonitor 03:29:12 INFO - Successfully installed mozsystemmonitor 03:29:12 INFO - Cleaning up... 03:29:12 INFO - Return code: 0 03:29:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 03:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:12 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 0x7fa9eeb4ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xebd350>, '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 0xebe250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebe6e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', '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 03:29:12 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 03:29:12 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 03:29:12 INFO - Using env: {'DISPLAY': ':0', 03:29:12 INFO - 'HOME': '/home/cltbld', 03:29:12 INFO - 'LANG': 'en_US.UTF-8', 03:29:12 INFO - 'LANGUAGE': 'en_US:en', 03:29:12 INFO - 'LOGNAME': 'cltbld', 03:29:12 INFO - 'MAIL': '/var/mail/cltbld', 03:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:12 INFO - 'MOZ_NO_REMOTE': '1', 03:29:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:12 INFO - 'NO_EM_RESTART': '1', 03:29:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:29:12 INFO - 'PWD': '/builds/slave/test-pgo', 03:29:12 INFO - 'SHELL': '/bin/bash', 03:29:12 INFO - 'SHLVL': '1', 03:29:12 INFO - 'TERM': 'linux', 03:29:12 INFO - 'TMOUT': '86400', 03:29:12 INFO - 'USER': 'cltbld', 03:29:12 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', 03:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:12 INFO - '_': '/tools/buildbot/bin/python'} 03:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:12 INFO - Downloading/unpacking blobuploader==1.2.4 03:29: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 03:29: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 03:29: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 03:29: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 03:29: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 03:29: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 03:29:14 INFO - Downloading blobuploader-1.2.4.tar.gz 03:29:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 03:29:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:29:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 03:29:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 03:29:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:15 INFO - Downloading docopt-0.6.1.tar.gz 03:29:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 03:29:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 03:29:15 INFO - Installing collected packages: blobuploader, requests, docopt 03:29:15 INFO - Running setup.py install for blobuploader 03:29:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:29:15 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 03:29:15 INFO - Running setup.py install for requests 03:29:15 INFO - Running setup.py install for docopt 03:29:15 INFO - Successfully installed blobuploader requests docopt 03:29:15 INFO - Cleaning up... 03:29:15 INFO - Return code: 0 03:29:15 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:29:15 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')]} 03:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:15 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')]} 03:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:15 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 0x7fa9eeb4ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xebd350>, '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 0xebe250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebe6e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', '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 03:29:15 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 03:29:15 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 03:29:15 INFO - Using env: {'DISPLAY': ':0', 03:29:15 INFO - 'HOME': '/home/cltbld', 03:29:15 INFO - 'LANG': 'en_US.UTF-8', 03:29:15 INFO - 'LANGUAGE': 'en_US:en', 03:29:15 INFO - 'LOGNAME': 'cltbld', 03:29:15 INFO - 'MAIL': '/var/mail/cltbld', 03:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:15 INFO - 'MOZ_NO_REMOTE': '1', 03:29:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:15 INFO - 'NO_EM_RESTART': '1', 03:29:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:29:15 INFO - 'PWD': '/builds/slave/test-pgo', 03:29:15 INFO - 'SHELL': '/bin/bash', 03:29:15 INFO - 'SHLVL': '1', 03:29:15 INFO - 'TERM': 'linux', 03:29:15 INFO - 'TMOUT': '86400', 03:29:15 INFO - 'USER': 'cltbld', 03:29:15 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', 03:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:15 INFO - '_': '/tools/buildbot/bin/python'} 03:29:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:29:15 INFO - Running setup.py (path:/tmp/pip-zZ2rvH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:29:16 INFO - Running setup.py (path:/tmp/pip-mheVcx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:29:16 INFO - Running setup.py (path:/tmp/pip-e0pKB7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:29:16 INFO - Running setup.py (path:/tmp/pip-Cmc3iF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 03:29:16 INFO - Running setup.py (path:/tmp/pip-ixeQqY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 03:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:29:16 INFO - Running setup.py (path:/tmp/pip-qk3j4F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:29:16 INFO - Running setup.py (path:/tmp/pip-Y44RSj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:29:16 INFO - Running setup.py (path:/tmp/pip-ave_rn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 03:29:16 INFO - Running setup.py (path:/tmp/pip-UQ622I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 03:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 03:29:16 INFO - Running setup.py (path:/tmp/pip-9ba3oh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 03:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:29:16 INFO - Running setup.py (path:/tmp/pip-7_qzmH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:29:16 INFO - Running setup.py (path:/tmp/pip-DIwKYM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:29:16 INFO - Running setup.py (path:/tmp/pip-s3yHEI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:29:16 INFO - Running setup.py (path:/tmp/pip-M8VaK0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:29:16 INFO - Running setup.py (path:/tmp/pip-0A2b_v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 03:29:17 INFO - Running setup.py (path:/tmp/pip-S3u3Zt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 03:29:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 03:29:17 INFO - Running setup.py (path:/tmp/pip-sjrocv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 03:29:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:29:17 INFO - Running setup.py install for manifestparser 03:29:17 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 03:29:17 INFO - Running setup.py install for mozcrash 03:29:17 INFO - Running setup.py install for mozdebug 03:29:17 INFO - Running setup.py install for mozdevice 03:29:17 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 03:29:17 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 03:29:17 INFO - Running setup.py install for mozfile 03:29:17 INFO - Running setup.py install for mozhttpd 03:29:17 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 03:29:17 INFO - Running setup.py install for mozinfo 03:29:17 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 03:29:17 INFO - Running setup.py install for mozInstall 03:29:17 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 03:29:17 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 03:29:17 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 03:29:17 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 03:29:17 INFO - Running setup.py install for mozleak 03:29:18 INFO - Running setup.py install for mozlog 03:29:18 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 03:29:18 INFO - Running setup.py install for moznetwork 03:29:18 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 03:29:18 INFO - Running setup.py install for mozprocess 03:29:18 INFO - Running setup.py install for mozprofile 03:29:18 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 03:29:18 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 03:29:18 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 03:29:18 INFO - Running setup.py install for mozrunner 03:29:18 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 03:29:18 INFO - Running setup.py install for mozscreenshot 03:29:18 INFO - Running setup.py install for moztest 03:29:18 INFO - Running setup.py install for mozversion 03:29:18 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 03:29:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:29:18 INFO - Cleaning up... 03:29:18 INFO - Return code: 0 03:29:18 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:29:18 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')]} 03:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:18 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')]} 03:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:18 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 0x7fa9eeb4ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xebd350>, '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 0xebe250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebe6e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', '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 03:29:18 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 03:29:18 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 03:29:18 INFO - Using env: {'DISPLAY': ':0', 03:29:18 INFO - 'HOME': '/home/cltbld', 03:29:18 INFO - 'LANG': 'en_US.UTF-8', 03:29:18 INFO - 'LANGUAGE': 'en_US:en', 03:29:18 INFO - 'LOGNAME': 'cltbld', 03:29:18 INFO - 'MAIL': '/var/mail/cltbld', 03:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:18 INFO - 'MOZ_NO_REMOTE': '1', 03:29:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:18 INFO - 'NO_EM_RESTART': '1', 03:29:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:29:18 INFO - 'PWD': '/builds/slave/test-pgo', 03:29:18 INFO - 'SHELL': '/bin/bash', 03:29:18 INFO - 'SHLVL': '1', 03:29:18 INFO - 'TERM': 'linux', 03:29:18 INFO - 'TMOUT': '86400', 03:29:18 INFO - 'USER': 'cltbld', 03:29:18 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', 03:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:18 INFO - '_': '/tools/buildbot/bin/python'} 03:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:29:19 INFO - Running setup.py (path:/tmp/pip-fucGy9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:29:19 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)) 03:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:29:19 INFO - Running setup.py (path:/tmp/pip-56mZut-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:29:19 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)) 03:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:29:19 INFO - Running setup.py (path:/tmp/pip-JO6p5A-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:29:19 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)) 03:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:29:19 INFO - Running setup.py (path:/tmp/pip-7reBJ6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:29:19 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)) 03:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 03:29:19 INFO - Running setup.py (path:/tmp/pip-MVJw8R-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 03:29:19 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)) 03:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:29:19 INFO - Running setup.py (path:/tmp/pip-J4kEoX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:29:19 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)) 03:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:29:19 INFO - Running setup.py (path:/tmp/pip-yNYUyl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:29:19 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)) 03:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:29:19 INFO - Running setup.py (path:/tmp/pip-M_yA_J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:29:19 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)) 03:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 03:29:19 INFO - Running setup.py (path:/tmp/pip-qWrN5V-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 03:29:19 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)) 03:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 03:29:19 INFO - Running setup.py (path:/tmp/pip-1htoGR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 03:29:19 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)) 03:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:29:19 INFO - Running setup.py (path:/tmp/pip-Zen1Yx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:29:19 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)) 03:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:29:19 INFO - Running setup.py (path:/tmp/pip-keAa50-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:29:20 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)) 03:29:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:29:20 INFO - Running setup.py (path:/tmp/pip-4J93eh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:29:20 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)) 03:29:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:29:20 INFO - Running setup.py (path:/tmp/pip-uQw1Qj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:29:20 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)) 03:29:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:29:20 INFO - Running setup.py (path:/tmp/pip-2nDdVS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:29:20 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)) 03:29:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 03:29:20 INFO - Running setup.py (path:/tmp/pip-liUyIm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 03:29:20 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)) 03:29:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 03:29:20 INFO - Running setup.py (path:/tmp/pip-UnqcXs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 03:29:20 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)) 03:29:20 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)) 03:29:20 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)) 03:29:20 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)) 03:29:20 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)) 03:29:20 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)) 03:29:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 03:29:20 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 03:29:20 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 03:29:20 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 03:29:20 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 03:29:20 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 03:29:20 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 03:29:22 INFO - Downloading blessings-1.5.1.tar.gz 03:29:22 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 03:29:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 03:29:22 INFO - Installing collected packages: blessings 03:29:22 INFO - Running setup.py install for blessings 03:29:22 INFO - Successfully installed blessings 03:29:22 INFO - Cleaning up... 03:29:22 INFO - Return code: 0 03:29:22 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 03:29:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 03:29:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 03:29:22 INFO - Reading from file tmpfile_stdout 03:29:22 INFO - Current package versions: 03:29:22 INFO - argparse == 1.2.1 03:29:22 INFO - blessings == 1.5.1 03:29:22 INFO - blobuploader == 1.2.4 03:29:22 INFO - docopt == 0.6.1 03:29:22 INFO - manifestparser == 1.1 03:29:22 INFO - mozInstall == 1.12 03:29:22 INFO - mozcrash == 0.16 03:29:22 INFO - mozdebug == 0.1 03:29:22 INFO - mozdevice == 0.46 03:29:22 INFO - mozfile == 1.2 03:29:22 INFO - mozhttpd == 0.7 03:29:22 INFO - mozinfo == 0.8 03:29:22 INFO - mozleak == 0.1 03:29:22 INFO - mozlog == 3.0 03:29:22 INFO - moznetwork == 0.27 03:29:22 INFO - mozprocess == 0.22 03:29:22 INFO - mozprofile == 0.27 03:29:22 INFO - mozrunner == 6.11 03:29:22 INFO - mozscreenshot == 0.1 03:29:22 INFO - mozsystemmonitor == 0.0 03:29:22 INFO - moztest == 0.7 03:29:22 INFO - mozversion == 1.4 03:29:22 INFO - psutil == 3.1.1 03:29:22 INFO - requests == 1.2.3 03:29:22 INFO - wsgiref == 0.1.2 03:29:22 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:29:22 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')]} 03:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:22 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')]} 03:29:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:22 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 0x7fa9eeb4ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xebd350>, '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 0xebe250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebe6e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', '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 03:29:22 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 03:29:22 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 03:29:22 INFO - Using env: {'DISPLAY': ':0', 03:29:22 INFO - 'HOME': '/home/cltbld', 03:29:22 INFO - 'LANG': 'en_US.UTF-8', 03:29:22 INFO - 'LANGUAGE': 'en_US:en', 03:29:22 INFO - 'LOGNAME': 'cltbld', 03:29:22 INFO - 'MAIL': '/var/mail/cltbld', 03:29:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:22 INFO - 'MOZ_NO_REMOTE': '1', 03:29:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:22 INFO - 'NO_EM_RESTART': '1', 03:29:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:29:22 INFO - 'PWD': '/builds/slave/test-pgo', 03:29:22 INFO - 'SHELL': '/bin/bash', 03:29:22 INFO - 'SHLVL': '1', 03:29:22 INFO - 'TERM': 'linux', 03:29:22 INFO - 'TMOUT': '86400', 03:29:22 INFO - 'USER': 'cltbld', 03:29:22 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', 03:29:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:22 INFO - '_': '/tools/buildbot/bin/python'} 03:29:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:22 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)) 03:29:22 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)) 03:29:22 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)) 03:29:22 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)) 03:29:22 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)) 03:29:22 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)) 03:29:22 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)) 03:29:22 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)) 03:29:22 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)) 03:29:22 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)) 03:29:22 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)) 03:29:22 INFO - Cleaning up... 03:29:22 INFO - Return code: 0 03:29:22 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 03:29:22 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')]} 03:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:22 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')]} 03:29:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:22 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 0x7fa9eeb4ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xebd350>, '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 0xebe250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebe6e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', '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 03:29:22 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 03:29:22 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 03:29:22 INFO - Using env: {'DISPLAY': ':0', 03:29:22 INFO - 'HOME': '/home/cltbld', 03:29:22 INFO - 'LANG': 'en_US.UTF-8', 03:29:22 INFO - 'LANGUAGE': 'en_US:en', 03:29:22 INFO - 'LOGNAME': 'cltbld', 03:29:22 INFO - 'MAIL': '/var/mail/cltbld', 03:29:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:22 INFO - 'MOZ_NO_REMOTE': '1', 03:29:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:22 INFO - 'NO_EM_RESTART': '1', 03:29:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:29:22 INFO - 'PWD': '/builds/slave/test-pgo', 03:29:22 INFO - 'SHELL': '/bin/bash', 03:29:22 INFO - 'SHLVL': '1', 03:29:22 INFO - 'TERM': 'linux', 03:29:22 INFO - 'TMOUT': '86400', 03:29:22 INFO - 'USER': 'cltbld', 03:29:22 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', 03:29:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:22 INFO - '_': '/tools/buildbot/bin/python'} 03:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:23 INFO - Downloading/unpacking jsonschema 03:29:23 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 03:29:23 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 03:29:23 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 03:29:23 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 03:29:23 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 03:29:23 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 03:29:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:29:24 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 03:29:24 INFO - Downloading/unpacking functools32 (from jsonschema) 03:29:24 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 03:29:24 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 03:29:24 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 03:29:24 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 03:29:24 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 03:29:24 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 03:29:24 INFO - Downloading functools32-3.2.3-2.tar.gz 03:29:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 03:29:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 03:29:24 INFO - warning: no files found matching '*.txt' 03:29:24 INFO - no previously-included directories found matching 'build' 03:29:24 INFO - no previously-included directories found matching 'dist' 03:29:24 INFO - no previously-included directories found matching '.git*' 03:29:24 INFO - Installing collected packages: jsonschema, functools32 03:29:25 INFO - Running setup.py install for functools32 03:29:25 INFO - warning: no files found matching '*.txt' 03:29:25 INFO - no previously-included directories found matching 'build' 03:29:25 INFO - no previously-included directories found matching 'dist' 03:29:25 INFO - no previously-included directories found matching '.git*' 03:29:25 INFO - Successfully installed jsonschema functools32 03:29:25 INFO - Cleaning up... 03:29:25 INFO - Return code: 0 03:29:25 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 03:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:25 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 0x7fa9eeb4ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xebd350>, '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 0xebe250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebe6e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', '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 03:29:25 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 03:29:25 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 03:29:25 INFO - Using env: {'DISPLAY': ':0', 03:29:25 INFO - 'HOME': '/home/cltbld', 03:29:25 INFO - 'LANG': 'en_US.UTF-8', 03:29:25 INFO - 'LANGUAGE': 'en_US:en', 03:29:25 INFO - 'LOGNAME': 'cltbld', 03:29:25 INFO - 'MAIL': '/var/mail/cltbld', 03:29:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:25 INFO - 'MOZ_NO_REMOTE': '1', 03:29:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:25 INFO - 'NO_EM_RESTART': '1', 03:29:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:29:25 INFO - 'PWD': '/builds/slave/test-pgo', 03:29:25 INFO - 'SHELL': '/bin/bash', 03:29:25 INFO - 'SHLVL': '1', 03:29:25 INFO - 'TERM': 'linux', 03:29:25 INFO - 'TMOUT': '86400', 03:29:25 INFO - 'USER': 'cltbld', 03:29:25 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', 03:29:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:25 INFO - '_': '/tools/buildbot/bin/python'} 03:29:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:25 INFO - Downloading/unpacking flake8 03:29:25 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 03:29:25 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 03:29:25 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 03:29:25 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 03:29:25 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 03:29:25 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 03:29:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:29:27 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 03:29:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:29:27 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 03:29:27 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 03:29:27 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 03:29:27 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 03:29:27 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 03:29:27 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 03:29:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:29:27 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 03:29:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:29:27 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 03:29:27 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 03:29:27 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 03:29:27 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 03:29:27 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 03:29:27 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 03:29:27 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 03:29:27 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:29:27 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 03:29:27 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 03:29:27 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 03:29:27 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 03:29:27 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 03:29:27 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 03:29:27 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 03:29:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 03:29:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:29:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:29:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:29:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:29:27 INFO - no previously-included directories found matching 'docs/_build' 03:29:27 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:29:27 INFO - Running setup.py install for pep8 03:29:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:29:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:29:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:29:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:29:27 INFO - no previously-included directories found matching 'docs/_build' 03:29:27 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 03:29:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:29:27 INFO - Cleaning up... 03:29:27 INFO - Return code: 0 03:29:27 INFO - Running post-action listener: _resource_record_post_action 03:29:27 INFO - Running post-action listener: _start_resource_monitoring 03:29:28 INFO - Starting resource monitoring. 03:29:28 INFO - ##### 03:29:28 INFO - ##### Running install step. 03:29:28 INFO - ##### 03:29:28 INFO - Running pre-action listener: _resource_record_pre_action 03:29:28 INFO - Running main action method: install 03:29:28 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 03:29:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 03:29:28 INFO - Reading from file tmpfile_stdout 03:29:28 INFO - Detecting whether we're running mozinstall >=1.0... 03:29:28 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 03:29:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 03:29:28 INFO - Reading from file tmpfile_stdout 03:29:28 INFO - Output received: 03:29:28 INFO - Usage: mozinstall [options] installer 03:29:28 INFO - Options: 03:29:28 INFO - -h, --help show this help message and exit 03:29:28 INFO - -d DEST, --destination=DEST 03:29:28 INFO - Directory to install application into. [default: 03:29:28 INFO - "/builds/slave/test-pgo"] 03:29:28 INFO - --app=APP Application being installed. [default: firefox] 03:29:28 INFO - mkdir: /builds/slave/test-pgo/build/application 03:29:28 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'] 03:29:28 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 03:29:42 INFO - Reading from file tmpfile_stdout 03:29:42 INFO - Output received: 03:29:42 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 03:29:42 INFO - Running post-action listener: _resource_record_post_action 03:29:42 INFO - ##### 03:29:42 INFO - ##### Running run-tests step. 03:29:42 INFO - ##### 03:29:42 INFO - Running pre-action listener: _resource_record_pre_action 03:29:42 INFO - Running main action method: run_tests 03:29:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 03:29:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 03:29:42 INFO - Python 2.7.3 03:29:42 INFO - Return code: 0 03:29:42 INFO - grabbing minidump binary from tooltool 03:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:42 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 0xebd350>, '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 0xebe250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebe6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 03:29:42 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 03:29:42 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 03:29:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:29:42 INFO - Return code: 0 03:29:42 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 03:29:42 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 03:29:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 03:29:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 03:29:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 03:29:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 03:29:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 03:29:43 INFO - Return code: 0 03:29:43 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/1451898186/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 03:29:43 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/1451898186/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --webServer localhost 03:29:43 INFO - Using env: {'DISPLAY': ':0', 03:29:43 INFO - 'HOME': '/home/cltbld', 03:29:43 INFO - 'LANG': 'en_US.UTF-8', 03:29:43 INFO - 'LANGUAGE': 'en_US:en', 03:29:43 INFO - 'LOGNAME': 'cltbld', 03:29:43 INFO - 'MAIL': '/var/mail/cltbld', 03:29:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 03:29:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 03:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:43 INFO - 'MOZ_NO_REMOTE': '1', 03:29:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 03:29:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:43 INFO - 'NO_EM_RESTART': '1', 03:29:43 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:29:43 INFO - 'PWD': '/builds/slave/test-pgo', 03:29:43 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 03:29:43 INFO - 'SHELL': '/bin/bash', 03:29:43 INFO - 'SHLVL': '1', 03:29:43 INFO - 'TERM': 'linux', 03:29:43 INFO - 'TMOUT': '86400', 03:29:43 INFO - 'USER': 'cltbld', 03:29:43 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600', 03:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:43 INFO - '_': '/tools/buildbot/bin/python'} 03:29:43 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/1451898186/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] with output_timeout 3600 03:29:43 INFO - mozversion INFO | application_buildid: 20160104010306 03:29:43 INFO - mozversion INFO | application_changeset: 692ee3b8ba0ab463fda9876c8b4a04f04830c891 03:29:43 INFO - mozversion INFO | application_display_name: Nightly 03:29:43 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:29:43 INFO - mozversion INFO | application_name: Firefox 03:29:43 INFO - mozversion INFO | application_remotingname: firefox 03:29:43 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 03:29:43 INFO - mozversion INFO | application_vendor: Mozilla 03:29:43 INFO - mozversion INFO | application_version: 44.0 03:29:43 INFO - mozversion INFO | platform_buildid: 20160104010306 03:29:43 INFO - mozversion INFO | platform_changeset: 692ee3b8ba0ab463fda9876c8b4a04f04830c891 03:29:43 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 03:29:43 INFO - mozversion INFO | platform_version: 44.0 03:29:43 INFO - 2016-01-04 03:29:43,702 DEBUG : using testdate: 1451906983 03:29:43 INFO - 2016-01-04 03:29:43,702 DEBUG : actual date: 1451906983 03:29:43 INFO - 2016-01-04 03:29:43,711 INFO : starting webserver on 'localhost:35800' 03:29:43 INFO - 2016-01-04 03:29:43,712 INFO : Starting test suite talos-linux64-ix-046 03:29:43 INFO - 2016-01-04 03:29:43,712 INFO : Starting test dromaeo_css 03:29:43 INFO - 2016-01-04 03:29:43,712 DEBUG : operating with platform_type : linux_ 03:29:43 INFO - 2016-01-04 03:29:43,713 INFO : Initialising browser for dromaeo_css test... 03:29:43 INFO - 2016-01-04 03:29:43,729 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpm7SvIb/profile http://localhost:35800/getInfo.html 03:29:47 INFO - 2016-01-04 03:29:47,016 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:29:47 INFO - 2016-01-04 03:29:47,016 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:29:47 INFO - 2016-01-04 03:29:47,016 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 03:29:47 INFO - 2016-01-04 03:29:47,016 DEBUG : BROWSER_OUTPUT: __metrics 03:29:47 INFO - 2016-01-04 03:29:47,104 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] 03:29:48 INFO - 2016-01-04 03:29:48,333 INFO : Browser initialized. 03:29:48 INFO - 2016-01-04 03:29:48,334 INFO : Running cycle 1/1 for dromaeo_css test... 03:29:48 INFO - 2016-01-04 03:29:48,334 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpm7SvIb/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:29:59 INFO - 2016-01-04 03:29:59,895 DEBUG : BROWSER_OUTPUT: RSS: Main: 128081920 03:29:59 INFO - 2016-01-04 03:29:59,895 DEBUG : BROWSER_OUTPUT: 03:30:51 INFO - 2016-01-04 03:30:51,003 DEBUG : BROWSER_OUTPUT: 1451907051001 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] 03:32:58 INFO - 2016-01-04 03:32:58,780 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35800/tests/dromaeo/cssquery-ext.html) 03:32:59 INFO - 2016-01-04 03:32:59,833 DEBUG : BROWSER_OUTPUT: RSS: Main: 236331008 03:32:59 INFO - 2016-01-04 03:32:59,833 DEBUG : BROWSER_OUTPUT: 03:36:01 INFO - 2016-01-04 03:36:01,873 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/dromaeo/cssquery-ext.html (next: http://localhost:35800/tests/dromaeo/cssquery-jquery.html) 03:36:02 INFO - 2016-01-04 03:36:02,434 DEBUG : BROWSER_OUTPUT: RSS: Main: 212627456 03:36:02 INFO - 2016-01-04 03:36:02,435 DEBUG : BROWSER_OUTPUT: 03:39:04 INFO - 2016-01-04 03:39:04,172 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35800/tests/dromaeo/cssquery-mootools.html) 03:39:04 INFO - 2016-01-04 03:39:04,732 DEBUG : BROWSER_OUTPUT: RSS: Main: 202559488 03:39:04 INFO - 2016-01-04 03:39:04,733 DEBUG : BROWSER_OUTPUT: 03:42:06 INFO - 2016-01-04 03:42:06,416 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35800/tests/dromaeo/cssquery-prototype.html) 03:42:06 INFO - 2016-01-04 03:42:06,978 DEBUG : BROWSER_OUTPUT: RSS: Main: 200736768 03:42:06 INFO - 2016-01-04 03:42:06,978 DEBUG : BROWSER_OUTPUT: 03:45:09 INFO - 2016-01-04 03:45:09,254 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35800/tests/dromaeo/cssquery-yui.html) 03:45:09 INFO - 2016-01-04 03:45:09,836 DEBUG : BROWSER_OUTPUT: RSS: Main: 194945024 03:45:09 INFO - 2016-01-04 03:45:09,836 DEBUG : BROWSER_OUTPUT: 03:48:11 INFO - 2016-01-04 03:48:11,979 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/dromaeo/cssquery-yui.html (next: http://localhost:35800/tests/dromaeo/cssquery-dojo.html) 03:48:12 INFO - 2016-01-04 03:48:12,230 DEBUG : BROWSER_OUTPUT: RSS: Main: 235200512 03:48:12 INFO - 2016-01-04 03:48:12,230 DEBUG : BROWSER_OUTPUT: 03:48:12 INFO - 2016-01-04 03:48:12,230 DEBUG : BROWSER_OUTPUT: __start_tp_report 03:48:12 INFO - 2016-01-04 03:48:12,230 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 03:48:12 INFO - 2016-01-04 03:48:12,230 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 03:48:12 INFO - 2016-01-04 03:48:12,230 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 03:48:12 INFO - 2016-01-04 03:48:12,231 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3315.8342082895856;3396.0830575744567;3414.3854106260865;3424.5376874121985;3442.483627455881;5674.971625141874;5700.8859822803515;5714.685692286927;5725.398833122519;5725.4847063764255;5711.514521265687;5738.110592858103;5741.598088133832;5748.913766293498;5757.510611598019;4975.601951843843;5181.067407866573;5206.661566998152;5273;5305.124654432014;5027.547520723152;5046.5710414614805;5110.054639891613;5184.922226166592;5200.115980283342;5206.088934436478;5243;5249.081410753122;5249.606279529032;5272.446393128724;4935.407751069859;5121.539061484447;5140.871478213037;5233.947660523403;5237.162054071349;4955.083309587738;5049.949500504985;5072.594192464594;5073.771680274396;5111.642185047048;5484.287042684466;5487.286652735119;5505.972470137664;5508.256385387961;5511.696856672881;5911.763529458816;6023.397660233985;6038.879222415571;6042.486388656969;6043.3956604339655;6018.458338749533;6084.5132389408745;6088.269407671108;6112.63324200545;6116.571839971204;5636.71816409182;5732.197492351074;5738.254026976508;5745.971270143623;5754.884902301972;5943.791967281124;6058.1518587397795;6110.541709371815;6223.190985171898;6241.937580624226;6026.427489388501;6062.757489700362;6068.908966365508;6083.543734219951;6091.2081429414775;6166.506679465632;6176.752929882754;6183.505319574423;6184.381561843779;6188.659623720739;5853.970730146407;5947.167396564398;5969.582129250997;6120.66336351496;6128.754849806047;6104.633721976696;6120.479759220427;6129.478994285536;6129.877402451926;6133.662648554283;4686.671932964659;4690.437147542317;4690.906181876343;4698.083873644607;4724.960508688083;4041.6362527372676;4106.404571337199;4120.7321524100835;4200.85297014603;4206.053637931441;5791.942080579224;5821.505172060422;5828;5842.795502157404;5844.853878653068;6134.723937422827;6155.784547540815;6157.230346206724;6161.938380616137;6162.476189523851;5457.808976685797;5661.405552417019;5722.856928576819;5753.798617048383;5754.971225143931;5292.52367286946;5326.121190003683;5341.252224688546;5342.198670199501;5349.81275655358;6044.455998960027;6304.495640348761;6309.968450157719;6313.116163737081;6320.336364681734;6039.607425517327;6055.333913269449;6138.324784273637;6141.570090093651;6152.938470615237;6258.155149054819;6262.937370626236;6273.341299163522;6307.085472606538;6309.3690630936535;5859.677717725566;5983.790567330123;6037.516998640186;6039.365866583946;6040.335563088145;6022.427869352405;6128.295246046583;6128.601640893327;6129.08063790435;6134.171886795404;5970.31341395745;6004.519638429003;6021.367756385692;6022.156898034191;6025.0359942409;5828.883422331614;5943.049112142036;5959.344472107978;5992.610480318765;6002.129691194839;5850.824475265749;5867.9413205867395;5868.67722275279;5876.44173803488;5890.528757699288;5908.911366329595;5916.142159386951;5964.373740757158;5964.612300200473;5968.731407086779;5692.516136128475;5761.193432919478;5800.680962546931;5837.387074357302;5856.795012174553 03:48:12 INFO - 2016-01-04 03:48:12,234 DEBUG : BROWSER_OUTPUT: |1;ext.html;603.9853046881241;636.3890664961637;637.9457746091582;640.609228370694;645.303072681504;34885.651143488554;44635.330470042936;44857.77571112148;44894;44896.1020779585;47624.52375476244;48257;48260.51739482604;48270.75864620672;48270.75864620672;8151.06262779779;8243.876341854877;9358.438493690383;9580.473073980927;10737.570497180104;11022.393768342738;11126.443677816076;11165.944170279137;11176.497057632385;11178.720531986684;11093.72265693356;11189.328640281608;11218;11221.551137954471;11229.438528073604;11087.057600104;11183.440827958651;11184.720381990433;11185.272957257794;11196.552137914474;10943.343399396063;11060.115190784718;11070.889291107067;11072.501737421835;11077.723056923562;44389.33415998763;44495.33257001147;44705.329420058726;44779.776101119285;44807.551924480664;3740.0462881965195;3787.3750831112834;3834.8466061357512;3870.767753934773;4238.7456752594635;33894.83052584721;33922.83038584816;33951.32097358064;33991;34048.31903361944;3848.1726428817938;3940.290747665419;3968.72218944674;4029.435878976946;4100.0159961609;329738;331084;331590;332410;333153;58565.41434585685;59141;72897.63551182316;72966.63516682382;73000;69481;69832.6508367455;70203.64898175477;70306.29693702997;70341.64829175822;18613.06934653262;18991.050447477846;19029.14368853377;19032.52418689544;19042.619147617246;13801.240931748851;13847.792283115761;13847.792283115761;13959.79060314096;14018.088824226392;22069.227577035046;22281.55436891253;22329.3301200961;22336.888315558317;22457.10171593118;8012.278894899487;8253.257206851413;8288.958555207186;8326.542040187847;8336.916630833735;1068.1188019883627;1101.9366311509273;1107.9556817727362;1138.9601363952388;1173.7828501727208;31334.373312534077;31908.680913191034;32027.35945281081;32224.35551288961;32917.50623740646;1090.0734375780683;1163.790517706821;1211.0553768060927;1211.5577814097792;1245.0537591430577;14610.488632897797;16006;16776.245068971926;16881.577960551083;17298.740518892228;394614.0807887884;396644;400184;400372;401473;52479.73760131251;52822;52887.73556132195;52911.73544132255;52949.735251323495;1370.821793166882;1405.5221224783418;1477.697072100217;1518.2560545332929;1539.084244874298;30923.536146958264;30969.380612387176;31160.844195779784;31493.22792939795;31699.20751981219;54687.72656136694;55186;55191.448085520235;55228.171577425564;55286.17070744022;303808;305522;305989;306591;306721;36330;36567;36720.63279367279;36893.44659830051;36899.26201476009;48060.7596962013;48136.518634813205;49126.75436622794;49328.50671493239;49388.50611493983;40265.59734402736;40893.795531023345;40924.59075409327;41211;42001;40364.394534081424;40858.59141408548;41055.178896421305;41335.586644133175;41412.585874140874;39429.408558872216;40278;40280.79859600683;40662.1867562653;40761;35042.29915401729;35322.93786214618;35805;35945.820270898475;36363.636363636026;27491.587626186025;27971.860140699166;28076.157715268575;28173.577396339482;28231.294217644725 03:48:12 INFO - 2016-01-04 03:48:12,236 DEBUG : BROWSER_OUTPUT: |2;jquery.html;367.5277268709708;369.08651088555825;369.2117329501514;372.75770749013157;373.26094333220226;12252.142350035501;12644.620661380173;14043.22762248076;14441.42234310629;18328.81671183288;13365.465381384758;13435.126716763429;13632.386542605582;13800.723985520284;13833.377498012589;3869.1681288523105;4835.419749630049;4872.123017856784;4979.278004689318;5007.023630392075;3959.148783011652;5249.396319423258;5312.574994000471;5315;5316.946830531684;5167.224916262553;5231.006108839328;5233.842984710464;5240.475952404748;5312.415634280226;4543.795529201194;5346.893062138774;5355.196720491918;5356.678599284055;5357.919631205535;4591.058832939273;4666.253399456071;4671.462781780105;4675.742834144121;4677.88305292367;15395.923020384938;15406.075635461795;16019.038857668576;16106.791990600752;16138.193090345436;2221.3558068160214;2221.466847956495;2229.2420577003895;2240.8543444676206;2413.9034438622434;11131.443427828657;11173.776524469466;11397.943010284978;11400.71498212543;11534.480948357344;1813.2656274208966;1825.8174182581768;1833.1475863723433;1868.1499917537487;1888.9338873139511;67560;68265.65867170681;69177.65411172963;69627;69925.65037174833;7656.349210317186;7811.648475818602;7993;8048.315893149031;8061.355091592659;9660.661876834278;9668.129868311886;9682.273829462818;9700.126988571063;9702.854457183148;6520.967395162994;6563.245226798884;6587.473002159816;6613.834654133589;6625.271220165778;7023.894641580278;7024.5434046786795;7034.788956331318;7045.753398631024;7058.435325173974;3477.7739446935866;3644.1618427761764;3680.0799800049986;3698.6116457772087;3716.312482190803;1249.6063739921894;1258.169608058695;1267.9492820287269;1294.9805752913715;1298.0718786068007;520.2404489445377;536.2358638939496;541.0909671751417;546.3580293155526;582.8309790160806;13635.590932272047;13638.863611363959;13762.862371376359;14069.226192559507;14103;565.6888711208901;570.5492660797923;571.9542436605145;587.5563949218313;601.4196300569971;7417.406607471389;7447.329740323397;7481.85036299271;7497.66260518278;7620.237976202447;404185;414293;648279;651698;667105;80673.59663201764;81543.59228203939;82999.5850020746;84966;85673.14326856652;597.9970100149559;611.6605284067297;612.0604871522193;612.185792895448;612.745710530125;12037.337946413032;12215.938920305342;12223.388830558615;12230.694232644255;12252.816207756712;40109;40309.379890307035;40396.596034040456;40843.795781020905;41402.37896431496;50701.74649126731;53615.73192134171;54192.72903635457;55559.72220138874;55781;7505.587192704454;7605.250792317687;7651.655675494728;7688.423368247516;7746.070471543226;13637.318134093453;13806.585802425943;13844.307784610528;14026.438942442188;14079.296035198404;11351.318920864775;11649.825252621047;11680.357580333164;11798.056155507367;12219.327936963196;11337.489812958602;11609.129315301556;11751.76496470083;12060.336681482602;12347.320897350732;12250.877491224974;12343.506259749509;12401.193922395016;12498.562550310695;12500;7735.9226407735205;7773.727919522789;7841.9215807843475;7880.448368614142;7884.921150788418;11976.880231197576;12025.939870300943;12175.39123043862;12299.077569182526;12604.936975315064 03:48:12 INFO - 2016-01-04 03:48:12,237 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1574.5906064423245;1578.4712121439318;1833.5416145963509;1854.656888475632;1859.7024476083825;7789.415793815465;7920.841583168341;7923.088844782852;7937.960310198441;7939.444238903272;7157.355837974581;7345.41236701064;7546.245375462451;7773.611319434034;8079.717209897654;2310.90756369745;2372.9762702372927;2385.0340612052146;2396.8681722505257;2397.304781613339;2141.06863514371;2416.335507735378;2430.1008626808107;2465.469923966347;2473.6660550825586;2061.835053195741;2317.351141680332;2427.1990243219784;2430.5746494363502;2431.9513609727796;2358.1864256831363;2397.7841994220603;2399.592069348206;2401.1475926046264;2403.375122468153;2386.2125498585424;2391.4380120671626;2402.1952645863657;2403.531311394279;2403.5433267679086;7974.043114826258;8058.838823223561;8065.3144482719035;8069.152738962442;8070.515769053817;4801.183798754185;4851.199552073904;4861.951380486185;4864.100141473803;4885.706857588556;3230.095573239497;3253.8210398428077;3260.7391408687486;3261.59230096238;3290.128116049249;1067.5996501312009;1079.0127033764097;1119.043218048565;2039.5614942787372;2092.1213090502024;10763.085137763299;10833.729156771065;10842.512086956027;10849.077828384598;10861.891381086167;4325.329573916048;4375.890602734957;4439.467263928284;4443.466783985942;4447.911041779211;8889.599968001456;8891.066438024043;8894.28845692343;8898.42160259581;8899.955500222457;4974.353334066548;4985.875353116128;4988.625853061035;4997.800087996439;5017.9247311290355;4586.289125185534;4606.907861842811;4609.331646911179;4623.398958135488;4634.23535116702;3708.7033037356946;3720.9627903721157;3720.981395093007;3729.2355067211156;3742.2328422673286;1069.4438891776388;1078.3206579854798;1132.3206076354286;1136.3011747775058;1140.7832511822728;1159.756451145252;1173.7945859474557;1224.1981502116128;1432.4127107885924;1451.310627451973;7749.690012399441;7868.25251601097;7870.803229919298;7882.763517094496;7885.684572617031;780.4887798491997;782.2724865874791;791.2799352589117;802.0856223904754;895.632790555869;6110.847228819226;6187.133801267826;6196.194494715658;6204.410580994799;6209.161763162006;9408.106229908148;9868.901310986941;9885.703428897144;9889.554970026365;9904.405735655884;9719.271054670928;9723.978982206909;9730.805383892424;9744.805103898025;9768.804623907483;7367.8158046049275;7560.735374261984;7562.281583249583;7580.924190758132;7588.089429268523;7414.110306763223;7450.627468626655;7455.291747283999;7468.589227592534;7482.139553951365;8760.036395996307;8772.91227087721;8881.777955551162;8908.510031948304;8921.107889211027;8696.391252612257;8888.73333799987;8928.464292142493;8936.642534298555;9154.908450915673;6921.134858142732;7023.122109736283;7028.753993610199;7032.894506582507;7038.155421349471;7551.773446796605;7674.424418168548;7701.614919254127;7707.807304817425;7862.567558784093;6805.795826125224;7332.853342933005;7402.9629851850395;7409.481336306622;7470.252974702486;6916.446684265347;7320.524165929198;7342.816429589303;7398.408127349691;7445.888311675221;7484.962575187307;7511.774646760606;7522.097348318237;7524.097108347033;7524.586147761706;6695.832604184934;6725.562838415487;6736.528443008942;6758.797236082925;6789.422899053636;6846.5892046477375;6899.758508452381;6901.62041087745;6967.581945083311;6970.546914450747 03:48:12 INFO - 2016-01-04 03:48:12,240 DEBUG : BROWSER_OUTPUT: |4;prototype.html;352.9717622590192;354.71977138060936;359.7805338743366;361.93485172668943;364.3750967091439;5871.29544454666;5874.353821079678;5887.440693134151;5890.998530249857;5910.70446477676;5611.0741727614995;5685.829425117253;5712.857178570538;5799.449052340021;5805.419458054193;4544.068465964486;4567.36056952027;4577.702449340783;4580.725156490613;4583.404157459526;4557.817687292504;4559.498455169929;4568.314752787075;4570.954290457103;4575.908481830362;4369.73781573105;4441.133978874121;4482.417285752847;4543.727376357413;4545.340925565791;4529.0715403342565;4534.863954081383;4545.590896819269;4548.658850586203;4553.521880202581;3986.382110772835;4027;4027.9597204027878;4034.334334834749;4044.1911617676287;5526.972365138189;5539.390667026624;5540.556755459554;5544.112941929312;5554.694491802949;5161.74191290434;5285.07511185541;5323.095073837457;5338.359396872362;5343;6222.097795819581;6244.344343843876;6263.217097862767;6263.968680156616;6266.843328916813;4496.2805951048;4500.392447019676;4501.212287849613;4501.639868810487;4503.369528266046;6285.748570057192;6411.134496842913;6448.16173897395;6462.709178086998;6472.449841763456;4470.709403888802;4499.190145773792;4501.279795232747;4501.527339629357;4502.144592527409;4551.704139230939;4558.772061396917;4559.110973360163;4559.521250268674;4561.771911404417;5210.687358758487;5213.348331458567;5214.348206474191;5216.008958297987;5219.921701174486;6132.877342453126;6215.192025036717;6226.533010024175;6227.377262273827;6304.810855674337;1622.9675406491804;1636.533587927435;1637.2141372141439;1644.761509581104;1645.243188133472;1105.3147048829776;1109.3399427340719;1109.8612673415823;1127.667338135252;1128.4132251229314;6078.513718902477;6082.482988945901;6088.1476593276975;6093;6093.238345206849;6456.51576131792;6472.644004579794;6478.546501744832;6479.773207937699;6491.285958544557;5933.703314834241;6012.849678757979;6023.156758053876;6115.1744514490865;6115.6330620162935;3496.2483066140903;3530.682238598487;3538.239278555123;3557.8221088945447;3581.194231297937;6552.63960482178;6559.967200163968;6593.307702691149;6629.602223866584;6632.668366581652;6481.09264702942;6557.901631475532;6571.178602674666;6589.934100658932;6610.86778264432;6658.234303055211;6681.766138185238;6683.532152749261;6706.396424321834;6707.262201157869;6491.448226900767;6498.252700939453;6515.934840651533;6526.282108968105;6529.249136349381;6353.36466353361;6420.55056146084;6439.034144878306;6440.6135631862235;6450.741970321134;6698.66506674674;6701.1623547056615;6705.396514313735;6706.463482921452;6709.060731497691;4530.682852200314;4532.184206842668;4533.750643714496;4538.205813982474;4538.818447262072;6378.425941665272;6469.676516174079;6500.154979852589;6507.674616269073;6515.641639709862;6311.589746666452;6319.494440444845;6320.178376810985;6322.304546499973;6322.33615470369;6272.686365681789;6283.622982620875;6293.433590976834;6294.433500984934;6303.46420554258;6042.788502402394;6158.907616385668;6181.876362472815;6186.10301506288;6198.876022479615;6325.936740632535;6342.112104305309;6399.584027038228;6405.807825765234;6419.839504012437;6176.227971503562;6191.349908259568;6212.596181248205;6222.533310001678;6229.844253893508 03:48:12 INFO - 2016-01-04 03:48:12,242 DEBUG : BROWSER_OUTPUT: |5;yui.html;964.7858165510156;964.8408012677909;969.5588507229211;979.2557656181309;988.3575675810722;14297.142171469719;14474.782878256834;14587.489437869677;14673.92663036686;14711.264436778158;14480.782788258184;14948;14969.101853888775;14976.925115374435;14997.550073497785;783.7766236622564;785.1481142959896;929.5538141691992;952.200151872427;967.1440774914216;869.491347561674;943.2831048403194;946.4510583861378;953.2278854128143;954.9856752148723;828.888100106487;982.9115379615833;991.3457118301923;995.1292618958412;995.4525011243824;838.4298676899706;980.1570649241646;986.634945070325;988.5403287471288;989.2185173712759;727.5234721257585;731.9304666056717;733.336330620789;734.111724812977;734.6216698400328;14476.131432114107;14620;14625.92687036558;14641.853581464156;14644.487442939553;13286.402111904987;13730.725385492335;13770.104943178761;13840.723185536333;14103.083299585494;11703.473343699468;11763.235389699728;11789.58736444229;11803.645890623295;12001.579944701982;3590.317839610466;3605.7836529808033;3619.330423871566;3625.619309972468;3694.5751238607368;52928.47071529274;53354.19968700472;53383.46616533785;53907.19139212915;54160.45839541594;8567.143285671507;8574.314054875595;8648.394612377073;8697.95651021741;8773.561321933877;14437.566872993826;14440.566782996528;14451;14484.710305793826;14488.492902748563;7606.847863042819;7642.770716878503;7667.808304792313;7671.923280767232;7844.646990885424;4270.572942705705;4405.845795397146;4448.065906159678;4472.217361961643;4538.750368729686;3148.8897888574247;3174.9682503175136;3175.4284228839033;3178.1577881861326;3178.666240044808;475.97858096385744;480.9831655892097;482.899783390626;489.09517392823705;489.51537977402825;696.2028477393357;723.0347486106107;757.3184134279082;762.710170135345;789.502613353595;9461.24310055194;9628.422294662203;9708.52639499432;9725.418162033715;9748.31761776683;562.3729541561212;584.6141546579322;660.2638058564665;700.9474289428211;731.4039058167683;4568.246239370533;4744.976275118603;4852.053849499313;4987.900241995139;5013.1362645330555;68198.3180168202;69476.6526167376;69592.30407695858;69890.30109698838;70076;60177.097343539885;61490.69254653698;61881.69059154675;61952;62980;55213.72393138089;55645.72177139088;55996.440035599124;56795.71602141963;57300.71349643308;9917.603295868084;10131.341462804894;10173.135283500986;10202.28583999113;10316;39550.40674389829;40892.38661420141;40903.42013310668;41169.1766164681;41182.794086029375;48927.26609100938;51105.74447127741;55123.44876551183;55500.167497486764;55706.164407533106;8847.69033083839;9022.593983270637;9027.71183687743;9198.95400522993;9288.210502107397;13312.933435332761;13761.036727429384;13832.930835346162;13992.650183745487;14043.859561404664;12866.935665321613;12979.545715899898;13495.527656531975;13495.527656531975;14134;12805.487780488676;12863.742725145257;12992.477621420254;13264.801027984395;13540.526081587097;12681.55614553523;12690;12694.809577856155;12867.099303048697;12885.355732213164;10690.679279621623;10892.673219803419;10902.800691923723;10997.725056873642;11244.55021799119;10685.305455145392;10947.069499092666;11090.5563777448;11208.327500349678;11290.604828830952 03:48:12 INFO - 2016-01-04 03:48:12,242 DEBUG : BROWSER_OUTPUT: __end_tp_report 03:48:12 INFO - 2016-01-04 03:48:12,242 DEBUG : BROWSER_OUTPUT: __start_cc_report 03:48:12 INFO - 2016-01-04 03:48:12,242 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,809 03:48:12 INFO - 2016-01-04 03:48:12,242 DEBUG : BROWSER_OUTPUT: __end_cc_report 03:48:12 INFO - 2016-01-04 03:48:12,242 DEBUG : BROWSER_OUTPUT: __startTimestamp1451908092227__endTimestamp 03:48:12 INFO - 2016-01-04 03:48:12,242 DEBUG : BROWSER_OUTPUT: 03:48:12 INFO - 2016-01-04 03:48:12,242 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 03:48:12 INFO - 2016-01-04 03:48:12,242 DEBUG : BROWSER_OUTPUT: Number of tests: 6 03:48:12 INFO - 2016-01-04 03:48:12,243 DEBUG : BROWSER_OUTPUT: 03:48:12 INFO - 2016-01-04 03:48:12,243 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5651.70 Median:5856.80 stddev:632.30 (10.8%) stddev-sans-first:607.10 03:48:12 INFO - 2016-01-04 03:48:12,243 DEBUG : BROWSER_OUTPUT: Values: 3315.8 3396.1 3414.4 3424.5 3442.5 5675.0 5700.9 5714.7 5725.4 5725.5 5711.5 5738.1 5741.6 5748.9 5757.5 4975.6 5181.1 5206.7 5273.0 5305.1 5027.5 5046.6 5110.1 5184.9 5200.1 5206.1 5243.0 5249.1 5249.6 5272.4 4935.4 5121.5 5140.9 5233.9 5237.2 4955.1 5049.9 5072.6 5073.8 5111.6 5484.3 5487.3 5506.0 5508.3 5511.7 5911.8 6023.4 6038.9 6042.5 6043.4 6018.5 6084.5 6088.3 6112.6 6116.6 5636.7 5732.2 5738.3 5746.0 5754.9 5943.8 6058.2 6110.5 6223.2 6241.9 6026.4 6062.8 6068.9 6083.5 6091.2 6166.5 6176.8 6183.5 6184.4 6188.7 5854.0 5947.2 5969.6 6120.7 6128.8 6104.6 6120.5 6129.5 6129.9 6133.7 4686.7 4690.4 4690.9 4698.1 4725.0 4041.6 4106.4 4120.7 4200.9 4206.1 5791.9 5821.5 5828.0 5842.8 5844.9 6134.7 6155.8 6157.2 6161.9 6162.5 5457.8 5661.4 5722.9 5753.8 5755.0 5292.5 5326.1 5341.3 5342.2 5349.8 6044.5 6304.5 6310.0 6313.1 6320.3 6039.6 6055.3 6138.3 6141.6 6152.9 6258.2 6262.9 6273.3 6307.1 6309.4 5859.7 5983.8 6037.5 6039.4 6040.3 6022.4 6128.3 6128.6 6129.1 6134.2 5970.3 6004.5 6021.4 6022.2 6025.0 5828.9 5943.0 5959.3 5992.6 6002.1 5850.8 5867.9 5868.7 5876.4 5890.5 5908.9 5916.1 5964.4 5964.6 5968.7 5692.5 5761.2 5800.7 5837.4 5856.8 03:48:12 INFO - 2016-01-04 03:48:12,243 DEBUG : BROWSER_OUTPUT: 03:48:12 INFO - 2016-01-04 03:48:12,243 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:53649.53 Median:36137.91 stddev:90952.69 (251.7%) stddev-sans-first:91120.63 03:48:12 INFO - 2016-01-04 03:48:12,243 DEBUG : BROWSER_OUTPUT: Values: 604.0 636.4 637.9 640.6 645.3 34885.7 44635.3 44857.8 44894.0 44896.1 47624.5 48257.0 48260.5 48270.8 48270.8 8151.1 8243.9 9358.4 9580.5 10737.6 11022.4 11126.4 11165.9 11176.5 11178.7 11093.7 11189.3 11218.0 11221.6 11229.4 11087.1 11183.4 11184.7 11185.3 11196.6 10943.3 11060.1 11070.9 11072.5 11077.7 44389.3 44495.3 44705.3 44779.8 44807.6 3740.0 3787.4 3834.8 3870.8 4238.7 33894.8 33922.8 33951.3 33991.0 34048.3 3848.2 3940.3 3968.7 4029.4 4100.0 329738.0 331084.0 331590.0 332410.0 333153.0 58565.4 59141.0 72897.6 72966.6 73000.0 69481.0 69832.7 70203.6 70306.3 70341.6 18613.1 18991.1 19029.1 19032.5 19042.6 13801.2 13847.8 13847.8 13959.8 14018.1 22069.2 22281.6 22329.3 22336.9 22457.1 8012.3 8253.3 8289.0 8326.5 8336.9 1068.1 1101.9 1108.0 1139.0 1173.8 31334.4 31908.7 32027.4 32224.4 32917.5 1090.1 1163.8 1211.1 1211.6 1245.1 14610.5 16006.0 16776.2 16881.6 17298.7 394614.1 396644.0 400184.0 400372.0 401473.0 52479.7 52822.0 52887.7 52911.7 52949.7 1370.8 1405.5 1477.7 1518.3 1539.1 30923.5 30969.4 31160.8 31493.2 31699.2 54687.7 55186.0 55191.4 55228.2 55286.2 303808.0 305522.0 305989.0 306591.0 306721.0 36330.0 36567.0 36720.6 36893.4 36899.3 48060.8 48136.5 49126.8 49328.5 49388.5 40265.6 40893.8 40924.6 41211.0 42001.0 40364.4 40858.6 41055.2 41335.6 41412.6 39429.4 40278.0 40280.8 40662.2 40761.0 35042.3 35322.9 35805.0 35945.8 36363.6 27491.6 27971.9 28076.2 28173.6 28231.3 03:48:12 INFO - 2016-01-04 03:48:12,243 DEBUG : BROWSER_OUTPUT: 03:48:12 INFO - 2016-01-04 03:48:12,244 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28808.22 Median:4628.66 stddev:93626.95 (2022.8%) stddev-sans-first:93865.24 03:48:12 INFO - 2016-01-04 03:48:12,245 DEBUG : BROWSER_OUTPUT: Values: 367.5 369.1 369.2 372.8 373.3 12252.1 12644.6 14043.2 14441.4 18328.8 13365.5 13435.1 13632.4 13800.7 13833.4 3869.2 4835.4 4872.1 4979.3 5007.0 3959.1 5249.4 5312.6 5315.0 5316.9 5167.2 5231.0 5233.8 5240.5 5312.4 4543.8 5346.9 5355.2 5356.7 5357.9 4591.1 4666.3 4671.5 4675.7 4677.9 15395.9 15406.1 16019.0 16106.8 16138.2 2221.4 2221.5 2229.2 2240.9 2413.9 11131.4 11173.8 11397.9 11400.7 11534.5 1813.3 1825.8 1833.1 1868.1 1888.9 67560.0 68265.7 69177.7 69627.0 69925.7 7656.3 7811.6 7993.0 8048.3 8061.4 9660.7 9668.1 9682.3 9700.1 9702.9 6521.0 6563.2 6587.5 6613.8 6625.3 7023.9 7024.5 7034.8 7045.8 7058.4 3477.8 3644.2 3680.1 3698.6 3716.3 1249.6 1258.2 1267.9 1295.0 1298.1 520.2 536.2 541.1 546.4 582.8 13635.6 13638.9 13762.9 14069.2 14103.0 565.7 570.5 572.0 587.6 601.4 7417.4 7447.3 7481.9 7497.7 7620.2 404185.0 414293.0 648279.0 651698.0 667105.0 80673.6 81543.6 82999.6 84966.0 85673.1 598.0 611.7 612.1 612.2 612.7 12037.3 12215.9 12223.4 12230.7 12252.8 40109.0 40309.4 40396.6 40843.8 41402.4 50701.7 53615.7 54192.7 55559.7 55781.0 7505.6 7605.3 7651.7 7688.4 7746.1 13637.3 13806.6 13844.3 14026.4 14079.3 11351.3 11649.8 11680.4 11798.1 12219.3 11337.5 11609.1 11751.8 12060.3 12347.3 12250.9 12343.5 12401.2 12498.6 12500.0 7735.9 7773.7 7841.9 7880.4 7884.9 11976.9 12025.9 12175.4 12299.1 12604.9 03:48:12 INFO - 2016-01-04 03:48:12,245 DEBUG : BROWSER_OUTPUT: 03:48:12 INFO - 2016-01-04 03:48:12,245 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5671.70 Median:6826.19 stddev:2891.43 (42.4%) stddev-sans-first:2883.14 03:48:12 INFO - 2016-01-04 03:48:12,245 DEBUG : BROWSER_OUTPUT: Values: 1574.6 1578.5 1833.5 1854.7 1859.7 7789.4 7920.8 7923.1 7938.0 7939.4 7157.4 7345.4 7546.2 7773.6 8079.7 2310.9 2373.0 2385.0 2396.9 2397.3 2141.1 2416.3 2430.1 2465.5 2473.7 2061.8 2317.4 2427.2 2430.6 2432.0 2358.2 2397.8 2399.6 2401.1 2403.4 2386.2 2391.4 2402.2 2403.5 2403.5 7974.0 8058.8 8065.3 8069.2 8070.5 4801.2 4851.2 4862.0 4864.1 4885.7 3230.1 3253.8 3260.7 3261.6 3290.1 1067.6 1079.0 1119.0 2039.6 2092.1 10763.1 10833.7 10842.5 10849.1 10861.9 4325.3 4375.9 4439.5 4443.5 4447.9 8889.6 8891.1 8894.3 8898.4 8900.0 4974.4 4985.9 4988.6 4997.8 5017.9 4586.3 4606.9 4609.3 4623.4 4634.2 3708.7 3721.0 3721.0 3729.2 3742.2 1069.4 1078.3 1132.3 1136.3 1140.8 1159.8 1173.8 1224.2 1432.4 1451.3 7749.7 7868.3 7870.8 7882.8 7885.7 780.5 782.3 791.3 802.1 895.6 6110.8 6187.1 6196.2 6204.4 6209.2 9408.1 9868.9 9885.7 9889.6 9904.4 9719.3 9724.0 9730.8 9744.8 9768.8 7367.8 7560.7 7562.3 7580.9 7588.1 7414.1 7450.6 7455.3 7468.6 7482.1 8760.0 8772.9 8881.8 8908.5 8921.1 8696.4 8888.7 8928.5 8936.6 9154.9 6921.1 7023.1 7028.8 7032.9 7038.2 7551.8 7674.4 7701.6 7707.8 7862.6 6805.8 7332.9 7403.0 7409.5 7470.3 6916.4 7320.5 7342.8 7398.4 7445.9 7485.0 7511.8 7522.1 7524.1 7524.6 6695.8 6725.6 6736.5 6758.8 6789.4 6846.6 6899.8 6901.6 6967.6 6970.5 03:48:12 INFO - 2016-01-04 03:48:12,245 DEBUG : BROWSER_OUTPUT: 03:48:12 INFO - 2016-01-04 03:48:12,245 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5260.35 Median:5973.28 stddev:1558.26 (26.1%) stddev-sans-first:1518.48 03:48:12 INFO - 2016-01-04 03:48:12,245 DEBUG : BROWSER_OUTPUT: Values: 353.0 354.7 359.8 361.9 364.4 5871.3 5874.4 5887.4 5891.0 5910.7 5611.1 5685.8 5712.9 5799.4 5805.4 4544.1 4567.4 4577.7 4580.7 4583.4 4557.8 4559.5 4568.3 4571.0 4575.9 4369.7 4441.1 4482.4 4543.7 4545.3 4529.1 4534.9 4545.6 4548.7 4553.5 3986.4 4027.0 4028.0 4034.3 4044.2 5527.0 5539.4 5540.6 5544.1 5554.7 5161.7 5285.1 5323.1 5338.4 5343.0 6222.1 6244.3 6263.2 6264.0 6266.8 4496.3 4500.4 4501.2 4501.6 4503.4 6285.7 6411.1 6448.2 6462.7 6472.4 4470.7 4499.2 4501.3 4501.5 4502.1 4551.7 4558.8 4559.1 4559.5 4561.8 5210.7 5213.3 5214.3 5216.0 5219.9 6132.9 6215.2 6226.5 6227.4 6304.8 1623.0 1636.5 1637.2 1644.8 1645.2 1105.3 1109.3 1109.9 1127.7 1128.4 6078.5 6082.5 6088.1 6093.0 6093.2 6456.5 6472.6 6478.5 6479.8 6491.3 5933.7 6012.8 6023.2 6115.2 6115.6 3496.2 3530.7 3538.2 3557.8 3581.2 6552.6 6560.0 6593.3 6629.6 6632.7 6481.1 6557.9 6571.2 6589.9 6610.9 6658.2 6681.8 6683.5 6706.4 6707.3 6491.4 6498.3 6515.9 6526.3 6529.2 6353.4 6420.6 6439.0 6440.6 6450.7 6698.7 6701.2 6705.4 6706.5 6709.1 4530.7 4532.2 4533.8 4538.2 4538.8 6378.4 6469.7 6500.2 6507.7 6515.6 6311.6 6319.5 6320.2 6322.3 6322.3 6272.7 6283.6 6293.4 6294.4 6303.5 6042.8 6158.9 6181.9 6186.1 6198.9 6325.9 6342.1 6399.6 6405.8 6419.8 6176.2 6191.3 6212.6 6222.5 6229.8 03:48:12 INFO - 2016-01-04 03:48:12,245 DEBUG : BROWSER_OUTPUT: 03:48:12 INFO - 2016-01-04 03:48:12,245 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15626.65 Median:489.31 stddev:19045.39 (3892.3%) stddev-sans-first:19066.99 03:48:12 INFO - 2016-01-04 03:48:12,245 DEBUG : BROWSER_OUTPUT: Values: 964.8 964.8 969.6 979.3 988.4 14297.1 14474.8 14587.5 14673.9 14711.3 14480.8 14948.0 14969.1 14976.9 14997.6 783.8 785.1 929.6 952.2 967.1 869.5 943.3 946.5 953.2 955.0 828.9 982.9 991.3 995.1 995.5 838.4 980.2 986.6 988.5 989.2 727.5 731.9 733.3 734.1 734.6 14476.1 14620.0 14625.9 14641.9 14644.5 13286.4 13730.7 13770.1 13840.7 14103.1 11703.5 11763.2 11789.6 11803.6 12001.6 3590.3 3605.8 3619.3 3625.6 3694.6 52928.5 53354.2 53383.5 53907.2 54160.5 8567.1 8574.3 8648.4 8698.0 8773.6 14437.6 14440.6 14451.0 14484.7 14488.5 7606.8 7642.8 7667.8 7671.9 7844.6 4270.6 4405.8 4448.1 4472.2 4538.8 3148.9 3175.0 3175.4 3178.2 3178.7 476.0 481.0 482.9 489.1 489.5 696.2 723.0 757.3 762.7 789.5 9461.2 9628.4 9708.5 9725.4 9748.3 562.4 584.6 660.3 700.9 731.4 4568.2 4745.0 4852.1 4987.9 5013.1 68198.3 69476.7 69592.3 69890.3 70076.0 60177.1 61490.7 61881.7 61952.0 62980.0 55213.7 55645.7 55996.4 56795.7 57300.7 9917.6 10131.3 10173.1 10202.3 10316.0 39550.4 40892.4 40903.4 41169.2 41182.8 48927.3 51105.7 55123.4 55500.2 55706.2 8847.7 9022.6 9027.7 9199.0 9288.2 13312.9 13761.0 13832.9 13992.7 14043.9 12866.9 12979.5 13495.5 13495.5 14134.0 12805.5 12863.7 12992.5 13264.8 13540.5 12681.6 12690.0 12694.8 12867.1 12885.4 10690.7 10892.7 10902.8 10997.7 11244.6 10685.3 10947.1 11090.6 11208.3 11290.6 03:48:12 INFO - 2016-01-04 03:48:12,246 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 03:48:12 INFO - 2016-01-04 03:48:12,246 DEBUG : BROWSER_OUTPUT: 03:48:12 INFO - 2016-01-04 03:48:12,797 INFO : Browser exited with error code: 0 03:48:12 INFO - 2016-01-04 03:48:12,813 INFO : Completed test dromaeo_css (00:18:29) 03:48:12 INFO - 2016-01-04 03:48:12,814 INFO : Starting test kraken 03:48:12 INFO - 2016-01-04 03:48:12,814 DEBUG : operating with platform_type : linux_ 03:48:12 INFO - 2016-01-04 03:48:12,814 INFO : Initialising browser for kraken test... 03:48:12 INFO - 2016-01-04 03:48:12,820 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpHsC1xA/profile http://localhost:35800/getInfo.html 03:48:16 INFO - 2016-01-04 03:48:16,070 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:48:16 INFO - 2016-01-04 03:48:16,070 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:48:16 INFO - 2016-01-04 03:48:16,070 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 03:48:16 INFO - 2016-01-04 03:48:16,071 DEBUG : BROWSER_OUTPUT: __metrics 03:48:16 INFO - 2016-01-04 03:48:16,173 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] 03:48:17 INFO - 2016-01-04 03:48:17,393 INFO : Browser initialized. 03:48:17 INFO - 2016-01-04 03:48:17,394 INFO : Running cycle 1/1 for kraken test... 03:48:17 INFO - 2016-01-04 03:48:17,394 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpHsC1xA/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:48:28 INFO - 2016-01-04 03:48:28,910 DEBUG : BROWSER_OUTPUT: RSS: Main: 128475136 03:48:28 INFO - 2016-01-04 03:48:28,910 DEBUG : BROWSER_OUTPUT: 03:48:31 INFO - 2016-01-04 03:48:31,332 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35800/tests/kraken/driver.html?testName=audio-beat-detection) 03:48:31 INFO - 2016-01-04 03:48:31,906 DEBUG : BROWSER_OUTPUT: RSS: Main: 163639296 03:48:31 INFO - 2016-01-04 03:48:31,906 DEBUG : BROWSER_OUTPUT: 03:48:34 INFO - 2016-01-04 03:48:34,435 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35800/tests/kraken/driver.html?testName=audio-dft) 03:48:35 INFO - 2016-01-04 03:48:35,008 DEBUG : BROWSER_OUTPUT: RSS: Main: 162078720 03:48:35 INFO - 2016-01-04 03:48:35,008 DEBUG : BROWSER_OUTPUT: 03:48:37 INFO - 2016-01-04 03:48:37,791 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35800/tests/kraken/driver.html?testName=audio-fft) 03:48:38 INFO - 2016-01-04 03:48:38,358 DEBUG : BROWSER_OUTPUT: RSS: Main: 158580736 03:48:38 INFO - 2016-01-04 03:48:38,358 DEBUG : BROWSER_OUTPUT: 03:48:40 INFO - 2016-01-04 03:48:40,323 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35800/tests/kraken/driver.html?testName=audio-oscillator) 03:48:40 INFO - 2016-01-04 03:48:40,885 DEBUG : BROWSER_OUTPUT: RSS: Main: 159272960 03:48:40 INFO - 2016-01-04 03:48:40,885 DEBUG : BROWSER_OUTPUT: 03:48:43 INFO - 2016-01-04 03:48:43,039 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35800/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:48:43 INFO - 2016-01-04 03:48:43,600 DEBUG : BROWSER_OUTPUT: RSS: Main: 156835840 03:48:43 INFO - 2016-01-04 03:48:43,600 DEBUG : BROWSER_OUTPUT: 03:48:46 INFO - 2016-01-04 03:48:46,447 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35800/tests/kraken/driver.html?testName=imaging-darkroom) 03:48:47 INFO - 2016-01-04 03:48:47,013 DEBUG : BROWSER_OUTPUT: RSS: Main: 157163520 03:48:47 INFO - 2016-01-04 03:48:47,013 DEBUG : BROWSER_OUTPUT: 03:48:50 INFO - 2016-01-04 03:48:50,320 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35800/tests/kraken/driver.html?testName=imaging-desaturate) 03:48:50 INFO - 2016-01-04 03:48:50,887 DEBUG : BROWSER_OUTPUT: RSS: Main: 157851648 03:48:50 INFO - 2016-01-04 03:48:50,887 DEBUG : BROWSER_OUTPUT: 03:48:53 INFO - 2016-01-04 03:48:53,626 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35800/tests/kraken/driver.html?testName=json-parse-financial) 03:48:54 INFO - 2016-01-04 03:48:54,192 DEBUG : BROWSER_OUTPUT: RSS: Main: 158638080 03:48:54 INFO - 2016-01-04 03:48:54,192 DEBUG : BROWSER_OUTPUT: 03:48:55 INFO - 2016-01-04 03:48:55,701 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35800/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:48:56 INFO - 2016-01-04 03:48:56,255 DEBUG : BROWSER_OUTPUT: RSS: Main: 161374208 03:48:56 INFO - 2016-01-04 03:48:56,255 DEBUG : BROWSER_OUTPUT: 03:48:57 INFO - 2016-01-04 03:48:57,989 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35800/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:48:58 INFO - 2016-01-04 03:48:58,584 DEBUG : BROWSER_OUTPUT: RSS: Main: 159911936 03:48:58 INFO - 2016-01-04 03:48:58,584 DEBUG : BROWSER_OUTPUT: 03:49:00 INFO - 2016-01-04 03:49:00,544 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35800/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:49:01 INFO - 2016-01-04 03:49:01,112 DEBUG : BROWSER_OUTPUT: RSS: Main: 163889152 03:49:01 INFO - 2016-01-04 03:49:01,113 DEBUG : BROWSER_OUTPUT: 03:49:03 INFO - 2016-01-04 03:49:03,412 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35800/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:49:03 INFO - 2016-01-04 03:49:03,994 DEBUG : BROWSER_OUTPUT: RSS: Main: 168579072 03:49:03 INFO - 2016-01-04 03:49:03,994 DEBUG : BROWSER_OUTPUT: 03:49:06 INFO - 2016-01-04 03:49:06,421 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35800/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:49:07 INFO - 2016-01-04 03:49:07,003 DEBUG : BROWSER_OUTPUT: RSS: Main: 166498304 03:49:07 INFO - 2016-01-04 03:49:07,003 DEBUG : BROWSER_OUTPUT: 03:49:08 INFO - 2016-01-04 03:49:08,614 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35800/tests/kraken/driver.html?testName=ai-astar) 03:49:08 INFO - 2016-01-04 03:49:08,860 DEBUG : BROWSER_OUTPUT: RSS: Main: 226439168 03:49:08 INFO - 2016-01-04 03:49:08,860 DEBUG : BROWSER_OUTPUT: 03:49:08 INFO - 2016-01-04 03:49:08,862 DEBUG : BROWSER_OUTPUT: __start_tp_report 03:49:08 INFO - 2016-01-04 03:49:08,862 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 03:49:08 INFO - 2016-01-04 03:49:08,862 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 03:49:08 INFO - 2016-01-04 03:49:08,863 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 03:49:08 INFO - 2016-01-04 03:49:08,863 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;109;103;110;110;109;99;109;106;108 03:49:08 INFO - 2016-01-04 03:49:08,863 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;144;121;147;165;129;146;142;131;155;122 03:49:08 INFO - 2016-01-04 03:49:08,864 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;160;161;162;159;159;160;164;160;159 03:49:08 INFO - 2016-01-04 03:49:08,864 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;70;71;139;73;72;71;72;168;71 03:49:08 INFO - 2016-01-04 03:49:08,864 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;89;131;89;89;89;134;89;89;140 03:49:08 INFO - 2016-01-04 03:49:08,864 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;110;110;110;109;109 03:49:08 INFO - 2016-01-04 03:49:08,865 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;145;145;147;145;146;146;147;147 03:49:08 INFO - 2016-01-04 03:49:08,865 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;95;95;96;95;96;95;95;96 03:49:08 INFO - 2016-01-04 03:49:08,865 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;52;54;55;54;53;54;57;54;52;54 03:49:08 INFO - 2016-01-04 03:49:08,866 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;62;63;63;64;64;63;63;63;63 03:49:08 INFO - 2016-01-04 03:49:08,866 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;77;78;79;78;92;85;78;77;85 03:49:08 INFO - 2016-01-04 03:49:08,866 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;107;110;108;112;121;108;107;130;113;113 03:49:08 INFO - 2016-01-04 03:49:08,866 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;136;138;137;141;135;137;140;142;134;136 03:49:08 INFO - 2016-01-04 03:49:08,867 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;60;61;60;61;63;61;61;62;61;63 03:49:08 INFO - 2016-01-04 03:49:08,867 DEBUG : BROWSER_OUTPUT: __end_tp_report 03:49:08 INFO - 2016-01-04 03:49:08,867 DEBUG : BROWSER_OUTPUT: __start_cc_report 03:49:08 INFO - 2016-01-04 03:49:08,867 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,885 03:49:08 INFO - 2016-01-04 03:49:08,868 DEBUG : BROWSER_OUTPUT: __end_cc_report 03:49:08 INFO - 2016-01-04 03:49:08,868 DEBUG : BROWSER_OUTPUT: __startTimestamp1451908148861__endTimestamp 03:49:08 INFO - 2016-01-04 03:49:08,868 DEBUG : BROWSER_OUTPUT: 03:49:08 INFO - 2016-01-04 03:49:08,868 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 03:49:08 INFO - 2016-01-04 03:49:08,870 DEBUG : BROWSER_OUTPUT: Number of tests: 14 03:49:08 INFO - 2016-01-04 03:49:08,870 DEBUG : BROWSER_OUTPUT: 03:49:08 INFO - 2016-01-04 03:49:08,870 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.00 Median:109.00 stddev:3.53 (3.2%) stddev-sans-first:3.74 03:49:08 INFO - 2016-01-04 03:49:08,870 DEBUG : BROWSER_OUTPUT: Values: 107.0 109.0 103.0 110.0 110.0 109.0 99.0 109.0 106.0 108.0 03:49:08 INFO - 2016-01-04 03:49:08,870 DEBUG : BROWSER_OUTPUT: 03:49:08 INFO - 2016-01-04 03:49:08,870 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.20 Median:145.00 stddev:14.30 (9.9%) stddev-sans-first:15.11 03:49:08 INFO - 2016-01-04 03:49:08,871 DEBUG : BROWSER_OUTPUT: Values: 144.0 121.0 147.0 165.0 129.0 146.0 142.0 131.0 155.0 122.0 03:49:08 INFO - 2016-01-04 03:49:08,871 DEBUG : BROWSER_OUTPUT: 03:49:08 INFO - 2016-01-04 03:49:08,871 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.60 Median:160.50 stddev:1.65 (1.0%) stddev-sans-first:1.67 03:49:08 INFO - 2016-01-04 03:49:08,871 DEBUG : BROWSER_OUTPUT: Values: 162.0 160.0 161.0 162.0 159.0 159.0 160.0 164.0 160.0 159.0 03:49:08 INFO - 2016-01-04 03:49:08,871 DEBUG : BROWSER_OUTPUT: 03:49:08 INFO - 2016-01-04 03:49:08,871 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.70 Median:71.00 stddev:35.36 (49.8%) stddev-sans-first:36.92 03:49:08 INFO - 2016-01-04 03:49:08,871 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 71.0 139.0 73.0 72.0 71.0 72.0 168.0 71.0 03:49:08 INFO - 2016-01-04 03:49:08,871 DEBUG : BROWSER_OUTPUT: 03:49:08 INFO - 2016-01-04 03:49:08,871 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.90 Median:89.00 stddev:22.26 (25.0%) stddev-sans-first:23.11 03:49:08 INFO - 2016-01-04 03:49:08,872 DEBUG : BROWSER_OUTPUT: Values: 90.0 89.0 131.0 89.0 89.0 89.0 134.0 89.0 89.0 140.0 03:49:08 INFO - 2016-01-04 03:49:08,872 DEBUG : BROWSER_OUTPUT: 03:49:08 INFO - 2016-01-04 03:49:08,872 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 03:49:08 INFO - 2016-01-04 03:49:08,872 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 110.0 110.0 110.0 109.0 109.0 03:49:08 INFO - 2016-01-04 03:49:08,872 DEBUG : BROWSER_OUTPUT: 03:49:08 INFO - 2016-01-04 03:49:08,872 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 03:49:08 INFO - 2016-01-04 03:49:08,872 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 145.0 145.0 147.0 145.0 146.0 146.0 147.0 147.0 03:49:08 INFO - 2016-01-04 03:49:08,872 DEBUG : BROWSER_OUTPUT: 03:49:08 INFO - 2016-01-04 03:49:08,872 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 03:49:08 INFO - 2016-01-04 03:49:08,873 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 95.0 95.0 96.0 95.0 96.0 95.0 95.0 96.0 03:49:08 INFO - 2016-01-04 03:49:08,873 DEBUG : BROWSER_OUTPUT: 03:49:08 INFO - 2016-01-04 03:49:08,873 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:1.45 (2.7%) stddev-sans-first:1.36 03:49:08 INFO - 2016-01-04 03:49:08,873 DEBUG : BROWSER_OUTPUT: Values: 52.0 54.0 55.0 54.0 53.0 54.0 57.0 54.0 52.0 54.0 03:49:08 INFO - 2016-01-04 03:49:08,873 DEBUG : BROWSER_OUTPUT: 03:49:08 INFO - 2016-01-04 03:49:08,873 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.10 Median:63.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 03:49:08 INFO - 2016-01-04 03:49:08,873 DEBUG : BROWSER_OUTPUT: Values: 63.0 62.0 63.0 63.0 64.0 64.0 63.0 63.0 63.0 63.0 03:49:08 INFO - 2016-01-04 03:49:08,873 DEBUG : BROWSER_OUTPUT: 03:49:08 INFO - 2016-01-04 03:49:08,873 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:78.50 stddev:5.15 (6.6%) stddev-sans-first:5.20 03:49:08 INFO - 2016-01-04 03:49:08,874 DEBUG : BROWSER_OUTPUT: Values: 76.0 77.0 78.0 79.0 78.0 92.0 85.0 78.0 77.0 85.0 03:49:08 INFO - 2016-01-04 03:49:08,874 DEBUG : BROWSER_OUTPUT: 03:49:08 INFO - 2016-01-04 03:49:08,874 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:112.90 Median:112.50 stddev:7.34 (6.5%) stddev-sans-first:7.47 03:49:08 INFO - 2016-01-04 03:49:08,874 DEBUG : BROWSER_OUTPUT: Values: 107.0 110.0 108.0 112.0 121.0 108.0 107.0 130.0 113.0 113.0 03:49:08 INFO - 2016-01-04 03:49:08,874 DEBUG : BROWSER_OUTPUT: 03:49:08 INFO - 2016-01-04 03:49:08,874 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.60 Median:137.50 stddev:2.63 (1.9%) stddev-sans-first:2.73 03:49:08 INFO - 2016-01-04 03:49:08,874 DEBUG : BROWSER_OUTPUT: Values: 136.0 138.0 137.0 141.0 135.0 137.0 140.0 142.0 134.0 136.0 03:49:08 INFO - 2016-01-04 03:49:08,874 DEBUG : BROWSER_OUTPUT: 03:49:08 INFO - 2016-01-04 03:49:08,874 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.30 Median:61.00 stddev:1.06 (1.7%) stddev-sans-first:1.01 03:49:08 INFO - 2016-01-04 03:49:08,875 DEBUG : BROWSER_OUTPUT: Values: 60.0 61.0 60.0 61.0 63.0 61.0 61.0 62.0 61.0 63.0 03:49:08 INFO - 2016-01-04 03:49:08,875 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 03:49:08 INFO - 2016-01-04 03:49:08,875 DEBUG : BROWSER_OUTPUT: 03:49:09 INFO - 2016-01-04 03:49:09,517 INFO : Browser exited with error code: 0 03:49:09 INFO - 2016-01-04 03:49:09,569 INFO : Completed test kraken (00:00:56) 03:49:09 INFO - 2016-01-04 03:49:09,569 INFO : Starting test v8_7 03:49:09 INFO - 2016-01-04 03:49:09,569 DEBUG : operating with platform_type : linux_ 03:49:09 INFO - 2016-01-04 03:49:09,569 INFO : Initialising browser for v8_7 test... 03:49:09 INFO - 2016-01-04 03:49:09,575 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpQ4kYxA/profile http://localhost:35800/getInfo.html 03:49:12 INFO - 2016-01-04 03:49:12,887 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:49:12 INFO - 2016-01-04 03:49:12,888 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:49:12 INFO - 2016-01-04 03:49:12,888 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 03:49:12 INFO - 2016-01-04 03:49:12,888 DEBUG : BROWSER_OUTPUT: __metrics 03:49:13 INFO - 2016-01-04 03:49:13,033 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] 03:49:14 INFO - 2016-01-04 03:49:14,185 INFO : Browser initialized. 03:49:14 INFO - 2016-01-04 03:49:14,186 INFO : Running cycle 1/1 for v8_7 test... 03:49:14 INFO - 2016-01-04 03:49:14,186 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpQ4kYxA/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:49:25 INFO - 2016-01-04 03:49:25,792 DEBUG : BROWSER_OUTPUT: RSS: Main: 128659456 03:49:25 INFO - 2016-01-04 03:49:25,793 DEBUG : BROWSER_OUTPUT: 03:49:47 INFO - 2016-01-04 03:49:47,130 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35800/tests/v8_7/run.html (next: http://localhost:35800/tests/v8_7/run.html) 03:49:47 INFO - 2016-01-04 03:49:47,375 DEBUG : BROWSER_OUTPUT: RSS: Main: 418439168 03:49:47 INFO - 2016-01-04 03:49:47,375 DEBUG : BROWSER_OUTPUT: 03:49:47 INFO - 2016-01-04 03:49:47,375 DEBUG : BROWSER_OUTPUT: __start_tp_report 03:49:47 INFO - 2016-01-04 03:49:47,375 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 03:49:47 INFO - 2016-01-04 03:49:47,375 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 03:49:47 INFO - 2016-01-04 03:49:47,375 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 03:49:47 INFO - 2016-01-04 03:49:47,375 DEBUG : BROWSER_OUTPUT: |0;Richards;166.88918558077435 03:49:47 INFO - 2016-01-04 03:49:47,375 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;169.7792869269949 03:49:47 INFO - 2016-01-04 03:49:47,375 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.3296089385475 03:49:47 INFO - 2016-01-04 03:49:47,376 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5010 03:49:47 INFO - 2016-01-04 03:49:47,376 DEBUG : BROWSER_OUTPUT: |4;RayTrace;946.073793755913 03:49:47 INFO - 2016-01-04 03:49:47,376 DEBUG : BROWSER_OUTPUT: |5;Earley;553.7098560354374 03:49:47 INFO - 2016-01-04 03:49:47,376 DEBUG : BROWSER_OUTPUT: |6;Boyer;10489.583333333334 03:49:47 INFO - 2016-01-04 03:49:47,376 DEBUG : BROWSER_OUTPUT: |7;RegExp;23976.190476190477 03:49:47 INFO - 2016-01-04 03:49:47,376 DEBUG : BROWSER_OUTPUT: |8;Splay;538.2131324004306 03:49:47 INFO - 2016-01-04 03:49:47,376 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4817.307692307692 03:49:47 INFO - 2016-01-04 03:49:47,376 DEBUG : BROWSER_OUTPUT: __end_tp_report 03:49:47 INFO - 2016-01-04 03:49:47,376 DEBUG : BROWSER_OUTPUT: __startTimestamp1451908187374__endTimestamp 03:49:47 INFO - 2016-01-04 03:49:47,376 DEBUG : BROWSER_OUTPUT: 03:49:47 INFO - 2016-01-04 03:49:47,376 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 03:49:47 INFO - 2016-01-04 03:49:47,376 DEBUG : BROWSER_OUTPUT: Number of tests: 10 03:49:47 INFO - 2016-01-04 03:49:47,377 DEBUG : BROWSER_OUTPUT: 03:49:47 INFO - 2016-01-04 03:49:47,377 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.89 Median:166.89 stddev:0.00 (0.0%) 03:49:47 INFO - 2016-01-04 03:49:47,377 DEBUG : BROWSER_OUTPUT: Values: 166.9 03:49:47 INFO - 2016-01-04 03:49:47,377 DEBUG : BROWSER_OUTPUT: 03:49:47 INFO - 2016-01-04 03:49:47,377 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:169.78 Median:169.78 stddev:0.00 (0.0%) 03:49:47 INFO - 2016-01-04 03:49:47,377 DEBUG : BROWSER_OUTPUT: Values: 169.8 03:49:47 INFO - 2016-01-04 03:49:47,377 DEBUG : BROWSER_OUTPUT: 03:49:47 INFO - 2016-01-04 03:49:47,378 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.33 Median:279.33 stddev:0.00 (0.0%) 03:49:47 INFO - 2016-01-04 03:49:47,378 DEBUG : BROWSER_OUTPUT: Values: 279.3 03:49:47 INFO - 2016-01-04 03:49:47,378 DEBUG : BROWSER_OUTPUT: 03:49:47 INFO - 2016-01-04 03:49:47,378 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5010.00 Median:5010.00 stddev:0.00 (0.0%) 03:49:47 INFO - 2016-01-04 03:49:47,378 DEBUG : BROWSER_OUTPUT: Values: 5010.0 03:49:47 INFO - 2016-01-04 03:49:47,379 DEBUG : BROWSER_OUTPUT: 03:49:47 INFO - 2016-01-04 03:49:47,379 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:946.07 Median:946.07 stddev:0.00 (0.0%) 03:49:47 INFO - 2016-01-04 03:49:47,379 DEBUG : BROWSER_OUTPUT: Values: 946.1 03:49:47 INFO - 2016-01-04 03:49:47,379 DEBUG : BROWSER_OUTPUT: 03:49:47 INFO - 2016-01-04 03:49:47,379 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:553.71 Median:553.71 stddev:0.00 (0.0%) 03:49:47 INFO - 2016-01-04 03:49:47,380 DEBUG : BROWSER_OUTPUT: Values: 553.7 03:49:47 INFO - 2016-01-04 03:49:47,380 DEBUG : BROWSER_OUTPUT: 03:49:47 INFO - 2016-01-04 03:49:47,380 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10489.58 Median:10489.58 stddev:0.00 (0.0%) 03:49:47 INFO - 2016-01-04 03:49:47,380 DEBUG : BROWSER_OUTPUT: Values: 10489.6 03:49:47 INFO - 2016-01-04 03:49:47,380 DEBUG : BROWSER_OUTPUT: 03:49:47 INFO - 2016-01-04 03:49:47,380 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23976.19 Median:23976.19 stddev:0.00 (0.0%) 03:49:47 INFO - 2016-01-04 03:49:47,382 DEBUG : BROWSER_OUTPUT: Values: 23976.2 03:49:47 INFO - 2016-01-04 03:49:47,382 DEBUG : BROWSER_OUTPUT: 03:49:47 INFO - 2016-01-04 03:49:47,382 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:538.21 Median:538.21 stddev:0.00 (0.0%) 03:49:47 INFO - 2016-01-04 03:49:47,382 DEBUG : BROWSER_OUTPUT: Values: 538.2 03:49:47 INFO - 2016-01-04 03:49:47,382 DEBUG : BROWSER_OUTPUT: 03:49:47 INFO - 2016-01-04 03:49:47,382 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4817.31 Median:4817.31 stddev:0.00 (0.0%) 03:49:47 INFO - 2016-01-04 03:49:47,382 DEBUG : BROWSER_OUTPUT: Values: 4817.3 03:49:47 INFO - 2016-01-04 03:49:47,383 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 03:49:47 INFO - 2016-01-04 03:49:47,383 DEBUG : BROWSER_OUTPUT: 03:49:48 INFO - 2016-01-04 03:49:48,118 INFO : Browser exited with error code: 0 03:49:48 INFO - 2016-01-04 03:49:48,135 INFO : Completed test v8_7 (00:00:38) 03:49:48 INFO - 2016-01-04 03:49:48,451 INFO : Completed test suite (00:20:04) 03:49:48 INFO - 2016-01-04 03:49:48,451 DEBUG : Working with test: dromaeo_css 03:49:48 INFO - 2016-01-04 03:49:48,451 DEBUG : Generating results file: dromaeo_css 03:49:48 INFO - 2016-01-04 03:49:48,453 DEBUG : Working with test: kraken 03:49:48 INFO - 2016-01-04 03:49:48,453 DEBUG : Generating results file: kraken 03:49:48 INFO - 2016-01-04 03:49:48,454 INFO : javascript benchmark 03:49:48 INFO - 2016-01-04 03:49:48,454 DEBUG : Working with test: v8_7 03:49:48 INFO - 2016-01-04 03:49:48,454 DEBUG : Generating results file: v8_7 03:49:48 INFO - 2016-01-04 03:49:48,455 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:49:48 INFO - 2016-01-04 03:49:48,624 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 03:49:48 INFO - 2016-01-04 03:49:48,624 DEBUG : process_Request line: dromaeo_css 7201.48 graph.html#tests=[[72,53,35]] 03:49:48 INFO - 2016-01-04 03:49:48,624 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:49:48 INFO - 2016-01-04 03:49:48,660 DEBUG : process_Request line: kraken 1458.30 graph.html#tests=[[232,53,35]] 03:49:48 INFO - 2016-01-04 03:49:48,660 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:49:48 INFO - 2016-01-04 03:49:48,694 DEBUG : process_Request line: v8_7 23237.24 graph.html#tests=[[230,53,35]] 03:49:48 INFO - 2016-01-04 03:49:48,695 INFO : javascript benchmark 03:49:48 INFO - 2016-01-04 03:49:48,698 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3315.8342082895856, 3396.0830575744567, 3414.3854106260865, 3424.5376874121985, 3442.483627455881, 5674.971625141874, 5700.8859822803515, 5714.685692286927, 5725.398833122519, 5725.4847063764255, 5711.514521265687, 5738.110592858103, 5741.598088133832, 5748.913766293498, 5757.510611598019, 4975.601951843843, 5181.067407866573, 5206.661566998152, 5273.0, 5305.124654432014, 5027.547520723152, 5046.5710414614805, 5110.054639891613, 5184.922226166592, 5200.115980283342, 5206.088934436478, 5243.0, 5249.081410753122, 5249.606279529032, 5272.446393128724, 4935.407751069859, 5121.539061484447, 5140.871478213037, 5233.947660523403, 5237.162054071349, 4955.083309587738, 5049.949500504985, 5072.594192464594, 5073.771680274396, 5111.642185047048, 5484.287042684466, 5487.286652735119, 5505.972470137664, 5508.256385387961, 5511.696856672881, 5911.763529458816, 6023.397660233985, 6038.879222415571, 6042.486388656969, 6043.3956604339655, 6018.458338749533, 6084.5132389408745, 6088.269407671108, 6112.63324200545, 6116.571839971204, 5636.71816409182, 5732.197492351074, 5738.254026976508, 5745.971270143623, 5754.884902301972, 5943.791967281124, 6058.1518587397795, 6110.541709371815, 6223.190985171898, 6241.937580624226, 6026.427489388501, 6062.757489700362, 6068.908966365508, 6083.543734219951, 6091.2081429414775, 6166.506679465632, 6176.752929882754, 6183.505319574423, 6184.381561843779, 6188.659623720739, 5853.970730146407, 5947.167396564398, 5969.582129250997, 6120.66336351496, 6128.754849806047, 6104.633721976696, 6120.479759220427, 6129.478994285536, 6129.877402451926, 6133.662648554283, 4686.671932964659, 4690.437147542317, 4690.906181876343, 4698.083873644607, 4724.960508688083, 4041.6362527372676, 4106.404571337199, 4120.7321524100835, 4200.85297014603, 4206.053637931441, 5791.942080579224, 5821.505172060422, 5828.0, 5842.795502157404, 5844.853878653068, 6134.723937422827, 6155.784547540815, 6157.230346206724, 6161.938380616137, 6162.476189523851, 5457.808976685797, 5661.405552417019, 5722.856928576819, 5753.798617048383, 5754.971225143931, 5292.52367286946, 5326.121190003683, 5341.252224688546, 5342.198670199501, 5349.81275655358, 6044.455998960027, 6304.495640348761, 6309.968450157719, 6313.116163737081, 6320.336364681734, 6039.607425517327, 6055.333913269449, 6138.324784273637, 6141.570090093651, 6152.938470615237, 6258.155149054819, 6262.937370626236, 6273.341299163522, 6307.085472606538, 6309.3690630936535, 5859.677717725566, 5983.790567330123, 6037.516998640186, 6039.365866583946, 6040.335563088145, 6022.427869352405, 6128.295246046583, 6128.601640893327, 6129.08063790435, 6134.171886795404, 5970.31341395745, 6004.519638429003, 6021.367756385692, 6022.156898034191, 6025.0359942409, 5828.883422331614, 5943.049112142036, 5959.344472107978, 5992.610480318765, 6002.129691194839, 5850.824475265749, 5867.9413205867395, 5868.67722275279, 5876.44173803488, 5890.528757699288, 5908.911366329595, 5916.142159386951, 5964.373740757158, 5964.612300200473, 5968.731407086779, 5692.516136128475, 5761.193432919478, 5800.680962546931, 5837.387074357302, 5856.795012174553], "unit": "ms", "name": "dojo.html", "value": 5610.300198682945}, {"lowerIsBetter": false, "replicates": [603.9853046881241, 636.3890664961637, 637.9457746091582, 640.609228370694, 645.303072681504, 34885.651143488554, 44635.330470042936, 44857.77571112148, 44894.0, 44896.1020779585, 47624.52375476244, 48257.0, 48260.51739482604, 48270.75864620672, 48270.75864620672, 8151.06262779779, 8243.876341854877, 9358.438493690383, 9580.473073980927, 10737.570497180104, 11022.393768342738, 11126.443677816076, 11165.944170279137, 11176.497057632385, 11178.720531986684, 11093.72265693356, 11189.328640281608, 11218.0, 11221.551137954471, 11229.438528073604, 11087.057600104, 11183.440827958651, 11184.720381990433, 11185.272957257794, 11196.552137914474, 10943.343399396063, 11060.115190784718, 11070.889291107067, 11072.501737421835, 11077.723056923562, 44389.33415998763, 44495.33257001147, 44705.329420058726, 44779.776101119285, 44807.551924480664, 3740.0462881965195, 3787.3750831112834, 3834.8466061357512, 3870.767753934773, 4238.7456752594635, 33894.83052584721, 33922.83038584816, 33951.32097358064, 33991.0, 34048.31903361944, 3848.1726428817938, 3940.290747665419, 3968.72218944674, 4029.435878976946, 4100.0159961609, 329738.0, 331084.0, 331590.0, 332410.0, 333153.0, 58565.41434585685, 59141.0, 72897.63551182316, 72966.63516682382, 73000.0, 69481.0, 69832.6508367455, 70203.64898175477, 70306.29693702997, 70341.64829175822, 18613.06934653262, 18991.050447477846, 19029.14368853377, 19032.52418689544, 19042.619147617246, 13801.240931748851, 13847.792283115761, 13847.792283115761, 13959.79060314096, 14018.088824226392, 22069.227577035046, 22281.55436891253, 22329.3301200961, 22336.888315558317, 22457.10171593118, 8012.278894899487, 8253.257206851413, 8288.958555207186, 8326.542040187847, 8336.916630833735, 1068.1188019883627, 1101.9366311509273, 1107.9556817727362, 1138.9601363952388, 1173.7828501727208, 31334.373312534077, 31908.680913191034, 32027.35945281081, 32224.35551288961, 32917.50623740646, 1090.0734375780683, 1163.790517706821, 1211.0553768060927, 1211.5577814097792, 1245.0537591430577, 14610.488632897797, 16006.0, 16776.245068971926, 16881.577960551083, 17298.740518892228, 394614.0807887884, 396644.0, 400184.0, 400372.0, 401473.0, 52479.73760131251, 52822.0, 52887.73556132195, 52911.73544132255, 52949.735251323495, 1370.821793166882, 1405.5221224783418, 1477.697072100217, 1518.2560545332929, 1539.084244874298, 30923.536146958264, 30969.380612387176, 31160.844195779784, 31493.22792939795, 31699.20751981219, 54687.72656136694, 55186.0, 55191.448085520235, 55228.171577425564, 55286.17070744022, 303808.0, 305522.0, 305989.0, 306591.0, 306721.0, 36330.0, 36567.0, 36720.63279367279, 36893.44659830051, 36899.26201476009, 48060.7596962013, 48136.518634813205, 49126.75436622794, 49328.50671493239, 49388.50611493983, 40265.59734402736, 40893.795531023345, 40924.59075409327, 41211.0, 42001.0, 40364.394534081424, 40858.59141408548, 41055.178896421305, 41335.586644133175, 41412.585874140874, 39429.408558872216, 40278.0, 40280.79859600683, 40662.1867562653, 40761.0, 35042.29915401729, 35322.93786214618, 35805.0, 35945.820270898475, 36363.636363636026, 27491.587626186025, 27971.860140699166, 28076.157715268575, 28173.577396339482, 28231.294217644725], "unit": "ms", "name": "ext.html", "value": 21070.397051502754}, {"lowerIsBetter": false, "replicates": [367.5277268709708, 369.08651088555825, 369.2117329501514, 372.75770749013157, 373.26094333220226, 12252.142350035501, 12644.620661380173, 14043.22762248076, 14441.42234310629, 18328.81671183288, 13365.465381384758, 13435.126716763429, 13632.386542605582, 13800.723985520284, 13833.377498012589, 3869.1681288523105, 4835.419749630049, 4872.123017856784, 4979.278004689318, 5007.023630392075, 3959.148783011652, 5249.396319423258, 5312.574994000471, 5315.0, 5316.946830531684, 5167.224916262553, 5231.006108839328, 5233.842984710464, 5240.475952404748, 5312.415634280226, 4543.795529201194, 5346.893062138774, 5355.196720491918, 5356.678599284055, 5357.919631205535, 4591.058832939273, 4666.253399456071, 4671.462781780105, 4675.742834144121, 4677.88305292367, 15395.923020384938, 15406.075635461795, 16019.038857668576, 16106.791990600752, 16138.193090345436, 2221.3558068160214, 2221.466847956495, 2229.2420577003895, 2240.8543444676206, 2413.9034438622434, 11131.443427828657, 11173.776524469466, 11397.943010284978, 11400.71498212543, 11534.480948357344, 1813.2656274208966, 1825.8174182581768, 1833.1475863723433, 1868.1499917537487, 1888.9338873139511, 67560.0, 68265.65867170681, 69177.65411172963, 69627.0, 69925.65037174833, 7656.349210317186, 7811.648475818602, 7993.0, 8048.315893149031, 8061.355091592659, 9660.661876834278, 9668.129868311886, 9682.273829462818, 9700.126988571063, 9702.854457183148, 6520.967395162994, 6563.245226798884, 6587.473002159816, 6613.834654133589, 6625.271220165778, 7023.894641580278, 7024.5434046786795, 7034.788956331318, 7045.753398631024, 7058.435325173974, 3477.7739446935866, 3644.1618427761764, 3680.0799800049986, 3698.6116457772087, 3716.312482190803, 1249.6063739921894, 1258.169608058695, 1267.9492820287269, 1294.9805752913715, 1298.0718786068007, 520.2404489445377, 536.2358638939496, 541.0909671751417, 546.3580293155526, 582.8309790160806, 13635.590932272047, 13638.863611363959, 13762.862371376359, 14069.226192559507, 14103.0, 565.6888711208901, 570.5492660797923, 571.9542436605145, 587.5563949218313, 601.4196300569971, 7417.406607471389, 7447.329740323397, 7481.85036299271, 7497.66260518278, 7620.237976202447, 404185.0, 414293.0, 648279.0, 651698.0, 667105.0, 80673.59663201764, 81543.59228203939, 82999.5850020746, 84966.0, 85673.14326856652, 597.9970100149559, 611.6605284067297, 612.0604871522193, 612.185792895448, 612.745710530125, 12037.337946413032, 12215.938920305342, 12223.388830558615, 12230.694232644255, 12252.816207756712, 40109.0, 40309.379890307035, 40396.596034040456, 40843.795781020905, 41402.37896431496, 50701.74649126731, 53615.73192134171, 54192.72903635457, 55559.72220138874, 55781.0, 7505.587192704454, 7605.250792317687, 7651.655675494728, 7688.423368247516, 7746.070471543226, 13637.318134093453, 13806.585802425943, 13844.307784610528, 14026.438942442188, 14079.296035198404, 11351.318920864775, 11649.825252621047, 11680.357580333164, 11798.056155507367, 12219.327936963196, 11337.489812958602, 11609.129315301556, 11751.76496470083, 12060.336681482602, 12347.320897350732, 12250.877491224974, 12343.506259749509, 12401.193922395016, 12498.562550310695, 12500.0, 7735.9226407735205, 7773.727919522789, 7841.9215807843475, 7880.448368614142, 7884.921150788418, 11976.880231197576, 12025.939870300943, 12175.39123043862, 12299.077569182526, 12604.936975315064], "unit": "ms", "name": "jquery.html", "value": 7695.389067058041}, {"lowerIsBetter": false, "replicates": [1574.5906064423245, 1578.4712121439318, 1833.5416145963509, 1854.656888475632, 1859.7024476083825, 7789.415793815465, 7920.841583168341, 7923.088844782852, 7937.960310198441, 7939.444238903272, 7157.355837974581, 7345.41236701064, 7546.245375462451, 7773.611319434034, 8079.717209897654, 2310.90756369745, 2372.9762702372927, 2385.0340612052146, 2396.8681722505257, 2397.304781613339, 2141.06863514371, 2416.335507735378, 2430.1008626808107, 2465.469923966347, 2473.6660550825586, 2061.835053195741, 2317.351141680332, 2427.1990243219784, 2430.5746494363502, 2431.9513609727796, 2358.1864256831363, 2397.7841994220603, 2399.592069348206, 2401.1475926046264, 2403.375122468153, 2386.2125498585424, 2391.4380120671626, 2402.1952645863657, 2403.531311394279, 2403.5433267679086, 7974.043114826258, 8058.838823223561, 8065.3144482719035, 8069.152738962442, 8070.515769053817, 4801.183798754185, 4851.199552073904, 4861.951380486185, 4864.100141473803, 4885.706857588556, 3230.095573239497, 3253.8210398428077, 3260.7391408687486, 3261.59230096238, 3290.128116049249, 1067.5996501312009, 1079.0127033764097, 1119.043218048565, 2039.5614942787372, 2092.1213090502024, 10763.085137763299, 10833.729156771065, 10842.512086956027, 10849.077828384598, 10861.891381086167, 4325.329573916048, 4375.890602734957, 4439.467263928284, 4443.466783985942, 4447.911041779211, 8889.599968001456, 8891.066438024043, 8894.28845692343, 8898.42160259581, 8899.955500222457, 4974.353334066548, 4985.875353116128, 4988.625853061035, 4997.800087996439, 5017.9247311290355, 4586.289125185534, 4606.907861842811, 4609.331646911179, 4623.398958135488, 4634.23535116702, 3708.7033037356946, 3720.9627903721157, 3720.981395093007, 3729.2355067211156, 3742.2328422673286, 1069.4438891776388, 1078.3206579854798, 1132.3206076354286, 1136.3011747775058, 1140.7832511822728, 1159.756451145252, 1173.7945859474557, 1224.1981502116128, 1432.4127107885924, 1451.310627451973, 7749.690012399441, 7868.25251601097, 7870.803229919298, 7882.763517094496, 7885.684572617031, 780.4887798491997, 782.2724865874791, 791.2799352589117, 802.0856223904754, 895.632790555869, 6110.847228819226, 6187.133801267826, 6196.194494715658, 6204.410580994799, 6209.161763162006, 9408.106229908148, 9868.901310986941, 9885.703428897144, 9889.554970026365, 9904.405735655884, 9719.271054670928, 9723.978982206909, 9730.805383892424, 9744.805103898025, 9768.804623907483, 7367.8158046049275, 7560.735374261984, 7562.281583249583, 7580.924190758132, 7588.089429268523, 7414.110306763223, 7450.627468626655, 7455.291747283999, 7468.589227592534, 7482.139553951365, 8760.036395996307, 8772.91227087721, 8881.777955551162, 8908.510031948304, 8921.107889211027, 8696.391252612257, 8888.73333799987, 8928.464292142493, 8936.642534298555, 9154.908450915673, 6921.134858142732, 7023.122109736283, 7028.753993610199, 7032.894506582507, 7038.155421349471, 7551.773446796605, 7674.424418168548, 7701.614919254127, 7707.807304817425, 7862.567558784093, 6805.795826125224, 7332.853342933005, 7402.9629851850395, 7409.481336306622, 7470.252974702486, 6916.446684265347, 7320.524165929198, 7342.816429589303, 7398.408127349691, 7445.888311675221, 7484.962575187307, 7511.774646760606, 7522.097348318237, 7524.097108347033, 7524.586147761706, 6695.832604184934, 6725.562838415487, 6736.528443008942, 6758.797236082925, 6789.422899053636, 6846.5892046477375, 6899.758508452381, 6901.62041087745, 6967.581945083311, 6970.546914450747], "unit": "ms", "name": "mootools.html", "value": 4675.098376911299}, {"lowerIsBetter": false, "replicates": [352.9717622590192, 354.71977138060936, 359.7805338743366, 361.93485172668943, 364.3750967091439, 5871.29544454666, 5874.353821079678, 5887.440693134151, 5890.998530249857, 5910.70446477676, 5611.0741727614995, 5685.829425117253, 5712.857178570538, 5799.449052340021, 5805.419458054193, 4544.068465964486, 4567.36056952027, 4577.702449340783, 4580.725156490613, 4583.404157459526, 4557.817687292504, 4559.498455169929, 4568.314752787075, 4570.954290457103, 4575.908481830362, 4369.73781573105, 4441.133978874121, 4482.417285752847, 4543.727376357413, 4545.340925565791, 4529.0715403342565, 4534.863954081383, 4545.590896819269, 4548.658850586203, 4553.521880202581, 3986.382110772835, 4027.0, 4027.9597204027878, 4034.334334834749, 4044.1911617676287, 5526.972365138189, 5539.390667026624, 5540.556755459554, 5544.112941929312, 5554.694491802949, 5161.74191290434, 5285.07511185541, 5323.095073837457, 5338.359396872362, 5343.0, 6222.097795819581, 6244.344343843876, 6263.217097862767, 6263.968680156616, 6266.843328916813, 4496.2805951048, 4500.392447019676, 4501.212287849613, 4501.639868810487, 4503.369528266046, 6285.748570057192, 6411.134496842913, 6448.16173897395, 6462.709178086998, 6472.449841763456, 4470.709403888802, 4499.190145773792, 4501.279795232747, 4501.527339629357, 4502.144592527409, 4551.704139230939, 4558.772061396917, 4559.110973360163, 4559.521250268674, 4561.771911404417, 5210.687358758487, 5213.348331458567, 5214.348206474191, 5216.008958297987, 5219.921701174486, 6132.877342453126, 6215.192025036717, 6226.533010024175, 6227.377262273827, 6304.810855674337, 1622.9675406491804, 1636.533587927435, 1637.2141372141439, 1644.761509581104, 1645.243188133472, 1105.3147048829776, 1109.3399427340719, 1109.8612673415823, 1127.667338135252, 1128.4132251229314, 6078.513718902477, 6082.482988945901, 6088.1476593276975, 6093.0, 6093.238345206849, 6456.51576131792, 6472.644004579794, 6478.546501744832, 6479.773207937699, 6491.285958544557, 5933.703314834241, 6012.849678757979, 6023.156758053876, 6115.1744514490865, 6115.6330620162935, 3496.2483066140903, 3530.682238598487, 3538.239278555123, 3557.8221088945447, 3581.194231297937, 6552.63960482178, 6559.967200163968, 6593.307702691149, 6629.602223866584, 6632.668366581652, 6481.09264702942, 6557.901631475532, 6571.178602674666, 6589.934100658932, 6610.86778264432, 6658.234303055211, 6681.766138185238, 6683.532152749261, 6706.396424321834, 6707.262201157869, 6491.448226900767, 6498.252700939453, 6515.934840651533, 6526.282108968105, 6529.249136349381, 6353.36466353361, 6420.55056146084, 6439.034144878306, 6440.6135631862235, 6450.741970321134, 6698.66506674674, 6701.1623547056615, 6705.396514313735, 6706.463482921452, 6709.060731497691, 4530.682852200314, 4532.184206842668, 4533.750643714496, 4538.205813982474, 4538.818447262072, 6378.425941665272, 6469.676516174079, 6500.154979852589, 6507.674616269073, 6515.641639709862, 6311.589746666452, 6319.494440444845, 6320.178376810985, 6322.304546499973, 6322.33615470369, 6272.686365681789, 6283.622982620875, 6293.433590976834, 6294.433500984934, 6303.46420554258, 6042.788502402394, 6158.907616385668, 6181.876362472815, 6186.10301506288, 6198.876022479615, 6325.936740632535, 6342.112104305309, 6399.584027038228, 6405.807825765234, 6419.839504012437, 6176.227971503562, 6191.349908259568, 6212.596181248205, 6222.533310001678, 6229.844253893508], "unit": "ms", "name": "prototype.html", "value": 4765.761440744963}, {"lowerIsBetter": false, "replicates": [964.7858165510156, 964.8408012677909, 969.5588507229211, 979.2557656181309, 988.3575675810722, 14297.142171469719, 14474.782878256834, 14587.489437869677, 14673.92663036686, 14711.264436778158, 14480.782788258184, 14948.0, 14969.101853888775, 14976.925115374435, 14997.550073497785, 783.7766236622564, 785.1481142959896, 929.5538141691992, 952.200151872427, 967.1440774914216, 869.491347561674, 943.2831048403194, 946.4510583861378, 953.2278854128143, 954.9856752148723, 828.888100106487, 982.9115379615833, 991.3457118301923, 995.1292618958412, 995.4525011243824, 838.4298676899706, 980.1570649241646, 986.634945070325, 988.5403287471288, 989.2185173712759, 727.5234721257585, 731.9304666056717, 733.336330620789, 734.111724812977, 734.6216698400328, 14476.131432114107, 14620.0, 14625.92687036558, 14641.853581464156, 14644.487442939553, 13286.402111904987, 13730.725385492335, 13770.104943178761, 13840.723185536333, 14103.083299585494, 11703.473343699468, 11763.235389699728, 11789.58736444229, 11803.645890623295, 12001.579944701982, 3590.317839610466, 3605.7836529808033, 3619.330423871566, 3625.619309972468, 3694.5751238607368, 52928.47071529274, 53354.19968700472, 53383.46616533785, 53907.19139212915, 54160.45839541594, 8567.143285671507, 8574.314054875595, 8648.394612377073, 8697.95651021741, 8773.561321933877, 14437.566872993826, 14440.566782996528, 14451.0, 14484.710305793826, 14488.492902748563, 7606.847863042819, 7642.770716878503, 7667.808304792313, 7671.923280767232, 7844.646990885424, 4270.572942705705, 4405.845795397146, 4448.065906159678, 4472.217361961643, 4538.750368729686, 3148.8897888574247, 3174.9682503175136, 3175.4284228839033, 3178.1577881861326, 3178.666240044808, 475.97858096385744, 480.9831655892097, 482.899783390626, 489.09517392823705, 489.51537977402825, 696.2028477393357, 723.0347486106107, 757.3184134279082, 762.710170135345, 789.502613353595, 9461.24310055194, 9628.422294662203, 9708.52639499432, 9725.418162033715, 9748.31761776683, 562.3729541561212, 584.6141546579322, 660.2638058564665, 700.9474289428211, 731.4039058167683, 4568.246239370533, 4744.976275118603, 4852.053849499313, 4987.900241995139, 5013.1362645330555, 68198.3180168202, 69476.6526167376, 69592.30407695858, 69890.30109698838, 70076.0, 60177.097343539885, 61490.69254653698, 61881.69059154675, 61952.0, 62980.0, 55213.72393138089, 55645.72177139088, 55996.440035599124, 56795.71602141963, 57300.71349643308, 9917.603295868084, 10131.341462804894, 10173.135283500986, 10202.28583999113, 10316.0, 39550.40674389829, 40892.38661420141, 40903.42013310668, 41169.1766164681, 41182.794086029375, 48927.26609100938, 51105.74447127741, 55123.44876551183, 55500.167497486764, 55706.164407533106, 8847.69033083839, 9022.593983270637, 9027.71183687743, 9198.95400522993, 9288.210502107397, 13312.933435332761, 13761.036727429384, 13832.930835346162, 13992.650183745487, 14043.859561404664, 12866.935665321613, 12979.545715899898, 13495.527656531975, 13495.527656531975, 14134.0, 12805.487780488676, 12863.742725145257, 12992.477621420254, 13264.801027984395, 13540.526081587097, 12681.55614553523, 12690.0, 12694.809577856155, 12867.099303048697, 12885.355732213164, 10690.679279621623, 10892.673219803419, 10902.800691923723, 10997.725056873642, 11244.55021799119, 10685.305455145392, 10947.069499092666, 11090.5563777448, 11208.327500349678, 11290.604828830952], "unit": "ms", "name": "yui.html", "value": 6881.45036874975}], "name": "dromaeo_css", "value": 7201.4844985908}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [107.0, 109.0, 103.0, 110.0, 110.0, 109.0, 99.0, 109.0, 106.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 107.0}, {"lowerIsBetter": true, "replicates": [144.0, 121.0, 147.0, 165.0, 129.0, 146.0, 142.0, 131.0, 155.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.2}, {"lowerIsBetter": true, "replicates": [162.0, 160.0, 161.0, 162.0, 159.0, 159.0, 160.0, 164.0, 160.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 160.6}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 71.0, 139.0, 73.0, 72.0, 71.0, 72.0, 168.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 87.7}, {"lowerIsBetter": true, "replicates": [90.0, 89.0, 131.0, 89.0, 89.0, 89.0, 134.0, 89.0, 89.0, 140.0], "unit": "ms", "name": "audio-oscillator", "value": 102.9}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 110.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 145.0, 147.0, 145.0, 146.0, 146.0, 147.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [52.0, 54.0, 55.0, 54.0, 53.0, 54.0, 57.0, 54.0, 52.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 53.9}, {"lowerIsBetter": true, "replicates": [63.0, 62.0, 63.0, 63.0, 64.0, 64.0, 63.0, 63.0, 63.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.1}, {"lowerIsBetter": true, "replicates": [76.0, 77.0, 78.0, 79.0, 78.0, 92.0, 85.0, 78.0, 77.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 80.5}, {"lowerIsBetter": true, "replicates": [107.0, 110.0, 108.0, 112.0, 121.0, 108.0, 107.0, 130.0, 113.0, 113.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 112.9}, {"lowerIsBetter": true, "replicates": [136.0, 138.0, 137.0, 141.0, 135.0, 137.0, 140.0, 142.0, 134.0, 136.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 137.6}, {"lowerIsBetter": true, "replicates": [60.0, 61.0, 60.0, 61.0, 63.0, 61.0, 61.0, 62.0, 61.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.3}], "name": "kraken", "value": 1458.3}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.88918558077435], "unit": "score", "name": "Richards", "value": 211.52958400000006}, {"lowerIsBetter": false, "replicates": [169.7792869269949], "unit": "score", "name": "DeltaBlue", "value": 389.4350199999999}, {"lowerIsBetter": false, "replicates": [279.3296089385475], "unit": "score", "name": "Encrypt", "value": 952.9279799999998}, {"lowerIsBetter": false, "replicates": [5010.0], "unit": "score", "name": "Decrypt", "value": 53.12994011976049}, {"lowerIsBetter": false, "replicates": [946.073793755913], "unit": "score", "name": "RayTrace", "value": 782.1683729999996}, {"lowerIsBetter": false, "replicates": [553.7098560354374], "unit": "score", "name": "Earley", "value": 1203.6321779999998}, {"lowerIsBetter": false, "replicates": [10489.583333333334], "unit": "score", "name": "Boyer", "value": 63.5356981132075}, {"lowerIsBetter": false, "replicates": [23976.190476190477], "unit": "score", "name": "RegExp", "value": 37.995402184707075}, {"lowerIsBetter": false, "replicates": [538.2131324004306], "unit": "score", "name": "Splay", "value": 151.41027799999995}, {"lowerIsBetter": false, "replicates": [4817.307692307692], "unit": "score", "name": "NavierStokes", "value": 308.055888223553}], "name": "v8_7", "value": 23237.243548117254}]} 03:49:48 INFO - RETURN: dromaeo_css: 7201.48 03:49:48 INFO - RETURN: kraken: 1458.30 03:49:48 INFO - RETURN: v8_7: 23237.24 03:49:48 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23237.24"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1458.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7201.48"}}} 03:49:48 INFO - Return code: 0 03:49:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 03:49:48 INFO - # TBPL SUCCESS # 03:49:48 INFO - Running post-action listener: _resource_record_post_action 03:49:48 INFO - Running post-run listener: _resource_record_post_run 03:49:49 INFO - Total resource usage - Wall time: 1220s; CPU: 13.0%; Read bytes: 319488; Write bytes: 501264384; Read time: 48; Write time: 364420 03:49:49 INFO - install - Wall time: 14s; CPU: 14.0%; Read bytes: 0; Write bytes: 238104576; Read time: 0; Write time: 228316 03:49:49 INFO - run-tests - Wall time: 1206s; CPU: 12.0%; Read bytes: 319488; Write bytes: 263159808; Read time: 48; Write time: 136104 03:49:49 INFO - Running post-run listener: _upload_blobber_files 03:49:49 INFO - Blob upload gear active. 03:49:49 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 03:49:49 INFO - Copying logs to upload dir... 03:49:49 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1250.524336 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-01-04 03:49:48.993326) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-04 03:49:48.997196) ========= 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=fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009920 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451898186/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-04 03:49:49.053120) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 03:49:49.053493) ========= 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=fce1c2174958ee92775f70ae000001a3-1451906937.213529-1333935600 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004790 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 03:49:49.104457) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-04 03:49:49.104990) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-04 03:49:49.105530) ========= ========= Total master_lag: 0.25 =========