builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-063 starttime: 1452823469.76 results: success (0) buildid: 20160114154200 builduid: 5372d65fb11b4081b1be1d93908aa1a3 revision: 5c042dab34a2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:29.761983) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:29.763250) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:29.763647) ========= 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=33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006307 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:29.791385) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:29.791938) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:29.827542) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:29.827869) ========= 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=33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004570 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:29.881876) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:29.882206) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:29.882648) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:29.882968) ========= 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=33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-14 18:04:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 454K=0.03s 2016-01-14 18:04:31 (454 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.141048 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:30.054290) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:30.054623) ========= 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=33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022611 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:30.108244) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:30.108665) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 5c042dab34a2 --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 5c042dab34a2 --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=33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-14 18:04:31,264 Setting DEBUG logging. 2016-01-14 18:04:31,264 attempt 1/10 2016-01-14 18:04:31,264 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/5c042dab34a2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-14 18:04:31,553 unpacking tar archive at: mozilla-beta-5c042dab34a2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.602235 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:30.725151) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:30.725497) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:30.734833) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:30.735141) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-14 18:04:30.735586) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-01-14 18:04:30.735920) ========= /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=33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:04:31 INFO - MultiFileLogger online at 20160114 18:04:31 in /builds/slave/test-pgo 18:04:31 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 18:04:31 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 18:04:31 INFO - {'append_to_log': False, 18:04:31 INFO - 'base_work_dir': '/builds/slave/test-pgo', 18:04:31 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:04:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 18:04:31 INFO - 'branch': 'Mozilla-Beta', 18:04:31 INFO - 'buildbot_json_path': 'buildprops.json', 18:04:31 INFO - 'config_files': ('talos/linux_config.py',), 18:04:31 INFO - 'default_actions': ('clobber', 18:04:31 INFO - 'read-buildbot-config', 18:04:31 INFO - 'download-and-extract', 18:04:31 INFO - 'populate-webroot', 18:04:31 INFO - 'create-virtualenv', 18:04:31 INFO - 'install', 18:04:31 INFO - 'run-tests'), 18:04:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:04:31 INFO - 'download_minidump_stackwalk': True, 18:04:31 INFO - 'download_symbols': 'ondemand', 18:04:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:04:31 INFO - 'tooltool.py': '/tools/tooltool.py', 18:04:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:04:31 INFO - '/tools/misc-python/virtualenv.py')}, 18:04:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:04:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:04:31 INFO - 'installer_path': 'installer.exe', 18:04:31 INFO - 'log_level': 'info', 18:04:31 INFO - 'log_name': 'talos', 18:04:31 INFO - 'log_to_console': True, 18:04:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:04:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:04:31 INFO - 'opt_config_files': (), 18:04:31 INFO - 'pip_index': False, 18:04:31 INFO - 'sps_profile': False, 18:04:31 INFO - 'sps_profile_interval': 0, 18:04:31 INFO - 'suite': 'dromaeojs', 18:04:31 INFO - 'system_bits': '32', 18:04:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:04:31 INFO - 'title': 'talos-linux64-ix-063', 18:04:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:04:31 INFO - 'use_talos_json': True, 18:04:31 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 18:04:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:04:31 INFO - 'work_dir': 'build'} 18:04:31 INFO - ##### 18:04:31 INFO - ##### Running clobber step. 18:04:31 INFO - ##### 18:04:31 INFO - Running pre-action listener: _resource_record_pre_action 18:04:31 INFO - Running main action method: clobber 18:04:31 INFO - rmtree: /builds/slave/test-pgo/build 18:04:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 18:04:32 INFO - Running post-action listener: _resource_record_post_action 18:04:32 INFO - ##### 18:04:32 INFO - ##### Running read-buildbot-config step. 18:04:32 INFO - ##### 18:04:32 INFO - Running pre-action listener: _resource_record_pre_action 18:04:32 INFO - Running main action method: read_buildbot_config 18:04:32 INFO - Using buildbot properties: 18:04:32 INFO - { 18:04:32 INFO - "properties": { 18:04:32 INFO - "buildnumber": 78, 18:04:32 INFO - "product": "firefox", 18:04:32 INFO - "script_repo_revision": "production", 18:04:32 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 18:04:32 INFO - "repository": "", 18:04:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 18:04:32 INFO - "buildid": "20160114154200", 18:04:32 INFO - "slavename": "talos-linux64-ix-063", 18:04:32 INFO - "pgo_build": "True", 18:04:32 INFO - "basedir": "/builds/slave/test-pgo", 18:04:32 INFO - "project": "", 18:04:32 INFO - "platform": "ubuntu64_hw", 18:04:32 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:04:32 INFO - "slavebuilddir": "test", 18:04:32 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 18:04:32 INFO - "branch": "mozilla-beta", 18:04:32 INFO - "repo_path": "releases/mozilla-beta", 18:04:32 INFO - "stage_platform": "linux64-pgo", 18:04:32 INFO - "builduid": "5372d65fb11b4081b1be1d93908aa1a3", 18:04:32 INFO - "revision": "5c042dab34a2" 18:04:32 INFO - }, 18:04:32 INFO - "sourcestamp": { 18:04:32 INFO - "repository": "", 18:04:32 INFO - "hasPatch": false, 18:04:32 INFO - "project": "", 18:04:32 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 18:04:32 INFO - "changes": [ 18:04:32 INFO - { 18:04:32 INFO - "category": null, 18:04:32 INFO - "files": [ 18:04:32 INFO - { 18:04:32 INFO - "url": null, 18:04:32 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/firefox-44.0.en-US.linux-x86_64.tar.bz2" 18:04:32 INFO - } 18:04:32 INFO - ], 18:04:32 INFO - "repository": "", 18:04:32 INFO - "rev": "5c042dab34a2", 18:04:32 INFO - "who": "sendchange", 18:04:32 INFO - "when": 1452823429, 18:04:32 INFO - "number": 7464216, 18:04:32 INFO - "comments": "Backed out changeset 7bd651ae54dd (bug 1192945) to hopefully fix 1231429 a=ritu", 18:04:32 INFO - "project": "", 18:04:32 INFO - "at": "Thu 14 Jan 2016 18:03:49", 18:04:32 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 18:04:32 INFO - "revlink": "", 18:04:32 INFO - "properties": [ 18:04:32 INFO - [ 18:04:32 INFO - "buildid", 18:04:32 INFO - "20160114154200", 18:04:32 INFO - "Change" 18:04:32 INFO - ], 18:04:32 INFO - [ 18:04:32 INFO - "builduid", 18:04:32 INFO - "5372d65fb11b4081b1be1d93908aa1a3", 18:04:32 INFO - "Change" 18:04:32 INFO - ], 18:04:32 INFO - [ 18:04:32 INFO - "pgo_build", 18:04:32 INFO - "True", 18:04:32 INFO - "Change" 18:04:32 INFO - ] 18:04:32 INFO - ], 18:04:32 INFO - "revision": "5c042dab34a2" 18:04:32 INFO - } 18:04:32 INFO - ], 18:04:32 INFO - "revision": "5c042dab34a2" 18:04:32 INFO - } 18:04:32 INFO - } 18:04:32 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/firefox-44.0.en-US.linux-x86_64.tar.bz2. 18:04:32 INFO - Running post-action listener: _resource_record_post_action 18:04:32 INFO - ##### 18:04:32 INFO - ##### Running download-and-extract step. 18:04:32 INFO - ##### 18:04:32 INFO - Running pre-action listener: _resource_record_pre_action 18:04:32 INFO - Running main action method: download_and_extract 18:04:32 INFO - mkdir: /builds/slave/test-pgo/build/tests 18:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:32 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/test_packages.json 18:04:32 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 18:04:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 18:04:32 INFO - Downloaded 1270 bytes. 18:04:32 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 18:04:32 INFO - Using the following test package requirements: 18:04:32 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 18:04:32 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:04:32 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 18:04:32 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:04:32 INFO - u'jsshell-linux-x86_64.zip'], 18:04:32 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:04:32 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 18:04:32 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 18:04:32 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:04:32 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 18:04:32 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:04:32 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 18:04:32 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:04:32 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 18:04:32 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 18:04:32 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:04:32 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 18:04:32 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:32 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:04:32 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/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 18:04:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/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 18:04:34 INFO - Downloaded 22571956 bytes. 18:04:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:04:34 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:04:34 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:04:35 INFO - Return code: 0 18:04:35 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:04:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:04:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/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 18:04:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/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 18:04:45 INFO - Downloaded 22571956 bytes. 18:04:45 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 18:04:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:04:45 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 18:04:47 INFO - Return code: 0 18:04:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:47 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 18:04:47 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/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 18:04:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/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 18:04:48 INFO - Downloaded 11131970 bytes. 18:04:48 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 18:04:48 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 18:04:48 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 18:04:48 INFO - Return code: 0 18:04:48 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')]} 18:04:48 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/firefox-44.0.en-US.linux-x86_64.tar.bz2 18:04:48 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/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 18:04:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/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 18:04:56 INFO - Downloaded 56631110 bytes. 18:04:56 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/firefox-44.0.en-US.linux-x86_64.tar.bz2 18:04:56 INFO - mkdir: /builds/slave/test-pgo/properties 18:04:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 18:04:56 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 18:04:56 INFO - Contents: 18:04:56 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/firefox-44.0.en-US.linux-x86_64.tar.bz2 18:04:56 INFO - Running post-action listener: _resource_record_post_action 18:04:56 INFO - Running post-action listener: set_extra_try_arguments 18:04:56 INFO - ##### 18:04:56 INFO - ##### Running populate-webroot step. 18:04:56 INFO - ##### 18:04:56 INFO - Running pre-action listener: _resource_record_pre_action 18:04:56 INFO - Running main action method: populate_webroot 18:04:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:04:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:04:56 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:04:56 INFO - '../fennec_ids.txt'], 18:04:56 INFO - 'tests': ['tcheck2']}, 18:04:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:04:56 INFO - '--tppagecycles', 18:04:56 INFO - '7'], 18:04:56 INFO - 'tests': ['tsvgm']}}, 18:04:56 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:04:56 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:04:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:04:56 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:04:56 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:04:56 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:04:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:56 INFO - 'talos_options': ['--e10s'], 18:04:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:56 INFO - 'tests': ['damp', 'tps']}, 18:04:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:56 INFO - 'talos_options': ['--e10s'], 18:04:56 INFO - 'tests': ['damp', 'tps']}, 18:04:56 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:04:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:04:56 INFO - 'other': {'tests': ['a11yr', 18:04:56 INFO - 'ts_paint', 18:04:56 INFO - 'tpaint', 18:04:56 INFO - 'sessionrestore', 18:04:56 INFO - 'sessionrestore_no_auto_restore']}, 18:04:56 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:04:56 INFO - 'tests': ['a11yr', 18:04:56 INFO - 'ts_paint', 18:04:56 INFO - 'tpaint', 18:04:56 INFO - 'sessionrestore', 18:04:56 INFO - 'sessionrestore_no_auto_restore']}, 18:04:56 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:04:56 INFO - 'tests': ['a11yr', 18:04:56 INFO - 'ts_paint', 18:04:56 INFO - 'tpaint', 18:04:56 INFO - 'sessionrestore', 18:04:56 INFO - 'sessionrestore_no_auto_restore']}, 18:04:56 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:04:56 INFO - 'tests': ['a11yr', 18:04:56 INFO - 'ts_paint', 18:04:56 INFO - 'tpaint', 18:04:56 INFO - 'sessionrestore', 18:04:56 INFO - 'sessionrestore_no_auto_restore']}, 18:04:56 INFO - 'other_l64': {'tests': ['a11yr', 18:04:56 INFO - 'ts_paint', 18:04:56 INFO - 'tpaint', 18:04:56 INFO - 'sessionrestore', 18:04:56 INFO - 'sessionrestore_no_auto_restore']}, 18:04:56 INFO - 'other_nol64': {'tests': ['a11yr', 18:04:56 INFO - 'ts_paint', 18:04:56 INFO - 'tpaint', 18:04:56 INFO - 'sessionrestore', 18:04:56 INFO - 'sessionrestore_no_auto_restore']}, 18:04:56 INFO - 'svgr': {'tests': ['tsvgx', 18:04:56 INFO - 'tsvgr_opacity', 18:04:56 INFO - 'tart', 18:04:56 INFO - 'tscrollx', 18:04:56 INFO - 'cart']}, 18:04:56 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:04:56 INFO - 'tests': ['tsvgx', 18:04:56 INFO - 'tsvgr_opacity', 18:04:56 INFO - 'tart', 18:04:56 INFO - 'tscrollx', 18:04:56 INFO - 'cart']}, 18:04:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:56 INFO - 'tests': ['tp5o']}, 18:04:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:56 INFO - 'talos_options': ['--e10s'], 18:04:56 INFO - 'tests': ['tp5o']}, 18:04:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:56 INFO - 'talos_options': ['--xperf_path', 18:04:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:56 INFO - 'tests': ['tp5n']}, 18:04:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:56 INFO - 'talos_options': ['--e10s', 18:04:56 INFO - '--xperf_path', 18:04:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:56 INFO - 'tests': ['tp5n']}}, 18:04:56 INFO - 'talos.zip': {'path': '', 18:04:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:04:56 INFO - Running post-action listener: _resource_record_post_action 18:04:56 INFO - ##### 18:04:56 INFO - ##### Running create-virtualenv step. 18:04:56 INFO - ##### 18:04:56 INFO - Running pre-action listener: _resource_record_pre_action 18:04:56 INFO - Running main action method: create_virtualenv 18:04:56 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 18:04:56 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 18:04:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 18:04:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:04:56 INFO - Using real prefix '/usr' 18:04:56 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 18:04:57 INFO - Installing distribute.............................................................................................................................................................................................done. 18:04:58 INFO - Installing pip.................done. 18:04:58 INFO - Return code: 0 18:04:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 18:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:58 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc2e3edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1635600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c4350>, '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 0x16c5250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c56e0>, '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': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', '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 18:04:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 18:04:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:04:58 INFO - Using env: {'DISPLAY': ':0', 18:04:58 INFO - 'HOME': '/home/cltbld', 18:04:58 INFO - 'LANG': 'en_US.UTF-8', 18:04:58 INFO - 'LANGUAGE': 'en_US:en', 18:04:58 INFO - 'LOGNAME': 'cltbld', 18:04:58 INFO - 'MAIL': '/var/mail/cltbld', 18:04:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:58 INFO - 'MOZ_NO_REMOTE': '1', 18:04:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:58 INFO - 'NO_EM_RESTART': '1', 18:04:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:04:58 INFO - 'PWD': '/builds/slave/test-pgo', 18:04:58 INFO - 'SHELL': '/bin/bash', 18:04:58 INFO - 'SHLVL': '1', 18:04:58 INFO - 'TERM': 'linux', 18:04:58 INFO - 'TMOUT': '86400', 18:04:58 INFO - 'USER': 'cltbld', 18:04:58 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', 18:04:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:58 INFO - '_': '/tools/buildbot/bin/python'} 18:04:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:59 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 18:04:59 INFO - Cleaning up... 18:04:59 INFO - Return code: 0 18:04:59 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 18:04:59 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')]} 18:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:59 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')]} 18:04:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:59 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 0x7fdc2e3edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1635600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c4350>, '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 0x16c5250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c56e0>, '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': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', '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 18:04:59 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 18:04:59 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 18:04:59 INFO - Using env: {'DISPLAY': ':0', 18:04:59 INFO - 'HOME': '/home/cltbld', 18:04:59 INFO - 'LANG': 'en_US.UTF-8', 18:04:59 INFO - 'LANGUAGE': 'en_US:en', 18:04:59 INFO - 'LOGNAME': 'cltbld', 18:04:59 INFO - 'MAIL': '/var/mail/cltbld', 18:04:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:59 INFO - 'MOZ_NO_REMOTE': '1', 18:04:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:59 INFO - 'NO_EM_RESTART': '1', 18:04:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:04:59 INFO - 'PWD': '/builds/slave/test-pgo', 18:04:59 INFO - 'SHELL': '/bin/bash', 18:04:59 INFO - 'SHLVL': '1', 18:04:59 INFO - 'TERM': 'linux', 18:04:59 INFO - 'TMOUT': '86400', 18:04:59 INFO - 'USER': 'cltbld', 18:04:59 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', 18:04:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:59 INFO - '_': '/tools/buildbot/bin/python'} 18:04:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:59 INFO - Downloading/unpacking psutil>=0.7.1 18:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:01 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 18:05:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:05:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 18:05:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:05:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:05:01 INFO - Installing collected packages: psutil 18:05:01 INFO - Running setup.py install for psutil 18:05:01 INFO - building 'psutil._psutil_linux' extension 18:05:01 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 18:05:01 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 18:05:01 INFO - building 'psutil._psutil_posix' extension 18:05:01 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 18:05:01 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 18:05:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:05:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:05:01 INFO - Successfully installed psutil 18:05:01 INFO - Cleaning up... 18:05:01 INFO - Return code: 0 18:05:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 18:05: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')]} 18:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:01 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 0x7fdc2e3edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1635600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c4350>, '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 0x16c5250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c56e0>, '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': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', '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 18:05:01 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 18:05:01 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 18:05:01 INFO - Using env: {'DISPLAY': ':0', 18:05:01 INFO - 'HOME': '/home/cltbld', 18:05:01 INFO - 'LANG': 'en_US.UTF-8', 18:05:01 INFO - 'LANGUAGE': 'en_US:en', 18:05:01 INFO - 'LOGNAME': 'cltbld', 18:05:01 INFO - 'MAIL': '/var/mail/cltbld', 18:05:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:01 INFO - 'MOZ_NO_REMOTE': '1', 18:05:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:01 INFO - 'NO_EM_RESTART': '1', 18:05:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:05:01 INFO - 'PWD': '/builds/slave/test-pgo', 18:05:01 INFO - 'SHELL': '/bin/bash', 18:05:01 INFO - 'SHLVL': '1', 18:05:01 INFO - 'TERM': 'linux', 18:05:01 INFO - 'TMOUT': '86400', 18:05:01 INFO - 'USER': 'cltbld', 18:05:01 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', 18:05:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:01 INFO - '_': '/tools/buildbot/bin/python'} 18:05:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:03 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:05:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:05:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:05:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:05:03 INFO - Installing collected packages: mozsystemmonitor 18:05:03 INFO - Running setup.py install for mozsystemmonitor 18:05:03 INFO - Successfully installed mozsystemmonitor 18:05:03 INFO - Cleaning up... 18:05:03 INFO - Return code: 0 18:05:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 18:05: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')]} 18:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7fdc2e3edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1635600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c4350>, '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 0x16c5250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c56e0>, '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': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', '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 18:05:03 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 18:05:03 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 18:05:03 INFO - Using env: {'DISPLAY': ':0', 18:05:03 INFO - 'HOME': '/home/cltbld', 18:05:03 INFO - 'LANG': 'en_US.UTF-8', 18:05:03 INFO - 'LANGUAGE': 'en_US:en', 18:05:03 INFO - 'LOGNAME': 'cltbld', 18:05:03 INFO - 'MAIL': '/var/mail/cltbld', 18:05:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:03 INFO - 'MOZ_NO_REMOTE': '1', 18:05:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:03 INFO - 'NO_EM_RESTART': '1', 18:05:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:05:03 INFO - 'PWD': '/builds/slave/test-pgo', 18:05:03 INFO - 'SHELL': '/bin/bash', 18:05:03 INFO - 'SHLVL': '1', 18:05:03 INFO - 'TERM': 'linux', 18:05:03 INFO - 'TMOUT': '86400', 18:05:03 INFO - 'USER': 'cltbld', 18:05:03 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', 18:05:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:03 INFO - '_': '/tools/buildbot/bin/python'} 18:05:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:04 INFO - Downloading/unpacking blobuploader==1.2.4 18:05:04 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 18:05:04 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 18:05:04 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 18:05:04 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 18:05:04 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 18:05:04 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 18:05:05 INFO - Downloading blobuploader-1.2.4.tar.gz 18:05:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:05:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:05:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:05:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 18:05:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:06 INFO - Downloading docopt-0.6.1.tar.gz 18:05:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:05:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 18:05:06 INFO - Installing collected packages: blobuploader, requests, docopt 18:05:06 INFO - Running setup.py install for blobuploader 18:05:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:05:06 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 18:05:06 INFO - Running setup.py install for requests 18:05:07 INFO - Running setup.py install for docopt 18:05:07 INFO - Successfully installed blobuploader requests docopt 18:05:07 INFO - Cleaning up... 18:05:07 INFO - Return code: 0 18:05:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:05: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')]} 18:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:07 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 0x7fdc2e3edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1635600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c4350>, '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 0x16c5250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c56e0>, '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': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', '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 18:05:07 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 18:05:07 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 18:05:07 INFO - Using env: {'DISPLAY': ':0', 18:05:07 INFO - 'HOME': '/home/cltbld', 18:05:07 INFO - 'LANG': 'en_US.UTF-8', 18:05:07 INFO - 'LANGUAGE': 'en_US:en', 18:05:07 INFO - 'LOGNAME': 'cltbld', 18:05:07 INFO - 'MAIL': '/var/mail/cltbld', 18:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:07 INFO - 'MOZ_NO_REMOTE': '1', 18:05:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:07 INFO - 'NO_EM_RESTART': '1', 18:05:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:05:07 INFO - 'PWD': '/builds/slave/test-pgo', 18:05:07 INFO - 'SHELL': '/bin/bash', 18:05:07 INFO - 'SHLVL': '1', 18:05:07 INFO - 'TERM': 'linux', 18:05:07 INFO - 'TMOUT': '86400', 18:05:07 INFO - 'USER': 'cltbld', 18:05:07 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', 18:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:07 INFO - '_': '/tools/buildbot/bin/python'} 18:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:05:07 INFO - Running setup.py (path:/tmp/pip-UpOImG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:05:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:05:07 INFO - Running setup.py (path:/tmp/pip-Q3pbr6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:05:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:05:07 INFO - Running setup.py (path:/tmp/pip-G_ibsz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:05:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:05:07 INFO - Running setup.py (path:/tmp/pip-EOntpm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:05:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:05:07 INFO - Running setup.py (path:/tmp/pip-2dq31L-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:05:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:05:07 INFO - Running setup.py (path:/tmp/pip-gXlvcG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:05:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:05:07 INFO - Running setup.py (path:/tmp/pip-y3mn1F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:05:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:05:07 INFO - Running setup.py (path:/tmp/pip-dv2eEd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:05:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:05:07 INFO - Running setup.py (path:/tmp/pip-jNLagT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:05:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:05:08 INFO - Running setup.py (path:/tmp/pip-WvdfS6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:05:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:05:08 INFO - Running setup.py (path:/tmp/pip-Wq6gjh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:05:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:05:08 INFO - Running setup.py (path:/tmp/pip-uqH3B0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:05:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:05:08 INFO - Running setup.py (path:/tmp/pip-uM5kBy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:05:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:05:08 INFO - Running setup.py (path:/tmp/pip-4KnjCm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:05:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:05:08 INFO - Running setup.py (path:/tmp/pip-e57elp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:05:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:05:08 INFO - Running setup.py (path:/tmp/pip-Oev5tQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:05:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:05:08 INFO - Running setup.py (path:/tmp/pip-2Q34io-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:05:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:05:08 INFO - Running setup.py install for manifestparser 18:05:08 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:05:08 INFO - Running setup.py install for mozcrash 18:05:08 INFO - Running setup.py install for mozdebug 18:05:08 INFO - Running setup.py install for mozdevice 18:05:09 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:05:09 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:05:09 INFO - Running setup.py install for mozfile 18:05:09 INFO - Running setup.py install for mozhttpd 18:05:09 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:05:09 INFO - Running setup.py install for mozinfo 18:05:09 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:05:09 INFO - Running setup.py install for mozInstall 18:05:09 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:05:09 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:05:09 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:05:09 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:05:09 INFO - Running setup.py install for mozleak 18:05:09 INFO - Running setup.py install for mozlog 18:05:09 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:05:09 INFO - Running setup.py install for moznetwork 18:05:09 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:05:09 INFO - Running setup.py install for mozprocess 18:05:09 INFO - Running setup.py install for mozprofile 18:05:09 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:05:09 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:05:09 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:05:09 INFO - Running setup.py install for mozrunner 18:05:10 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:05:10 INFO - Running setup.py install for mozscreenshot 18:05:10 INFO - Running setup.py install for moztest 18:05:10 INFO - Running setup.py install for mozversion 18:05:10 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:05:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:05:10 INFO - Cleaning up... 18:05:10 INFO - Return code: 0 18:05:10 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:05: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')]} 18:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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', '-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 0x7fdc2e3edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1635600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c4350>, '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 0x16c5250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c56e0>, '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': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', '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 18:05:10 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 18:05:10 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 18:05:10 INFO - Using env: {'DISPLAY': ':0', 18:05:10 INFO - 'HOME': '/home/cltbld', 18:05:10 INFO - 'LANG': 'en_US.UTF-8', 18:05:10 INFO - 'LANGUAGE': 'en_US:en', 18:05:10 INFO - 'LOGNAME': 'cltbld', 18:05:10 INFO - 'MAIL': '/var/mail/cltbld', 18:05:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:10 INFO - 'MOZ_NO_REMOTE': '1', 18:05:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:10 INFO - 'NO_EM_RESTART': '1', 18:05:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:05:10 INFO - 'PWD': '/builds/slave/test-pgo', 18:05:10 INFO - 'SHELL': '/bin/bash', 18:05:10 INFO - 'SHLVL': '1', 18:05:10 INFO - 'TERM': 'linux', 18:05:10 INFO - 'TMOUT': '86400', 18:05:10 INFO - 'USER': 'cltbld', 18:05:10 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', 18:05:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:10 INFO - '_': '/tools/buildbot/bin/python'} 18:05:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:05:10 INFO - Running setup.py (path:/tmp/pip-Ht8TMv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:05:10 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)) 18:05:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:05:10 INFO - Running setup.py (path:/tmp/pip-0Mg_5d-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:05:10 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)) 18:05:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:05:10 INFO - Running setup.py (path:/tmp/pip-d7y_ov-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:05:10 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)) 18:05:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:05:10 INFO - Running setup.py (path:/tmp/pip-EtpcvT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:05:10 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)) 18:05:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:05:10 INFO - Running setup.py (path:/tmp/pip-pRxXxX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:05:10 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)) 18:05:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:05:10 INFO - Running setup.py (path:/tmp/pip-W7XXn4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:05:11 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)) 18:05:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:05:11 INFO - Running setup.py (path:/tmp/pip-qS0bYT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:05:11 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)) 18:05:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:05:11 INFO - Running setup.py (path:/tmp/pip-TRe5f_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:05:11 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)) 18:05:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:05:11 INFO - Running setup.py (path:/tmp/pip-2PT_Mt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:05:11 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)) 18:05:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:05:11 INFO - Running setup.py (path:/tmp/pip-aB2fGX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:05:11 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)) 18:05:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:05:11 INFO - Running setup.py (path:/tmp/pip-VcVcvv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:05:11 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)) 18:05:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:05:11 INFO - Running setup.py (path:/tmp/pip-bTlqZb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:05:11 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)) 18:05:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:05:11 INFO - Running setup.py (path:/tmp/pip-FP5PdP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:05:11 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)) 18:05:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:05:11 INFO - Running setup.py (path:/tmp/pip-NsVi1_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:05:11 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)) 18:05:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:05:11 INFO - Running setup.py (path:/tmp/pip-YJsRZp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:05:11 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)) 18:05:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:05:11 INFO - Running setup.py (path:/tmp/pip-BqIwRD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:05:11 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)) 18:05:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:05:11 INFO - Running setup.py (path:/tmp/pip-FhQ8QD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:05:11 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)) 18:05:11 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)) 18:05:11 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)) 18:05:11 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)) 18:05:11 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)) 18:05:11 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)) 18:05:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:13 INFO - Downloading blessings-1.5.1.tar.gz 18:05:13 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 18:05:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:05:13 INFO - Installing collected packages: blessings 18:05:13 INFO - Running setup.py install for blessings 18:05:13 INFO - Successfully installed blessings 18:05:13 INFO - Cleaning up... 18:05:13 INFO - Return code: 0 18:05:13 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:05:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:05:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:05:14 INFO - Reading from file tmpfile_stdout 18:05:14 INFO - Current package versions: 18:05:14 INFO - argparse == 1.2.1 18:05:14 INFO - blessings == 1.5.1 18:05:14 INFO - blobuploader == 1.2.4 18:05:14 INFO - docopt == 0.6.1 18:05:14 INFO - manifestparser == 1.1 18:05:14 INFO - mozInstall == 1.12 18:05:14 INFO - mozcrash == 0.16 18:05:14 INFO - mozdebug == 0.1 18:05:14 INFO - mozdevice == 0.46 18:05:14 INFO - mozfile == 1.2 18:05:14 INFO - mozhttpd == 0.7 18:05:14 INFO - mozinfo == 0.8 18:05:14 INFO - mozleak == 0.1 18:05:14 INFO - mozlog == 3.0 18:05:14 INFO - moznetwork == 0.27 18:05:14 INFO - mozprocess == 0.22 18:05:14 INFO - mozprofile == 0.27 18:05:14 INFO - mozrunner == 6.11 18:05:14 INFO - mozscreenshot == 0.1 18:05:14 INFO - mozsystemmonitor == 0.0 18:05:14 INFO - moztest == 0.7 18:05:14 INFO - mozversion == 1.4 18:05:14 INFO - psutil == 3.1.1 18:05:14 INFO - requests == 1.2.3 18:05:14 INFO - wsgiref == 0.1.2 18:05:14 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:05:14 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')]} 18:05:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:14 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')]} 18:05:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:14 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 0x7fdc2e3edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1635600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c4350>, '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 0x16c5250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c56e0>, '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': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', '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 18:05:14 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 18:05:14 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 18:05:14 INFO - Using env: {'DISPLAY': ':0', 18:05:14 INFO - 'HOME': '/home/cltbld', 18:05:14 INFO - 'LANG': 'en_US.UTF-8', 18:05:14 INFO - 'LANGUAGE': 'en_US:en', 18:05:14 INFO - 'LOGNAME': 'cltbld', 18:05:14 INFO - 'MAIL': '/var/mail/cltbld', 18:05:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:14 INFO - 'MOZ_NO_REMOTE': '1', 18:05:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:14 INFO - 'NO_EM_RESTART': '1', 18:05:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:05:14 INFO - 'PWD': '/builds/slave/test-pgo', 18:05:14 INFO - 'SHELL': '/bin/bash', 18:05:14 INFO - 'SHLVL': '1', 18:05:14 INFO - 'TERM': 'linux', 18:05:14 INFO - 'TMOUT': '86400', 18:05:14 INFO - 'USER': 'cltbld', 18:05:14 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', 18:05:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:14 INFO - '_': '/tools/buildbot/bin/python'} 18:05:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:14 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)) 18:05:14 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)) 18:05:14 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)) 18:05:14 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)) 18:05:14 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)) 18:05:14 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)) 18:05:14 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)) 18:05:14 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)) 18:05:14 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)) 18:05:14 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)) 18:05:14 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)) 18:05:14 INFO - Cleaning up... 18:05:14 INFO - Return code: 0 18:05:14 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 18:05:14 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')]} 18:05:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:14 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')]} 18:05:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:14 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 0x7fdc2e3edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1635600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c4350>, '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 0x16c5250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c56e0>, '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': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', '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 18:05:14 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 18:05:14 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 18:05:14 INFO - Using env: {'DISPLAY': ':0', 18:05:14 INFO - 'HOME': '/home/cltbld', 18:05:14 INFO - 'LANG': 'en_US.UTF-8', 18:05:14 INFO - 'LANGUAGE': 'en_US:en', 18:05:14 INFO - 'LOGNAME': 'cltbld', 18:05:14 INFO - 'MAIL': '/var/mail/cltbld', 18:05:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:14 INFO - 'MOZ_NO_REMOTE': '1', 18:05:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:14 INFO - 'NO_EM_RESTART': '1', 18:05:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:05:14 INFO - 'PWD': '/builds/slave/test-pgo', 18:05:14 INFO - 'SHELL': '/bin/bash', 18:05:14 INFO - 'SHLVL': '1', 18:05:14 INFO - 'TERM': 'linux', 18:05:14 INFO - 'TMOUT': '86400', 18:05:14 INFO - 'USER': 'cltbld', 18:05:14 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', 18:05:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:14 INFO - '_': '/tools/buildbot/bin/python'} 18:05:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:14 INFO - Downloading/unpacking jsonschema 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:05:16 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 18:05:16 INFO - Downloading/unpacking functools32 (from jsonschema) 18:05:16 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 18:05:16 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 18:05:16 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 18:05:16 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 18:05:16 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 18:05:16 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 18:05:16 INFO - Downloading functools32-3.2.3-2.tar.gz 18:05:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 18:05:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 18:05:16 INFO - warning: no files found matching '*.txt' 18:05:16 INFO - no previously-included directories found matching 'build' 18:05:16 INFO - no previously-included directories found matching 'dist' 18:05:16 INFO - no previously-included directories found matching '.git*' 18:05:16 INFO - Installing collected packages: jsonschema, functools32 18:05:16 INFO - Running setup.py install for functools32 18:05:16 INFO - warning: no files found matching '*.txt' 18:05:16 INFO - no previously-included directories found matching 'build' 18:05:16 INFO - no previously-included directories found matching 'dist' 18:05:16 INFO - no previously-included directories found matching '.git*' 18:05:16 INFO - Successfully installed jsonschema functools32 18:05:16 INFO - Cleaning up... 18:05:16 INFO - Return code: 0 18:05:16 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 18:05:16 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')]} 18:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:16 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')]} 18:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:16 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 0x7fdc2e3edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1635600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c4350>, '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 0x16c5250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c56e0>, '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': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', '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 18:05:16 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 18:05:16 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 18:05:16 INFO - Using env: {'DISPLAY': ':0', 18:05:16 INFO - 'HOME': '/home/cltbld', 18:05:16 INFO - 'LANG': 'en_US.UTF-8', 18:05:16 INFO - 'LANGUAGE': 'en_US:en', 18:05:16 INFO - 'LOGNAME': 'cltbld', 18:05:16 INFO - 'MAIL': '/var/mail/cltbld', 18:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:16 INFO - 'MOZ_NO_REMOTE': '1', 18:05:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:16 INFO - 'NO_EM_RESTART': '1', 18:05:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:05:16 INFO - 'PWD': '/builds/slave/test-pgo', 18:05:16 INFO - 'SHELL': '/bin/bash', 18:05:16 INFO - 'SHLVL': '1', 18:05:16 INFO - 'TERM': 'linux', 18:05:16 INFO - 'TMOUT': '86400', 18:05:16 INFO - 'USER': 'cltbld', 18:05:16 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', 18:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:16 INFO - '_': '/tools/buildbot/bin/python'} 18:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:16 INFO - Downloading/unpacking flake8 18:05:16 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 18:05:16 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 18:05:16 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 18:05:16 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 18:05:16 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 18:05:16 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 18:05:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:05:18 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 18:05:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:05:18 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 18:05:18 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 18:05:18 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 18:05:18 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 18:05:18 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 18:05:18 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 18:05:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:05:18 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 18:05:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:05:18 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 18:05:18 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 18:05:18 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 18:05:18 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 18:05:18 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 18:05:18 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 18:05:18 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 18:05:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:05:18 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 18:05:18 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 18:05:18 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 18:05:18 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 18:05:18 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 18:05:18 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 18:05:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 18:05:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 18:05:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:05:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:05:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:05:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:05:19 INFO - no previously-included directories found matching 'docs/_build' 18:05:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:05:19 INFO - Running setup.py install for pep8 18:05:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:05:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:05:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:05:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:05:19 INFO - no previously-included directories found matching 'docs/_build' 18:05:19 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 18:05:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:05:19 INFO - Cleaning up... 18:05:19 INFO - Return code: 0 18:05:19 INFO - Running post-action listener: _resource_record_post_action 18:05:19 INFO - Running post-action listener: _start_resource_monitoring 18:05:19 INFO - Starting resource monitoring. 18:05:19 INFO - ##### 18:05:19 INFO - ##### Running install step. 18:05:19 INFO - ##### 18:05:19 INFO - Running pre-action listener: _resource_record_pre_action 18:05:19 INFO - Running main action method: install 18:05:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:05:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:05:19 INFO - Reading from file tmpfile_stdout 18:05:19 INFO - Detecting whether we're running mozinstall >=1.0... 18:05:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:05:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:05:19 INFO - Reading from file tmpfile_stdout 18:05:19 INFO - Output received: 18:05:19 INFO - Usage: mozinstall [options] installer 18:05:19 INFO - Options: 18:05:19 INFO - -h, --help show this help message and exit 18:05:19 INFO - -d DEST, --destination=DEST 18:05:19 INFO - Directory to install application into. [default: 18:05:19 INFO - "/builds/slave/test-pgo"] 18:05:19 INFO - --app=APP Application being installed. [default: firefox] 18:05:19 INFO - mkdir: /builds/slave/test-pgo/build/application 18:05:19 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'] 18:05:19 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 18:05:33 INFO - Reading from file tmpfile_stdout 18:05:33 INFO - Output received: 18:05:33 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:05:33 INFO - Running post-action listener: _resource_record_post_action 18:05:33 INFO - ##### 18:05:33 INFO - ##### Running run-tests step. 18:05:33 INFO - ##### 18:05:33 INFO - Running pre-action listener: _resource_record_pre_action 18:05:33 INFO - Running main action method: run_tests 18:05:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:05:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:05:33 INFO - Python 2.7.3 18:05:33 INFO - Return code: 0 18:05:33 INFO - grabbing minidump binary from tooltool 18:05:33 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')]} 18:05:33 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 0x16c4350>, '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 0x16c5250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c56e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:05:33 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 18:05:33 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 18:05:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:05:33 INFO - Return code: 0 18:05:33 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:05:33 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:05:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:05:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:05:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:05:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 18:05:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 18:05:34 INFO - Return code: 0 18:05:34 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/1452814920/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 18:05:34 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/1452814920/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-063 --webServer localhost 18:05:34 INFO - Using env: {'DISPLAY': ':0', 18:05:34 INFO - 'HOME': '/home/cltbld', 18:05:34 INFO - 'LANG': 'en_US.UTF-8', 18:05:34 INFO - 'LANGUAGE': 'en_US:en', 18:05:34 INFO - 'LOGNAME': 'cltbld', 18:05:34 INFO - 'MAIL': '/var/mail/cltbld', 18:05:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:05:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:34 INFO - 'MOZ_NO_REMOTE': '1', 18:05:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:05:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:34 INFO - 'NO_EM_RESTART': '1', 18:05:34 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:05:34 INFO - 'PWD': '/builds/slave/test-pgo', 18:05:34 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:05:34 INFO - 'SHELL': '/bin/bash', 18:05:34 INFO - 'SHLVL': '1', 18:05:34 INFO - 'TERM': 'linux', 18:05:34 INFO - 'TMOUT': '86400', 18:05:34 INFO - 'USER': 'cltbld', 18:05:34 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733', 18:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:34 INFO - '_': '/tools/buildbot/bin/python'} 18:05:34 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/1452814920/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--webServer', 'localhost'] with output_timeout 3600 18:05:35 INFO - mozversion INFO | application_buildid: 20160114154200 18:05:35 INFO - mozversion INFO | application_changeset: 5c042dab34a257de85b7eaf2bc81aaad16b8a1b0 18:05:35 INFO - mozversion INFO | application_display_name: Nightly 18:05:35 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:05:35 INFO - mozversion INFO | application_name: Firefox 18:05:35 INFO - mozversion INFO | application_remotingname: firefox 18:05:35 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:05:35 INFO - mozversion INFO | application_vendor: Mozilla 18:05:35 INFO - mozversion INFO | application_version: 44.0 18:05:35 INFO - mozversion INFO | platform_buildid: 20160114154200 18:05:35 INFO - mozversion INFO | platform_changeset: 5c042dab34a257de85b7eaf2bc81aaad16b8a1b0 18:05:35 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:05:35 INFO - mozversion INFO | platform_version: 44.0 18:05:35 INFO - 2016-01-14 18:05:35,137 DEBUG : using testdate: 1452823535 18:05:35 INFO - 2016-01-14 18:05:35,137 DEBUG : actual date: 1452823535 18:05:35 INFO - 2016-01-14 18:05:35,184 INFO : starting webserver on 'localhost:58095' 18:05:35 INFO - 2016-01-14 18:05:35,185 INFO : Starting test suite talos-linux64-ix-063 18:05:35 INFO - 2016-01-14 18:05:35,185 INFO : Starting test dromaeo_css 18:05:35 INFO - 2016-01-14 18:05:35,185 DEBUG : operating with platform_type : linux_ 18:05:35 INFO - 2016-01-14 18:05:35,186 INFO : Initialising browser for dromaeo_css test... 18:05:35 INFO - 2016-01-14 18:05:35,195 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppEh5ND/profile http://localhost:58095/getInfo.html 18:05:38 INFO - 2016-01-14 18:05:38,592 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:05:38 INFO - 2016-01-14 18:05:38,592 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:05:38 INFO - 2016-01-14 18:05:38,592 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:05:38 INFO - 2016-01-14 18:05:38,592 DEBUG : BROWSER_OUTPUT: __metrics 18:05:38 INFO - 2016-01-14 18:05:38,694 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] 18:05:39 INFO - 2016-01-14 18:05:39,922 INFO : Browser initialized. 18:05:39 INFO - 2016-01-14 18:05:39,923 INFO : Running cycle 1/1 for dromaeo_css test... 18:05:39 INFO - 2016-01-14 18:05:39,923 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppEh5ND/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:05:51 INFO - 2016-01-14 18:05:51,479 DEBUG : BROWSER_OUTPUT: RSS: Main: 128749568 18:05:51 INFO - 2016-01-14 18:05:51,479 DEBUG : BROWSER_OUTPUT: 18:06:43 INFO - 2016-01-14 18:06:43,135 DEBUG : BROWSER_OUTPUT: 1452823603133 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] 18:08:50 INFO - 2016-01-14 18:08:50,296 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58095/tests/dromaeo/cssquery-ext.html) 18:08:51 INFO - 2016-01-14 18:08:51,400 DEBUG : BROWSER_OUTPUT: RSS: Main: 235687936 18:08:51 INFO - 2016-01-14 18:08:51,400 DEBUG : BROWSER_OUTPUT: 18:11:53 INFO - 2016-01-14 18:11:53,398 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/dromaeo/cssquery-ext.html (next: http://localhost:58095/tests/dromaeo/cssquery-jquery.html) 18:11:53 INFO - 2016-01-14 18:11:53,958 DEBUG : BROWSER_OUTPUT: RSS: Main: 206319616 18:11:53 INFO - 2016-01-14 18:11:53,958 DEBUG : BROWSER_OUTPUT: 18:14:55 INFO - 2016-01-14 18:14:55,700 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58095/tests/dromaeo/cssquery-mootools.html) 18:14:56 INFO - 2016-01-14 18:14:56,266 DEBUG : BROWSER_OUTPUT: RSS: Main: 212115456 18:14:56 INFO - 2016-01-14 18:14:56,266 DEBUG : BROWSER_OUTPUT: 18:17:57 INFO - 2016-01-14 18:17:57,998 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58095/tests/dromaeo/cssquery-prototype.html) 18:17:58 INFO - 2016-01-14 18:17:58,579 DEBUG : BROWSER_OUTPUT: RSS: Main: 198770688 18:17:58 INFO - 2016-01-14 18:17:58,579 DEBUG : BROWSER_OUTPUT: 18:21:00 INFO - 2016-01-14 18:21:00,884 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58095/tests/dromaeo/cssquery-yui.html) 18:21:01 INFO - 2016-01-14 18:21:01,451 DEBUG : BROWSER_OUTPUT: RSS: Main: 193658880 18:21:01 INFO - 2016-01-14 18:21:01,451 DEBUG : BROWSER_OUTPUT: 18:24:03 INFO - 2016-01-14 18:24:03,623 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/dromaeo/cssquery-yui.html (next: http://localhost:58095/tests/dromaeo/cssquery-dojo.html) 18:24:03 INFO - 2016-01-14 18:24:03,873 DEBUG : BROWSER_OUTPUT: RSS: Main: 233967616 18:24:03 INFO - 2016-01-14 18:24:03,873 DEBUG : BROWSER_OUTPUT: 18:24:03 INFO - 2016-01-14 18:24:03,873 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:24:03 INFO - 2016-01-14 18:24:03,873 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:24:03 INFO - 2016-01-14 18:24:03,874 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:24:03 INFO - 2016-01-14 18:24:03,874 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:24:03 INFO - 2016-01-14 18:24:03,874 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3232.9191770205744;3279.245773472103;3290.0787779421757;3302.3230237801254;3323.8504267307962;5547.0570003099465;5574.303212098488;5582.91625625616;5592.636478628891;5606.943930560698;5588.385277619459;5595.440455954403;5603.859903502404;5606.159076138571;5619.915701264485;4988.401391832985;5095.235714642806;5104.974475127619;5191.402988656297;5194.246834209038;5002.499750025005;5103.5917126629965;5112.41207261166;5123.590112790967;5166.896662066756;5179.326687530615;5182.974085129588;5186.0665080285535;5198.636095473318;5205.817796377128;5125.077486052509;5132.871678208037;5186.766595503212;5203.115470370046;5204.9739751301195;4989.825356112555;4994.700317980933;5053.418856831475;5093.643444958854;5096.974515127437;5440.537554307889;5460.808871689472;5469.042917489423;5472.370677372113;5485.286912701363;5861.238039054938;6089.482393996515;6106.786262480836;6109;6111.877762444727;5879.647221166743;5898.557608179403;5970.641761494324;5993.370696076893;5996.670183139926;5652.265205523256;5709.4290570942985;5713.457221563945;5718.59969802114;5724.914126288109;6046.939530604637;6150.753969841246;6180.072989051679;6190.288116866618;6194.256689197235;5842.269716285465;5941.346451890288;5948.048312270016;5949.405059494016;5975.910361344583;6047.213862197887;6047.939520604826;6058.0610005449225;6058.485028772516;6061.878762424727;5754.079347304411;5842.357340692521;5875.9118613220835;5931.11033344993;5939.13882487037;6047.03247480401;6085.421884920926;6087.117367981707;6092.72582733778;6095.116208149793;4688.46082700487;4693.084848454587;4696.201645720235;4697.459792123881;4717.127331443694;4075.49056367954;4076.694247931417;4139.234241665302;4158.480189976253;4183.644390226865;5580.469855363734;5608.186812912105;5611.158326251013;5685.914711279252;5695.45893140151;6092.482139018144;6096.481799047131;6102.206713127265;6105.389461053948;6114;5555.2222688823595;5572.86067848307;5626.649604094988;5651.915221271602;5661.745221465044;5226.97386513065;5242.947570524322;5244.580433565306;5245.213218017328;5248.081585722483;6054.727537260834;6207.068939658997;6210.5652604317265;6214.223222097238;6218.471429928507;5990.191324171181;6053.576249662481;6182.319944806158;6183.505319574513;6183.81448556544;6262.5929314594405;6286.717097730704;6291.402316779899;6293.685315734287;6295.118683384421;5769.278840144982;5882.294124705063;6028.939710603014;6055.2430946131735;6059.121427392915;5980.820575382745;6128.662923539248;6129.724162412613;6132.816015519542;6138.662373569497;5993.640381577119;6002.279726432856;6003.489703375088;6009.248843894513;6015.789447369321;5673.914891276551;5709.400512946247;5726.627769194989;5971.910421343597;5995.700214989321;5900.5574581907385;5908.3500814911185;5910.586258962004;5915.40845915405;5916.230889984275;5939.46544810969;5954.702264886825;6001.609895356788;6003.0695242237525;6012.068129439944;5608.8878222434505;5697.886042279214;5714.314282286152;5794.246747922743;5893.116032595145 18:24:03 INFO - 2016-01-14 18:24:03,877 DEBUG : BROWSER_OUTPUT: |1;ext.html;591.4233622218337;615.9876802463948;637.5728262064416;643.1831573901146;646.1470858466826;37534.8123259384;44767.10465790682;45632.3155152673;45662;45724.999999999956;49712.75143624277;50501.747491262584;50524.74737626307;50613.74693126529;50635.493645063536;8421.45260558065;8565.271951884097;9398.90601093987;9593.712188634352;10734.78530429391;11077.723056923562;11200.439978001108;11213.7196570086;11213.7196570086;11225.158113141506;11137.220394572383;11150.442477876115;11202.215844890861;11224.943875280613;11246.156538259622;11145.387003714792;11183.105351571854;11200.271982321123;11229.831552526719;11260.718482037933;10911.290766100257;11091;11091.112710983101;11095.944520277428;11097.722556936142;45191.77404112958;45981.770091149665;46001.53998460006;46060.76969615131;46096.76951615254;3731.272401881607;3821.293060783764;3944.585818489075;4021.3163762160502;4494.1461122386645;33872.661273387195;34349.141271468165;34542.82728586341;34595.827020864985;34762.304753904784;3782.1399501640262;3848.615138486157;3865.40086286626;3942.743721658083;3956.8812935611977;329672;331001;331617;332160;333392;68790.65604672044;69999.65000174966;78212;79394;79639.20360796434;72849.2715072853;72959.63520182365;73036.26963730401;73111;73119.26880731231;17215.483535493957;17615.383461578782;17637.7354339685;17650.293988240326;17658.823411765974;13276;13346.065775395824;13372.06395552302;13448.731025379439;13463.663408414868;23126.537469250856;23439.76560234376;23499.412514686926;23518.294451166494;23639.7636023641;8502.65989360431;8519.914800851911;8619.310455163572;8622.310215182895;8809.779755506164;1051.3376572759266;1067.4449286371043;1080.2276372393776;1097.742030622803;1121.9902088120593;31121.68878311188;31653.52519712206;31852.681473185436;31947.520787188212;32377.51433728496;1163.2787671643634;1172.5485688010178;1174.2426135142955;1233.216907263894;1234.4444999750149;13643.522476713466;16154.111523866066;16291.185440727917;16638.83361166373;16950.660986780196;403289;405669;406486;407982.96008520364;408327;51914.48085519172;52722.736386317825;60716.69641651763;61204.081938770956;61259.38740612537;1479.9186044767425;1483.592012196633;1496.4089184771954;1500.7373709600777;1503.1356969742224;30913.536296955113;31038.534421983237;31209.687903121587;31746.365072698878;32711;50229.246561300875;50612.74693626508;50621.746891265306;50690;50718;249861;250088;252465;252488;252556;32774.67225327716;33340.499892501146;33408.83295583507;33479.16302092467;33619.663803361655;45714.54285457103;48360.5163948356;49259.26111108408;49300.2604960933;49394.75302623464;40905.59094409018;41419.1716165681;41886;41958.580414195465;42595.787021064694;41530.79234603808;41799.164016720104;42184.57815421806;42671.57328426676;43153.56846431496;40981.590184097775;40987.38518922159;41424;41493.37759933543;41604.58395416128;35013.8249308762;37560.812195938845;37751.2449751009;37778.811105944296;38406.80796595999;28223.43553128967;28267.85866070739;28275.2931176714;28573;28574.285642859093 18:24:03 INFO - 2016-01-14 18:24:03,878 DEBUG : BROWSER_OUTPUT: |2;jquery.html;385.7608282864624;386.1504689682699;389.92201559688067;396.52813152348705;401.11954260398403;14082.804184952825;18152.636947261046;19401.805981940175;19444.9027754861;20753.377398678025;20327.390178294674;20329.18683252668;20795.16819327229;20818.16727330909;20840.166393344287;5822.796202132937;5940.495057920082;5949.6727679977585;5952.55355848311;5952.821415357546;5213.2962050123315;5336.119540275849;5348.331458567679;5928.318243402021;5979.192808970777;3862.1696335287907;3970.50368703912;5284.788608455658;5290.021346050993;5291.074062039147;5072.061668591304;5133.537981581676;5171.198464238049;5178.01617692641;5182.041322355358;4420.292753159479;4492.8876778080485;4517.887052823673;4528.5245049270015;4530.501644819067;15698.136602486858;15838.841611583852;15943.282552285174;15994.680106397924;16092.59768505785;2165.6859755335545;2180.073468775764;2191.4671180365444;2198.362474882298;2217.168561789329;11218.831717524243;11383.430828458544;11397.943010284978;11408.657740267723;11432.942835285854;1786.1337251433044;1838.6322735452964;1860.5999710062267;1872.5880306332585;1911.4838993471685;69358;69671.30328696698;71817.28182718158;71910.64044679796;87483;17296;18055.909720451313;18441.907790460962;18547.814521854878;18699.158537865558;28212.43575128486;28216.71783282141;28556.85721571379;28616.570751438747;28640.85679571589;9673.854892176623;9911.10800028001;9942.30403871722;9986.800263994679;10062.547185376676;8500.65997360111;8540.359473039547;8551.914480855112;8565.143485651384;8598.39811213209;8940.15068568485;9040.18638322541;9067.09329067088;9081.818363632823;9092.090660961272;1645.3254165791968;1678.127373765635;2340.566995105911;3315.5689760331;3383.289509203095;529.9284596579489;546.158915270487;551.3962211378534;551.715866328841;578.0260261459371;12941.54704585335;13091.672708182372;13447.99140064499;13600.795988060187;13633.841123504415;547.9232907392968;552.8424399046253;565.7878295639135;573.627142357471;575.0884847516733;7228.783136505913;7287.453440991947;7341.963290183515;7461.515001524884;7534.434917381218;503950;544758;549187;614211;638841;47919.28121078187;56975;78437;78603.21396786073;78702;599.5623195067537;605.8636806718453;606.7481994972126;608.7838817219912;611.843979785152;12183.208091474025;12348;12395.814062788884;12429.943454806442;12489.37553122322;40679.59320406758;40903.795481022404;41542;41900.790496047324;41907.161856763305;55542;56313.71843140758;57665;57847.42152578589;58715.70642146762;7441.739539116105;7514.098308203051;7551.207123251979;7554.471187016856;7571.962140189264;13537.59387218385;13583.52457663977;13670.658233544242;13741.03812733099;13748.93125534366;11148.554057837595;11249.71875703114;11584;11993.520259189534;12071;11548.0761539075;11550.59572914944;11727.88272117302;11823.467943942715;11858.110641701734;11670.183087183816;11936.28382297065;11941.343226122646;12129.878701212874;12216.450259738156;7475.4019678426685;7483.962580187064;7531.8493630125995;7555.471117021756;7564.432667549846;11786;11849.822252666045;11964.461599227881;11966.623838258713;12053.096017798876 18:24:03 INFO - 2016-01-14 18:24:03,880 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1569.6108943584925;1593.0282527658135;1629.4215553478512;1850.777906651202;1947.211379391346;7922.881156782646;8108.432409731321;8109.026916769981;8110.067342255642;8120.106788253287;7224.277572242786;7391.889121663179;8018.959905200466;8191.0989791123075;8279.627416766245;2433.355160882363;2447.657327974085;2474.4927289905527;2475.9752402476015;2478.479519300949;2200.207925146946;2415.215055107097;2472.320111969205;2484.8757562121914;2490.402303447178;2116.5449428372895;2385.057902128658;2434.9391265218333;2436.561418944589;2437.0251899240357;2410.95178096438;2414.082648593532;2424.3939015246187;2453.4970331082086;2455.5702752018324;2400.1479474786547;2419.1532963462823;2419.830611857171;2461.8645974471397;2519.924402267917;8247.917520824774;8305.792355191108;8315.667373305061;8315.916840831575;8317.500949943023;5028.647994640377;5097.464766199532;5109.182530795092;5110.156824124015;5110.156824124015;3220.2754380264487;3248.3340915112344;3260.0382887048377;3337.933241335184;3358.5969683637877;1058.227493929436;1087.9401632910187;1088.5319312695617;1111.3887361950976;1113.5323164271026;11204.831927521094;11246.212765106364;11248.325100493914;11262.380569068699;11266.324020558875;4668.8365907193765;4715.22198837188;4749.525047495292;4761.833335833298;4774.188387974053;9654.903450965541;9664.226861851035;9664.71005869825;9668.274879384075;9674.08096230857;5180.585553155738;5180.611454140879;5189.247559103909;5192.9740351298005;5193.428722840485;4714.057188562301;4742.549457801503;4743.739094349844;4748.501407352247;4750.501197374295;3971.0270983609203;4000.4799376080923;4016.6987475939422;4025.1547175092974;4039.939400908989;1217.5860207529483;1254.4605819497704;1296.598054603076;1336.6391074409855;1361.156083228385;1232.5562797392931;1264.4373253902108;1300.336828217616;1545.65995480994;1546.4741987724228;7769.300762931363;7970.322522585529;7975.960120199478;7984.960075199703;7997.200279971957;1174.301290732013;1210;1244.8941839943707;1269.549309994957;1272.4719241514854;6380.904286435707;6434.678266086677;6478;6486.189226346707;6486.935130648728;9677.564509597085;9741.707748767549;9787.559559819825;9795;9810.558524866397;9510.09654082861;9714.077162669535;9744.415335079917;9761.902380976242;9769.316147869724;7612.743897257002;7663.731769388045;7688.46180767341;7713.4214933880185;7763.417743669134;7441.069866266716;7645.426593005658;7784.182660820758;7850.6074696266105;7852.41106916995;8680.696175633822;8850.291976641724;8902.332325075517;8926.598303076247;8929.330300227639;8834.29325653959;9089.818203635758;9098.090190980849;9148.085191480799;9179.449233046038;7538.170801211752;7613;7627;7630;7634.045744281965;7883.921160788319;7922.881156782538;7950.7217247396065;7951.562664053301;7959.562224077731;6913.758018469531;6914.9308506916295;7611.924649634879;7702.268284512962;7707.537547747153;7363.77908662741;7494.962525187558;7515;7620.085589729268;7636.389088872988;7598.430117741302;7632.542047477169;7632.580208088609;7641.617919103911;7648.502847314907;6844.110265665432;6906.654667266717;6910.343517365842;6934.479914006369;6934.653267336714;7183.856322873408;7211.71153153868;7218.675159617725;7221.494495385273;7221.602811845399 18:24:03 INFO - 2016-01-14 18:24:03,883 DEBUG : BROWSER_OUTPUT: |4;prototype.html;370.27611020455015;377.6789728730578;379.04858804400965;379.5218025288136;381.8510780795492;5857.6485410875375;5991.730372133265;6000.519958403328;6040.003399439092;6041.3354531001605;5837.678927658988;5897.587168898179;5916.704164791754;5936.584439089254;5962.04607262838;4681.74250416227;4684.250519916823;4689.812407503696;4695.765211739416;4702.788374523155;4627.329037289591;4675.625949924015;4687.718736875781;4687.742174180418;4690.882727931795;4571.268597024477;4641.582257596816;4655.4180727409075;4707.882302942419;4708.529147085298;4704.153252414564;4706.505816889246;4724.4094488188975;4724.929126063112;4728.40894888139;4023.1350259694304;4208.74747515147;4209.34755112958;4214.072903961123;4215.578442155791;5572.944270557324;5614.63504872182;5670.914936275959;5673.914891276634;5696.857578560528;5338.492843179892;5358.3837858645975;5359.437259087778;5362.570994320484;5363.249145119687;6200.596961197508;6203.720832562501;6205.658688772116;6208.348123447064;6218.937810621881;4495.57292057254;4577.633789296849;4581.289900065495;4593.586577208067;4638.466576343696;6534.836629084263;6547.247066587356;6548.214214294268;6559.442447391977;6577.04632828242;4777.976110119427;4780.49804770501;4784.330193772875;4786.44955830084;4786.497417771084;4741.407324084489;4813.205821039559;4816.253480710496;4823.927641085386;4826.372571565674;5430.701311427831;5432.891342173134;5444.346678398618;5445.074337362658;5447.319085114361;6302.968485157545;6318.557700960981;6319.33646967071;6330.68346582669;6333.493320534346;1738.3568079810298;1756.0517320646964;1760.1111438723362;1760.172718822177;1760.4454596801966;1178.3048001679051;1199.310396521986;1199.9340036298088;1211.0372254058045;1226.4480983557435;6096.512279017668;6104.389561043949;6112.633242005494;6116.938830611726;6117.908231376533;6488.124103246096;6495.902561461582;6527;6538.1500404948;6548.607083575001;5975.551833612496;6003.699815009232;6080.969595151996;6084.269887613515;6086.452219300284;3588.0312315674623;3610.422332426799;3621.4748861414946;3623.710103191738;3627.7460577759302;6511.348865113546;6575.309592492815;6642.86714265712;6704.363085506869;6730.865382692319;6663.966680166568;6670.933290667128;6685.39831415175;6688.866222675617;6690.698918548677;6712;6717.193936727526;6719.361660642231;6726.899096513556;6730.158730158632;6399.77600783989;6502.967485162544;6526.771562995273;6535.705893234911;6537.5096867734155;6484.059811327237;6499.902501462388;6516.73933042673;6521.02184672303;6523.478121750154;6805.0472933788315;6809.046733457221;6812.591244525344;6817.761378351734;6821.795346139624;4594.655400844883;4692.41344831896;4696.319033740157;4696.365990591363;4699.741514216751;6587.2753997061245;6589.868202636017;6595.604263744191;6605.735770569124;6609.504287178384;6388.712507937061;6409.230892292955;6410.038494225717;6411.070394792636;6418.775342862978;6292.056191571301;6395.840103997251;6399.168108146097;6400.583962042452;6404.679766011587;6236.314005459305;6296.496280297657;6300.905486417616;6301.0863424804065;6308.022256550242;6408.29508754046;6441.420272175339;6448.032795080588;6449.838754031187;6449.838754031187;6183.84540386494;6280.654563999024;6286.905696414466;6288.779892703734;6289.245290565162 18:24:03 INFO - 2016-01-14 18:24:03,883 DEBUG : BROWSER_OUTPUT: |5;yui.html;889.186394449079;977.1108291454773;977.379364103794;987.891331953485;997.8553109799075;13731.17612943224;14227.075240109385;14231.999999999987;15858.603534911634;16239.594010149753;15606.609834754137;15650.765238521431;15694.921525392358;15756.527304180865;15812;786.0174781523068;798.5368486277974;922.3220932614523;924.791921817589;936.0405584276137;912.0742446416892;918.7014220378404;919.7562645898843;920.7836158502746;920.8710780490703;980.0248752491245;991.9404835709845;993.9652112176075;994.0357852882728;994.3735446668588;806.4959400374765;819.1071731812324;980.2011360741009;997.1225321716843;1001.0990108901974;719.0364911019207;724.3770357492552;731.9414446844293;732.4982387064878;732.5275197497638;14811.925940370229;14822.777658335133;14874.405023799036;14915.403383864632;14932.47736329234;12462.252264864137;12621.305828179446;13006.284654344006;13759.10565813219;13810.792838107436;11149.331040137537;11203.159763017806;11378.431078446045;11746.412679366083;11798.115141364346;3955.4462375267485;3968.3253846846105;3980.6019398060253;4019.376996565537;4101.384792281182;52241.47758522442;52860;52899.47100529022;53369.93260134814;53373;10473.528691208914;10478.109360704426;10525.578976840994;10730.517126729315;10775.084117849914;21428.03573839181;21624.351269461942;21700.891495542735;21710.45723856916;21732.13071477155;7950.363970882431;8019.5990200490905;8098.83802323962;8178.386621003329;8196.262336389753;4495.988402609452;4550.34020067095;4563.38394316763;4572.908541829212;4752.025834703877;3447.6897079262985;3462.0306314232057;3473.600535938349;3491.860325586948;3497.772644778132;504.4173979054125;516.431924882626;518.7587771686184;523.8376103407955;527.2828952624428;620.0729908786303;634.8000379880323;642.3640595810115;663.3466035954581;667.3893387550337;8770.3422243332;9022.413543119676;9079.909200907907;9187.81624367509;9273.165415112673;620.6865532905854;661.5865084322298;671.2046225223127;674.0933444517117;685.2496516314627;4354.716943398669;4364.301711726109;4404.141192467502;4432.601065904084;4538.432695913011;64301.35698643047;64368;64432.67783661051;65216;65438;56796.71601642048;57392.426075739546;57429.71285143548;57555.42444575501;58453.41546584565;50571;50878.23682644689;50949.7452512735;51271.74364128155;51770.74114629478;9260.53697315118;9510.716053332711;9531.761705957406;9693.660721874983;9715.222782177267;39483;40296.597034030456;40910.1817963645;41018.79490602528;41130.38304425496;48148.75925620349;53577;53617;54055;54206.18690719563;8850.933999371788;8982.11631109782;9038.502882341534;9170.724878253663;9343.766405839908;13123.803142953055;13323.800142997668;13423.932880335537;13505.932470337979;13562.525311614047;10274.383536987805;10308.814486333868;10544.525496352837;10623.521941512496;10923.945380273048;11692.532298708296;12172.330521821385;12227.266364018187;12229;12282.38588070575;10983.61557345489;11681;11728.530858765555;11900.523979040741;12368.072394570623;10148;10868.891311087104;10961.890381096087;11045.502952367002;11099.334039957628;9959.900400995897;10173.389596624227;10270.229732769923;10496.107830834464;10519.368837869752 18:24:03 INFO - 2016-01-14 18:24:03,884 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:24:03 INFO - 2016-01-14 18:24:03,884 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:24:03 INFO - 2016-01-14 18:24:03,884 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,866 18:24:03 INFO - 2016-01-14 18:24:03,884 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:24:03 INFO - 2016-01-14 18:24:03,884 DEBUG : BROWSER_OUTPUT: __startTimestamp1452824643872__endTimestamp 18:24:03 INFO - 2016-01-14 18:24:03,884 DEBUG : BROWSER_OUTPUT: 18:24:03 INFO - 2016-01-14 18:24:03,884 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:24:03 INFO - 2016-01-14 18:24:03,884 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:24:03 INFO - 2016-01-14 18:24:03,885 DEBUG : BROWSER_OUTPUT: 18:24:03 INFO - 2016-01-14 18:24:03,885 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5602.24 Median:5769.28 stddev:633.85 (11.0%) stddev-sans-first:607.93 18:24:03 INFO - 2016-01-14 18:24:03,885 DEBUG : BROWSER_OUTPUT: Values: 3232.9 3279.2 3290.1 3302.3 3323.9 5547.1 5574.3 5582.9 5592.6 5606.9 5588.4 5595.4 5603.9 5606.2 5619.9 4988.4 5095.2 5105.0 5191.4 5194.2 5002.5 5103.6 5112.4 5123.6 5166.9 5179.3 5183.0 5186.1 5198.6 5205.8 5125.1 5132.9 5186.8 5203.1 5205.0 4989.8 4994.7 5053.4 5093.6 5097.0 5440.5 5460.8 5469.0 5472.4 5485.3 5861.2 6089.5 6106.8 6109.0 6111.9 5879.6 5898.6 5970.6 5993.4 5996.7 5652.3 5709.4 5713.5 5718.6 5724.9 6046.9 6150.8 6180.1 6190.3 6194.3 5842.3 5941.3 5948.0 5949.4 5975.9 6047.2 6047.9 6058.1 6058.5 6061.9 5754.1 5842.4 5875.9 5931.1 5939.1 6047.0 6085.4 6087.1 6092.7 6095.1 4688.5 4693.1 4696.2 4697.5 4717.1 4075.5 4076.7 4139.2 4158.5 4183.6 5580.5 5608.2 5611.2 5685.9 5695.5 6092.5 6096.5 6102.2 6105.4 6114.0 5555.2 5572.9 5626.6 5651.9 5661.7 5227.0 5242.9 5244.6 5245.2 5248.1 6054.7 6207.1 6210.6 6214.2 6218.5 5990.2 6053.6 6182.3 6183.5 6183.8 6262.6 6286.7 6291.4 6293.7 6295.1 5769.3 5882.3 6028.9 6055.2 6059.1 5980.8 6128.7 6129.7 6132.8 6138.7 5993.6 6002.3 6003.5 6009.2 6015.8 5673.9 5709.4 5726.6 5971.9 5995.7 5900.6 5908.4 5910.6 5915.4 5916.2 5939.5 5954.7 6001.6 6003.1 6012.1 5608.9 5697.9 5714.3 5794.2 5893.1 18:24:03 INFO - 2016-01-14 18:24:03,885 DEBUG : BROWSER_OUTPUT: 18:24:03 INFO - 2016-01-14 18:24:03,885 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:52821.33 Median:34888.06 stddev:88028.89 (252.3%) stddev-sans-first:88188.48 18:24:03 INFO - 2016-01-14 18:24:03,885 DEBUG : BROWSER_OUTPUT: Values: 591.4 616.0 637.6 643.2 646.1 37534.8 44767.1 45632.3 45662.0 45725.0 49712.8 50501.7 50524.7 50613.7 50635.5 8421.5 8565.3 9398.9 9593.7 10734.8 11077.7 11200.4 11213.7 11213.7 11225.2 11137.2 11150.4 11202.2 11224.9 11246.2 11145.4 11183.1 11200.3 11229.8 11260.7 10911.3 11091.0 11091.1 11095.9 11097.7 45191.8 45981.8 46001.5 46060.8 46096.8 3731.3 3821.3 3944.6 4021.3 4494.1 33872.7 34349.1 34542.8 34595.8 34762.3 3782.1 3848.6 3865.4 3942.7 3956.9 329672.0 331001.0 331617.0 332160.0 333392.0 68790.7 69999.7 78212.0 79394.0 79639.2 72849.3 72959.6 73036.3 73111.0 73119.3 17215.5 17615.4 17637.7 17650.3 17658.8 13276.0 13346.1 13372.1 13448.7 13463.7 23126.5 23439.8 23499.4 23518.3 23639.8 8502.7 8519.9 8619.3 8622.3 8809.8 1051.3 1067.4 1080.2 1097.7 1122.0 31121.7 31653.5 31852.7 31947.5 32377.5 1163.3 1172.5 1174.2 1233.2 1234.4 13643.5 16154.1 16291.2 16638.8 16950.7 403289.0 405669.0 406486.0 407983.0 408327.0 51914.5 52722.7 60716.7 61204.1 61259.4 1479.9 1483.6 1496.4 1500.7 1503.1 30913.5 31038.5 31209.7 31746.4 32711.0 50229.2 50612.7 50621.7 50690.0 50718.0 249861.0 250088.0 252465.0 252488.0 252556.0 32774.7 33340.5 33408.8 33479.2 33619.7 45714.5 48360.5 49259.3 49300.3 49394.8 40905.6 41419.2 41886.0 41958.6 42595.8 41530.8 41799.2 42184.6 42671.6 43153.6 40981.6 40987.4 41424.0 41493.4 41604.6 35013.8 37560.8 37751.2 37778.8 38406.8 28223.4 28267.9 28275.3 28573.0 28574.3 18:24:03 INFO - 2016-01-14 18:24:03,885 DEBUG : BROWSER_OUTPUT: 18:24:03 INFO - 2016-01-14 18:24:03,886 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30414.14 Median:41721.40 stddev:93580.39 (224.3%) stddev-sans-first:93815.75 18:24:03 INFO - 2016-01-14 18:24:03,887 DEBUG : BROWSER_OUTPUT: Values: 385.8 386.2 389.9 396.5 401.1 14082.8 18152.6 19401.8 19444.9 20753.4 20327.4 20329.2 20795.2 20818.2 20840.2 5822.8 5940.5 5949.7 5952.6 5952.8 5213.3 5336.1 5348.3 5928.3 5979.2 3862.2 3970.5 5284.8 5290.0 5291.1 5072.1 5133.5 5171.2 5178.0 5182.0 4420.3 4492.9 4517.9 4528.5 4530.5 15698.1 15838.8 15943.3 15994.7 16092.6 2165.7 2180.1 2191.5 2198.4 2217.2 11218.8 11383.4 11397.9 11408.7 11432.9 1786.1 1838.6 1860.6 1872.6 1911.5 69358.0 69671.3 71817.3 71910.6 87483.0 17296.0 18055.9 18441.9 18547.8 18699.2 28212.4 28216.7 28556.9 28616.6 28640.9 9673.9 9911.1 9942.3 9986.8 10062.5 8500.7 8540.4 8551.9 8565.1 8598.4 8940.2 9040.2 9067.1 9081.8 9092.1 1645.3 1678.1 2340.6 3315.6 3383.3 529.9 546.2 551.4 551.7 578.0 12941.5 13091.7 13448.0 13600.8 13633.8 547.9 552.8 565.8 573.6 575.1 7228.8 7287.5 7342.0 7461.5 7534.4 503950.0 544758.0 549187.0 614211.0 638841.0 47919.3 56975.0 78437.0 78603.2 78702.0 599.6 605.9 606.7 608.8 611.8 12183.2 12348.0 12395.8 12429.9 12489.4 40679.6 40903.8 41542.0 41900.8 41907.2 55542.0 56313.7 57665.0 57847.4 58715.7 7441.7 7514.1 7551.2 7554.5 7572.0 13537.6 13583.5 13670.7 13741.0 13748.9 11148.6 11249.7 11584.0 11993.5 12071.0 11548.1 11550.6 11727.9 11823.5 11858.1 11670.2 11936.3 11941.3 12129.9 12216.5 7475.4 7484.0 7531.8 7555.5 7564.4 11786.0 11849.8 11964.5 11966.6 12053.1 18:24:03 INFO - 2016-01-14 18:24:03,887 DEBUG : BROWSER_OUTPUT: 18:24:03 INFO - 2016-01-14 18:24:03,887 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5843.20 Median:6875.38 stddev:2955.96 (43.0%) stddev-sans-first:2946.80 18:24:03 INFO - 2016-01-14 18:24:03,887 DEBUG : BROWSER_OUTPUT: Values: 1569.6 1593.0 1629.4 1850.8 1947.2 7922.9 8108.4 8109.0 8110.1 8120.1 7224.3 7391.9 8019.0 8191.1 8279.6 2433.4 2447.7 2474.5 2476.0 2478.5 2200.2 2415.2 2472.3 2484.9 2490.4 2116.5 2385.1 2434.9 2436.6 2437.0 2411.0 2414.1 2424.4 2453.5 2455.6 2400.1 2419.2 2419.8 2461.9 2519.9 8247.9 8305.8 8315.7 8315.9 8317.5 5028.6 5097.5 5109.2 5110.2 5110.2 3220.3 3248.3 3260.0 3337.9 3358.6 1058.2 1087.9 1088.5 1111.4 1113.5 11204.8 11246.2 11248.3 11262.4 11266.3 4668.8 4715.2 4749.5 4761.8 4774.2 9654.9 9664.2 9664.7 9668.3 9674.1 5180.6 5180.6 5189.2 5193.0 5193.4 4714.1 4742.5 4743.7 4748.5 4750.5 3971.0 4000.5 4016.7 4025.2 4039.9 1217.6 1254.5 1296.6 1336.6 1361.2 1232.6 1264.4 1300.3 1545.7 1546.5 7769.3 7970.3 7976.0 7985.0 7997.2 1174.3 1210.0 1244.9 1269.5 1272.5 6380.9 6434.7 6478.0 6486.2 6486.9 9677.6 9741.7 9787.6 9795.0 9810.6 9510.1 9714.1 9744.4 9761.9 9769.3 7612.7 7663.7 7688.5 7713.4 7763.4 7441.1 7645.4 7784.2 7850.6 7852.4 8680.7 8850.3 8902.3 8926.6 8929.3 8834.3 9089.8 9098.1 9148.1 9179.4 7538.2 7613.0 7627.0 7630.0 7634.0 7883.9 7922.9 7950.7 7951.6 7959.6 6913.8 6914.9 7611.9 7702.3 7707.5 7363.8 7495.0 7515.0 7620.1 7636.4 7598.4 7632.5 7632.6 7641.6 7648.5 6844.1 6906.7 6910.3 6934.5 6934.7 7183.9 7211.7 7218.7 7221.5 7221.6 18:24:03 INFO - 2016-01-14 18:24:03,887 DEBUG : BROWSER_OUTPUT: 18:24:03 INFO - 2016-01-14 18:24:03,887 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5362.07 Median:6040.67 stddev:1548.39 (25.6%) stddev-sans-first:1506.72 18:24:03 INFO - 2016-01-14 18:24:03,889 DEBUG : BROWSER_OUTPUT: Values: 370.3 377.7 379.0 379.5 381.9 5857.6 5991.7 6000.5 6040.0 6041.3 5837.7 5897.6 5916.7 5936.6 5962.0 4681.7 4684.3 4689.8 4695.8 4702.8 4627.3 4675.6 4687.7 4687.7 4690.9 4571.3 4641.6 4655.4 4707.9 4708.5 4704.2 4706.5 4724.4 4724.9 4728.4 4023.1 4208.7 4209.3 4214.1 4215.6 5572.9 5614.6 5670.9 5673.9 5696.9 5338.5 5358.4 5359.4 5362.6 5363.2 6200.6 6203.7 6205.7 6208.3 6218.9 4495.6 4577.6 4581.3 4593.6 4638.5 6534.8 6547.2 6548.2 6559.4 6577.0 4778.0 4780.5 4784.3 4786.4 4786.5 4741.4 4813.2 4816.3 4823.9 4826.4 5430.7 5432.9 5444.3 5445.1 5447.3 6303.0 6318.6 6319.3 6330.7 6333.5 1738.4 1756.1 1760.1 1760.2 1760.4 1178.3 1199.3 1199.9 1211.0 1226.4 6096.5 6104.4 6112.6 6116.9 6117.9 6488.1 6495.9 6527.0 6538.2 6548.6 5975.6 6003.7 6081.0 6084.3 6086.5 3588.0 3610.4 3621.5 3623.7 3627.7 6511.3 6575.3 6642.9 6704.4 6730.9 6664.0 6670.9 6685.4 6688.9 6690.7 6712.0 6717.2 6719.4 6726.9 6730.2 6399.8 6503.0 6526.8 6535.7 6537.5 6484.1 6499.9 6516.7 6521.0 6523.5 6805.0 6809.0 6812.6 6817.8 6821.8 4594.7 4692.4 4696.3 4696.4 4699.7 6587.3 6589.9 6595.6 6605.7 6609.5 6388.7 6409.2 6410.0 6411.1 6418.8 6292.1 6395.8 6399.2 6400.6 6404.7 6236.3 6296.5 6300.9 6301.1 6308.0 6408.3 6441.4 6448.0 6449.8 6449.8 6183.8 6280.7 6286.9 6288.8 6289.2 18:24:03 INFO - 2016-01-14 18:24:03,889 DEBUG : BROWSER_OUTPUT: 18:24:03 INFO - 2016-01-14 18:24:03,889 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15284.09 Median:24326.59 stddev:18082.51 (74.3%) stddev-sans-first:18100.93 18:24:03 INFO - 2016-01-14 18:24:03,889 DEBUG : BROWSER_OUTPUT: Values: 889.2 977.1 977.4 987.9 997.9 13731.2 14227.1 14232.0 15858.6 16239.6 15606.6 15650.8 15694.9 15756.5 15812.0 786.0 798.5 922.3 924.8 936.0 912.1 918.7 919.8 920.8 920.9 980.0 991.9 994.0 994.0 994.4 806.5 819.1 980.2 997.1 1001.1 719.0 724.4 731.9 732.5 732.5 14811.9 14822.8 14874.4 14915.4 14932.5 12462.3 12621.3 13006.3 13759.1 13810.8 11149.3 11203.2 11378.4 11746.4 11798.1 3955.4 3968.3 3980.6 4019.4 4101.4 52241.5 52860.0 52899.5 53369.9 53373.0 10473.5 10478.1 10525.6 10730.5 10775.1 21428.0 21624.4 21700.9 21710.5 21732.1 7950.4 8019.6 8098.8 8178.4 8196.3 4496.0 4550.3 4563.4 4572.9 4752.0 3447.7 3462.0 3473.6 3491.9 3497.8 504.4 516.4 518.8 523.8 527.3 620.1 634.8 642.4 663.3 667.4 8770.3 9022.4 9079.9 9187.8 9273.2 620.7 661.6 671.2 674.1 685.2 4354.7 4364.3 4404.1 4432.6 4538.4 64301.4 64368.0 64432.7 65216.0 65438.0 56796.7 57392.4 57429.7 57555.4 58453.4 50571.0 50878.2 50949.7 51271.7 51770.7 9260.5 9510.7 9531.8 9693.7 9715.2 39483.0 40296.6 40910.2 41018.8 41130.4 48148.8 53577.0 53617.0 54055.0 54206.2 8850.9 8982.1 9038.5 9170.7 9343.8 13123.8 13323.8 13423.9 13505.9 13562.5 10274.4 10308.8 10544.5 10623.5 10923.9 11692.5 12172.3 12227.3 12229.0 12282.4 10983.6 11681.0 11728.5 11900.5 12368.1 10148.0 10868.9 10961.9 11045.5 11099.3 9959.9 10173.4 10270.2 10496.1 10519.4 18:24:03 INFO - 2016-01-14 18:24:03,889 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:24:03 INFO - 2016-01-14 18:24:03,889 DEBUG : BROWSER_OUTPUT: 18:24:04 INFO - 2016-01-14 18:24:04,423 INFO : Browser exited with error code: 0 18:24:04 INFO - 2016-01-14 18:24:04,441 INFO : Completed test dromaeo_css (00:18:29) 18:24:04 INFO - 2016-01-14 18:24:04,441 INFO : Starting test kraken 18:24:04 INFO - 2016-01-14 18:24:04,441 DEBUG : operating with platform_type : linux_ 18:24:04 INFO - 2016-01-14 18:24:04,441 INFO : Initialising browser for kraken test... 18:24:04 INFO - 2016-01-14 18:24:04,448 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpe555MI/profile http://localhost:58095/getInfo.html 18:24:07 INFO - 2016-01-14 18:24:07,699 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:24:07 INFO - 2016-01-14 18:24:07,699 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:24:07 INFO - 2016-01-14 18:24:07,700 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:24:07 INFO - 2016-01-14 18:24:07,700 DEBUG : BROWSER_OUTPUT: __metrics 18:24:07 INFO - 2016-01-14 18:24:07,796 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] 18:24:08 INFO - 2016-01-14 18:24:08,958 INFO : Browser initialized. 18:24:08 INFO - 2016-01-14 18:24:08,959 INFO : Running cycle 1/1 for kraken test... 18:24:08 INFO - 2016-01-14 18:24:08,959 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpe555MI/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:24:20 INFO - 2016-01-14 18:24:20,515 DEBUG : BROWSER_OUTPUT: RSS: Main: 128507904 18:24:20 INFO - 2016-01-14 18:24:20,516 DEBUG : BROWSER_OUTPUT: 18:24:22 INFO - 2016-01-14 18:24:22,976 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58095/tests/kraken/driver.html?testName=audio-beat-detection) 18:24:23 INFO - 2016-01-14 18:24:23,543 DEBUG : BROWSER_OUTPUT: RSS: Main: 162930688 18:24:23 INFO - 2016-01-14 18:24:23,544 DEBUG : BROWSER_OUTPUT: 18:24:26 INFO - 2016-01-14 18:24:26,080 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58095/tests/kraken/driver.html?testName=audio-dft) 18:24:26 INFO - 2016-01-14 18:24:26,641 DEBUG : BROWSER_OUTPUT: RSS: Main: 160243712 18:24:26 INFO - 2016-01-14 18:24:26,641 DEBUG : BROWSER_OUTPUT: 18:24:29 INFO - 2016-01-14 18:24:29,428 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58095/tests/kraken/driver.html?testName=audio-fft) 18:24:29 INFO - 2016-01-14 18:24:29,996 DEBUG : BROWSER_OUTPUT: RSS: Main: 157106176 18:24:29 INFO - 2016-01-14 18:24:29,996 DEBUG : BROWSER_OUTPUT: 18:24:31 INFO - 2016-01-14 18:24:31,969 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58095/tests/kraken/driver.html?testName=audio-oscillator) 18:24:32 INFO - 2016-01-14 18:24:32,530 DEBUG : BROWSER_OUTPUT: RSS: Main: 157130752 18:24:32 INFO - 2016-01-14 18:24:32,530 DEBUG : BROWSER_OUTPUT: 18:24:34 INFO - 2016-01-14 18:24:34,648 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58095/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:24:35 INFO - 2016-01-14 18:24:35,209 DEBUG : BROWSER_OUTPUT: RSS: Main: 155947008 18:24:35 INFO - 2016-01-14 18:24:35,209 DEBUG : BROWSER_OUTPUT: 18:24:38 INFO - 2016-01-14 18:24:38,054 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58095/tests/kraken/driver.html?testName=imaging-darkroom) 18:24:38 INFO - 2016-01-14 18:24:38,621 DEBUG : BROWSER_OUTPUT: RSS: Main: 156594176 18:24:38 INFO - 2016-01-14 18:24:38,621 DEBUG : BROWSER_OUTPUT: 18:24:41 INFO - 2016-01-14 18:24:41,995 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58095/tests/kraken/driver.html?testName=imaging-desaturate) 18:24:42 INFO - 2016-01-14 18:24:42,562 DEBUG : BROWSER_OUTPUT: RSS: Main: 155394048 18:24:42 INFO - 2016-01-14 18:24:42,562 DEBUG : BROWSER_OUTPUT: 18:24:45 INFO - 2016-01-14 18:24:45,300 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58095/tests/kraken/driver.html?testName=json-parse-financial) 18:24:45 INFO - 2016-01-14 18:24:45,866 DEBUG : BROWSER_OUTPUT: RSS: Main: 155238400 18:24:45 INFO - 2016-01-14 18:24:45,866 DEBUG : BROWSER_OUTPUT: 18:24:47 INFO - 2016-01-14 18:24:47,366 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58095/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:24:47 INFO - 2016-01-14 18:24:47,927 DEBUG : BROWSER_OUTPUT: RSS: Main: 159322112 18:24:47 INFO - 2016-01-14 18:24:47,927 DEBUG : BROWSER_OUTPUT: 18:24:49 INFO - 2016-01-14 18:24:49,669 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58095/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:24:50 INFO - 2016-01-14 18:24:50,258 DEBUG : BROWSER_OUTPUT: RSS: Main: 156499968 18:24:50 INFO - 2016-01-14 18:24:50,259 DEBUG : BROWSER_OUTPUT: 18:24:52 INFO - 2016-01-14 18:24:52,210 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58095/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:24:52 INFO - 2016-01-14 18:24:52,775 DEBUG : BROWSER_OUTPUT: RSS: Main: 163692544 18:24:52 INFO - 2016-01-14 18:24:52,775 DEBUG : BROWSER_OUTPUT: 18:24:55 INFO - 2016-01-14 18:24:55,041 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58095/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:24:55 INFO - 2016-01-14 18:24:55,622 DEBUG : BROWSER_OUTPUT: RSS: Main: 167260160 18:24:55 INFO - 2016-01-14 18:24:55,622 DEBUG : BROWSER_OUTPUT: 18:24:58 INFO - 2016-01-14 18:24:58,028 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58095/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:24:58 INFO - 2016-01-14 18:24:58,595 DEBUG : BROWSER_OUTPUT: RSS: Main: 165662720 18:24:58 INFO - 2016-01-14 18:24:58,596 DEBUG : BROWSER_OUTPUT: 18:25:00 INFO - 2016-01-14 18:25:00,210 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58095/tests/kraken/driver.html?testName=ai-astar) 18:25:00 INFO - 2016-01-14 18:25:00,468 DEBUG : BROWSER_OUTPUT: RSS: Main: 224948224 18:25:00 INFO - 2016-01-14 18:25:00,468 DEBUG : BROWSER_OUTPUT: 18:25:00 INFO - 2016-01-14 18:25:00,468 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:25:00 INFO - 2016-01-14 18:25:00,469 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:25:00 INFO - 2016-01-14 18:25:00,469 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:25:00 INFO - 2016-01-14 18:25:00,469 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:25:00 INFO - 2016-01-14 18:25:00,469 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;111;105;110;112;114;102;110;110;110 18:25:00 INFO - 2016-01-14 18:25:00,469 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;120;147;163;122;151;149;122;160;124 18:25:00 INFO - 2016-01-14 18:25:00,469 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;159;159;159;158;159;159;161;161;160 18:25:00 INFO - 2016-01-14 18:25:00,469 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;70;71;157;70;71;72;81;155;71 18:25:00 INFO - 2016-01-14 18:25:00,469 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;90;135;89;89;90;146;89;89;132 18:25:00 INFO - 2016-01-14 18:25:00,470 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;110;109;109;110;109;109 18:25:00 INFO - 2016-01-14 18:25:00,470 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;146;147;146;146;145;147;145;146 18:25:00 INFO - 2016-01-14 18:25:00,470 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;95;96;96;95;96;96;95 18:25:00 INFO - 2016-01-14 18:25:00,470 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;54;56;54;54;55;54;54;52;53 18:25:00 INFO - 2016-01-14 18:25:00,470 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;63;63;64;63;63;63;62;64;63 18:25:00 INFO - 2016-01-14 18:25:00,471 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;75;76;77;77;86;79;78;76;77;77 18:25:00 INFO - 2016-01-14 18:25:00,471 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;113;116;108;108;111;111;109;111;108;109 18:25:00 INFO - 2016-01-14 18:25:00,471 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;137;137;137;139;137;132;137;138;138;138 18:25:00 INFO - 2016-01-14 18:25:00,471 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;62;61;62;63;63;61;64;63;64 18:25:00 INFO - 2016-01-14 18:25:00,471 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:25:00 INFO - 2016-01-14 18:25:00,471 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:25:00 INFO - 2016-01-14 18:25:00,472 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,859 18:25:00 INFO - 2016-01-14 18:25:00,472 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:25:00 INFO - 2016-01-14 18:25:00,472 DEBUG : BROWSER_OUTPUT: __startTimestamp1452824700463__endTimestamp 18:25:00 INFO - 2016-01-14 18:25:00,472 DEBUG : BROWSER_OUTPUT: 18:25:00 INFO - 2016-01-14 18:25:00,472 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:25:00 INFO - 2016-01-14 18:25:00,472 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:25:00 INFO - 2016-01-14 18:25:00,473 DEBUG : BROWSER_OUTPUT: 18:25:00 INFO - 2016-01-14 18:25:00,473 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:109.30 Median:110.00 stddev:3.43 (3.1%) stddev-sans-first:3.64 18:25:00 INFO - 2016-01-14 18:25:00,474 DEBUG : BROWSER_OUTPUT: Values: 109.0 111.0 105.0 110.0 112.0 114.0 102.0 110.0 110.0 110.0 18:25:00 INFO - 2016-01-14 18:25:00,474 DEBUG : BROWSER_OUTPUT: 18:25:00 INFO - 2016-01-14 18:25:00,474 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.70 Median:149.00 stddev:16.87 (11.3%) stddev-sans-first:17.62 18:25:00 INFO - 2016-01-14 18:25:00,474 DEBUG : BROWSER_OUTPUT: Values: 149.0 120.0 147.0 163.0 122.0 151.0 149.0 122.0 160.0 124.0 18:25:00 INFO - 2016-01-14 18:25:00,474 DEBUG : BROWSER_OUTPUT: 18:25:00 INFO - 2016-01-14 18:25:00,475 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.50 Median:159.50 stddev:0.97 (0.6%) stddev-sans-first:1.01 18:25:00 INFO - 2016-01-14 18:25:00,475 DEBUG : BROWSER_OUTPUT: Values: 160.0 159.0 159.0 159.0 158.0 159.0 159.0 161.0 161.0 160.0 18:25:00 INFO - 2016-01-14 18:25:00,475 DEBUG : BROWSER_OUTPUT: 18:25:00 INFO - 2016-01-14 18:25:00,475 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.90 Median:71.00 stddev:35.51 (50.0%) stddev-sans-first:37.07 18:25:00 INFO - 2016-01-14 18:25:00,475 DEBUG : BROWSER_OUTPUT: Values: 71.0 70.0 71.0 157.0 70.0 71.0 72.0 81.0 155.0 71.0 18:25:00 INFO - 2016-01-14 18:25:00,475 DEBUG : BROWSER_OUTPUT: 18:25:00 INFO - 2016-01-14 18:25:00,475 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.80 Median:89.00 stddev:23.63 (26.6%) stddev-sans-first:24.45 18:25:00 INFO - 2016-01-14 18:25:00,475 DEBUG : BROWSER_OUTPUT: Values: 89.0 90.0 135.0 89.0 89.0 90.0 146.0 89.0 89.0 132.0 18:25:00 INFO - 2016-01-14 18:25:00,475 DEBUG : BROWSER_OUTPUT: 18:25:00 INFO - 2016-01-14 18:25:00,475 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:25:00 INFO - 2016-01-14 18:25:00,475 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 110.0 109.0 109.0 110.0 109.0 109.0 18:25:00 INFO - 2016-01-14 18:25:00,476 DEBUG : BROWSER_OUTPUT: 18:25:00 INFO - 2016-01-14 18:25:00,476 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 18:25:00 INFO - 2016-01-14 18:25:00,476 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 146.0 147.0 146.0 146.0 145.0 147.0 145.0 146.0 18:25:00 INFO - 2016-01-14 18:25:00,476 DEBUG : BROWSER_OUTPUT: 18:25:00 INFO - 2016-01-14 18:25:00,476 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 18:25:00 INFO - 2016-01-14 18:25:00,476 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 95.0 96.0 96.0 95.0 96.0 96.0 95.0 18:25:00 INFO - 2016-01-14 18:25:00,476 DEBUG : BROWSER_OUTPUT: 18:25:00 INFO - 2016-01-14 18:25:00,476 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:1.05 (2.0%) stddev-sans-first:1.12 18:25:00 INFO - 2016-01-14 18:25:00,476 DEBUG : BROWSER_OUTPUT: Values: 54.0 54.0 56.0 54.0 54.0 55.0 54.0 54.0 52.0 53.0 18:25:00 INFO - 2016-01-14 18:25:00,476 DEBUG : BROWSER_OUTPUT: 18:25:00 INFO - 2016-01-14 18:25:00,476 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 18:25:00 INFO - 2016-01-14 18:25:00,477 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 63.0 64.0 63.0 63.0 63.0 62.0 64.0 63.0 18:25:00 INFO - 2016-01-14 18:25:00,477 DEBUG : BROWSER_OUTPUT: 18:25:00 INFO - 2016-01-14 18:25:00,477 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.80 Median:77.00 stddev:3.08 (4.0%) stddev-sans-first:3.10 18:25:00 INFO - 2016-01-14 18:25:00,477 DEBUG : BROWSER_OUTPUT: Values: 75.0 76.0 77.0 77.0 86.0 79.0 78.0 76.0 77.0 77.0 18:25:00 INFO - 2016-01-14 18:25:00,477 DEBUG : BROWSER_OUTPUT: 18:25:00 INFO - 2016-01-14 18:25:00,477 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:110.40 Median:111.00 stddev:2.59 (2.3%) stddev-sans-first:2.57 18:25:00 INFO - 2016-01-14 18:25:00,477 DEBUG : BROWSER_OUTPUT: Values: 113.0 116.0 108.0 108.0 111.0 111.0 109.0 111.0 108.0 109.0 18:25:00 INFO - 2016-01-14 18:25:00,477 DEBUG : BROWSER_OUTPUT: 18:25:00 INFO - 2016-01-14 18:25:00,477 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.00 Median:137.50 stddev:1.89 (1.4%) stddev-sans-first:2.00 18:25:00 INFO - 2016-01-14 18:25:00,477 DEBUG : BROWSER_OUTPUT: Values: 137.0 137.0 137.0 139.0 137.0 132.0 137.0 138.0 138.0 138.0 18:25:00 INFO - 2016-01-14 18:25:00,477 DEBUG : BROWSER_OUTPUT: 18:25:00 INFO - 2016-01-14 18:25:00,479 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.00 stddev:1.16 (1.8%) stddev-sans-first:1.13 18:25:00 INFO - 2016-01-14 18:25:00,479 DEBUG : BROWSER_OUTPUT: Values: 64.0 62.0 61.0 62.0 63.0 63.0 61.0 64.0 63.0 64.0 18:25:00 INFO - 2016-01-14 18:25:00,479 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:25:00 INFO - 2016-01-14 18:25:00,479 DEBUG : BROWSER_OUTPUT: 18:25:01 INFO - 2016-01-14 18:25:01,071 INFO : Browser exited with error code: 0 18:25:01 INFO - 2016-01-14 18:25:01,125 INFO : Completed test kraken (00:00:56) 18:25:01 INFO - 2016-01-14 18:25:01,125 INFO : Starting test v8_7 18:25:01 INFO - 2016-01-14 18:25:01,125 DEBUG : operating with platform_type : linux_ 18:25:01 INFO - 2016-01-14 18:25:01,125 INFO : Initialising browser for v8_7 test... 18:25:01 INFO - 2016-01-14 18:25:01,132 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpglB1xr/profile http://localhost:58095/getInfo.html 18:25:04 INFO - 2016-01-14 18:25:04,390 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:25:04 INFO - 2016-01-14 18:25:04,390 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:25:04 INFO - 2016-01-14 18:25:04,390 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:25:04 INFO - 2016-01-14 18:25:04,391 DEBUG : BROWSER_OUTPUT: __metrics 18:25:04 INFO - 2016-01-14 18:25:04,519 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] 18:25:05 INFO - 2016-01-14 18:25:05,586 INFO : Browser initialized. 18:25:05 INFO - 2016-01-14 18:25:05,587 INFO : Running cycle 1/1 for v8_7 test... 18:25:05 INFO - 2016-01-14 18:25:05,588 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpglB1xr/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:25:17 INFO - 2016-01-14 18:25:17,127 DEBUG : BROWSER_OUTPUT: RSS: Main: 128139264 18:25:17 INFO - 2016-01-14 18:25:17,127 DEBUG : BROWSER_OUTPUT: 18:25:38 INFO - 2016-01-14 18:25:38,529 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58095/tests/v8_7/run.html (next: http://localhost:58095/tests/v8_7/run.html) 18:25:38 INFO - 2016-01-14 18:25:38,779 DEBUG : BROWSER_OUTPUT: RSS: Main: 419016704 18:25:38 INFO - 2016-01-14 18:25:38,779 DEBUG : BROWSER_OUTPUT: 18:25:38 INFO - 2016-01-14 18:25:38,780 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:25:38 INFO - 2016-01-14 18:25:38,780 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:25:38 INFO - 2016-01-14 18:25:38,781 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:25:38 INFO - 2016-01-14 18:25:38,781 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:25:38 INFO - 2016-01-14 18:25:38,781 DEBUG : BROWSER_OUTPUT: |0;Richards;163.18537859007833 18:25:38 INFO - 2016-01-14 18:25:38,781 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.85255424568598 18:25:38 INFO - 2016-01-14 18:25:38,781 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.6420581655481 18:25:38 INFO - 2016-01-14 18:25:38,781 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5030.150753768844 18:25:38 INFO - 2016-01-14 18:25:38,781 DEBUG : BROWSER_OUTPUT: |4;RayTrace;963.3911368015414 18:25:38 INFO - 2016-01-14 18:25:38,782 DEBUG : BROWSER_OUTPUT: |5;Earley;563.063063063063 18:25:38 INFO - 2016-01-14 18:25:38,782 DEBUG : BROWSER_OUTPUT: |6;Boyer;10489.583333333334 18:25:38 INFO - 2016-01-14 18:25:38,782 DEBUG : BROWSER_OUTPUT: |7;RegExp;24238.095238095237 18:25:38 INFO - 2016-01-14 18:25:38,782 DEBUG : BROWSER_OUTPUT: |8;Splay;521.376433785193 18:25:38 INFO - 2016-01-14 18:25:38,782 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4817.307692307692 18:25:38 INFO - 2016-01-14 18:25:38,782 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:25:38 INFO - 2016-01-14 18:25:38,783 DEBUG : BROWSER_OUTPUT: __startTimestamp1452824738779__endTimestamp 18:25:38 INFO - 2016-01-14 18:25:38,783 DEBUG : BROWSER_OUTPUT: 18:25:38 INFO - 2016-01-14 18:25:38,783 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:25:38 INFO - 2016-01-14 18:25:38,783 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:25:38 INFO - 2016-01-14 18:25:38,784 DEBUG : BROWSER_OUTPUT: 18:25:38 INFO - 2016-01-14 18:25:38,784 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.19 Median:163.19 stddev:0.00 (0.0%) 18:25:38 INFO - 2016-01-14 18:25:38,784 DEBUG : BROWSER_OUTPUT: Values: 163.2 18:25:38 INFO - 2016-01-14 18:25:38,784 DEBUG : BROWSER_OUTPUT: 18:25:38 INFO - 2016-01-14 18:25:38,784 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.85 Median:170.85 stddev:0.00 (0.0%) 18:25:38 INFO - 2016-01-14 18:25:38,784 DEBUG : BROWSER_OUTPUT: Values: 170.9 18:25:38 INFO - 2016-01-14 18:25:38,785 DEBUG : BROWSER_OUTPUT: 18:25:38 INFO - 2016-01-14 18:25:38,785 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.64 Median:279.64 stddev:0.00 (0.0%) 18:25:38 INFO - 2016-01-14 18:25:38,785 DEBUG : BROWSER_OUTPUT: Values: 279.6 18:25:38 INFO - 2016-01-14 18:25:38,786 DEBUG : BROWSER_OUTPUT: 18:25:38 INFO - 2016-01-14 18:25:38,786 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5030.15 Median:5030.15 stddev:0.00 (0.0%) 18:25:38 INFO - 2016-01-14 18:25:38,786 DEBUG : BROWSER_OUTPUT: Values: 5030.2 18:25:38 INFO - 2016-01-14 18:25:38,787 DEBUG : BROWSER_OUTPUT: 18:25:38 INFO - 2016-01-14 18:25:38,787 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:963.39 Median:963.39 stddev:0.00 (0.0%) 18:25:38 INFO - 2016-01-14 18:25:38,787 DEBUG : BROWSER_OUTPUT: Values: 963.4 18:25:38 INFO - 2016-01-14 18:25:38,787 DEBUG : BROWSER_OUTPUT: 18:25:38 INFO - 2016-01-14 18:25:38,787 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:563.06 Median:563.06 stddev:0.00 (0.0%) 18:25:38 INFO - 2016-01-14 18:25:38,787 DEBUG : BROWSER_OUTPUT: Values: 563.1 18:25:38 INFO - 2016-01-14 18:25:38,787 DEBUG : BROWSER_OUTPUT: 18:25:38 INFO - 2016-01-14 18:25:38,788 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10489.58 Median:10489.58 stddev:0.00 (0.0%) 18:25:38 INFO - 2016-01-14 18:25:38,788 DEBUG : BROWSER_OUTPUT: Values: 10489.6 18:25:38 INFO - 2016-01-14 18:25:38,788 DEBUG : BROWSER_OUTPUT: 18:25:38 INFO - 2016-01-14 18:25:38,788 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24238.10 Median:24238.10 stddev:0.00 (0.0%) 18:25:38 INFO - 2016-01-14 18:25:38,788 DEBUG : BROWSER_OUTPUT: Values: 24238.1 18:25:38 INFO - 2016-01-14 18:25:38,788 DEBUG : BROWSER_OUTPUT: 18:25:38 INFO - 2016-01-14 18:25:38,788 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:521.38 Median:521.38 stddev:0.00 (0.0%) 18:25:38 INFO - 2016-01-14 18:25:38,788 DEBUG : BROWSER_OUTPUT: Values: 521.4 18:25:38 INFO - 2016-01-14 18:25:38,789 DEBUG : BROWSER_OUTPUT: 18:25:38 INFO - 2016-01-14 18:25:38,789 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4817.31 Median:4817.31 stddev:0.00 (0.0%) 18:25:38 INFO - 2016-01-14 18:25:38,789 DEBUG : BROWSER_OUTPUT: Values: 4817.3 18:25:38 INFO - 2016-01-14 18:25:38,789 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:25:38 INFO - 2016-01-14 18:25:38,789 DEBUG : BROWSER_OUTPUT: 18:25:39 INFO - 2016-01-14 18:25:39,435 INFO : Browser exited with error code: 0 18:25:39 INFO - 2016-01-14 18:25:39,453 INFO : Completed test v8_7 (00:00:38) 18:25:39 INFO - 2016-01-14 18:25:39,787 INFO : Completed test suite (00:20:04) 18:25:39 INFO - 2016-01-14 18:25:39,787 DEBUG : Working with test: dromaeo_css 18:25:39 INFO - 2016-01-14 18:25:39,787 DEBUG : Generating results file: dromaeo_css 18:25:39 INFO - 2016-01-14 18:25:39,789 DEBUG : Working with test: kraken 18:25:39 INFO - 2016-01-14 18:25:39,789 DEBUG : Generating results file: kraken 18:25:39 INFO - 2016-01-14 18:25:39,789 INFO : javascript benchmark 18:25:39 INFO - 2016-01-14 18:25:39,789 DEBUG : Working with test: v8_7 18:25:39 INFO - 2016-01-14 18:25:39,789 DEBUG : Generating results file: v8_7 18:25:39 INFO - 2016-01-14 18:25:39,790 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:25:39 INFO - 2016-01-14 18:25:39,846 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 18:25:39 INFO - 2016-01-14 18:25:39,847 DEBUG : process_Request line: dromaeo_css 7418.19 graph.html#tests=[[72,53,35]] 18:25:39 INFO - 2016-01-14 18:25:39,847 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:25:39 INFO - 2016-01-14 18:25:39,875 DEBUG : process_Request line: kraken 1457.90 graph.html#tests=[[232,53,35]] 18:25:39 INFO - 2016-01-14 18:25:39,875 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:25:39 INFO - 2016-01-14 18:25:39,997 DEBUG : process_Request line: v8_7 23230.56 graph.html#tests=[[230,53,35]] 18:25:39 INFO - 2016-01-14 18:25:39,998 INFO : javascript benchmark 18:25:40 INFO - 2016-01-14 18:25:40,002 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3232.9191770205744, 3279.245773472103, 3290.0787779421757, 3302.3230237801254, 3323.8504267307962, 5547.0570003099465, 5574.303212098488, 5582.91625625616, 5592.636478628891, 5606.943930560698, 5588.385277619459, 5595.440455954403, 5603.859903502404, 5606.159076138571, 5619.915701264485, 4988.401391832985, 5095.235714642806, 5104.974475127619, 5191.402988656297, 5194.246834209038, 5002.499750025005, 5103.5917126629965, 5112.41207261166, 5123.590112790967, 5166.896662066756, 5179.326687530615, 5182.974085129588, 5186.0665080285535, 5198.636095473318, 5205.817796377128, 5125.077486052509, 5132.871678208037, 5186.766595503212, 5203.115470370046, 5204.9739751301195, 4989.825356112555, 4994.700317980933, 5053.418856831475, 5093.643444958854, 5096.974515127437, 5440.537554307889, 5460.808871689472, 5469.042917489423, 5472.370677372113, 5485.286912701363, 5861.238039054938, 6089.482393996515, 6106.786262480836, 6109.0, 6111.877762444727, 5879.647221166743, 5898.557608179403, 5970.641761494324, 5993.370696076893, 5996.670183139926, 5652.265205523256, 5709.4290570942985, 5713.457221563945, 5718.59969802114, 5724.914126288109, 6046.939530604637, 6150.753969841246, 6180.072989051679, 6190.288116866618, 6194.256689197235, 5842.269716285465, 5941.346451890288, 5948.048312270016, 5949.405059494016, 5975.910361344583, 6047.213862197887, 6047.939520604826, 6058.0610005449225, 6058.485028772516, 6061.878762424727, 5754.079347304411, 5842.357340692521, 5875.9118613220835, 5931.11033344993, 5939.13882487037, 6047.03247480401, 6085.421884920926, 6087.117367981707, 6092.72582733778, 6095.116208149793, 4688.46082700487, 4693.084848454587, 4696.201645720235, 4697.459792123881, 4717.127331443694, 4075.49056367954, 4076.694247931417, 4139.234241665302, 4158.480189976253, 4183.644390226865, 5580.469855363734, 5608.186812912105, 5611.158326251013, 5685.914711279252, 5695.45893140151, 6092.482139018144, 6096.481799047131, 6102.206713127265, 6105.389461053948, 6114.0, 5555.2222688823595, 5572.86067848307, 5626.649604094988, 5651.915221271602, 5661.745221465044, 5226.97386513065, 5242.947570524322, 5244.580433565306, 5245.213218017328, 5248.081585722483, 6054.727537260834, 6207.068939658997, 6210.5652604317265, 6214.223222097238, 6218.471429928507, 5990.191324171181, 6053.576249662481, 6182.319944806158, 6183.505319574513, 6183.81448556544, 6262.5929314594405, 6286.717097730704, 6291.402316779899, 6293.685315734287, 6295.118683384421, 5769.278840144982, 5882.294124705063, 6028.939710603014, 6055.2430946131735, 6059.121427392915, 5980.820575382745, 6128.662923539248, 6129.724162412613, 6132.816015519542, 6138.662373569497, 5993.640381577119, 6002.279726432856, 6003.489703375088, 6009.248843894513, 6015.789447369321, 5673.914891276551, 5709.400512946247, 5726.627769194989, 5971.910421343597, 5995.700214989321, 5900.5574581907385, 5908.3500814911185, 5910.586258962004, 5915.40845915405, 5916.230889984275, 5939.46544810969, 5954.702264886825, 6001.609895356788, 6003.0695242237525, 6012.068129439944, 5608.8878222434505, 5697.886042279214, 5714.314282286152, 5794.246747922743, 5893.116032595145], "unit": "ms", "name": "dojo.html", "value": 5559.896771200544}, {"lowerIsBetter": false, "replicates": [591.4233622218337, 615.9876802463948, 637.5728262064416, 643.1831573901146, 646.1470858466826, 37534.8123259384, 44767.10465790682, 45632.3155152673, 45662.0, 45724.999999999956, 49712.75143624277, 50501.747491262584, 50524.74737626307, 50613.74693126529, 50635.493645063536, 8421.45260558065, 8565.271951884097, 9398.90601093987, 9593.712188634352, 10734.78530429391, 11077.723056923562, 11200.439978001108, 11213.7196570086, 11213.7196570086, 11225.158113141506, 11137.220394572383, 11150.442477876115, 11202.215844890861, 11224.943875280613, 11246.156538259622, 11145.387003714792, 11183.105351571854, 11200.271982321123, 11229.831552526719, 11260.718482037933, 10911.290766100257, 11091.0, 11091.112710983101, 11095.944520277428, 11097.722556936142, 45191.77404112958, 45981.770091149665, 46001.53998460006, 46060.76969615131, 46096.76951615254, 3731.272401881607, 3821.293060783764, 3944.585818489075, 4021.3163762160502, 4494.1461122386645, 33872.661273387195, 34349.141271468165, 34542.82728586341, 34595.827020864985, 34762.304753904784, 3782.1399501640262, 3848.615138486157, 3865.40086286626, 3942.743721658083, 3956.8812935611977, 329672.0, 331001.0, 331617.0, 332160.0, 333392.0, 68790.65604672044, 69999.65000174966, 78212.0, 79394.0, 79639.20360796434, 72849.2715072853, 72959.63520182365, 73036.26963730401, 73111.0, 73119.26880731231, 17215.483535493957, 17615.383461578782, 17637.7354339685, 17650.293988240326, 17658.823411765974, 13276.0, 13346.065775395824, 13372.06395552302, 13448.731025379439, 13463.663408414868, 23126.537469250856, 23439.76560234376, 23499.412514686926, 23518.294451166494, 23639.7636023641, 8502.65989360431, 8519.914800851911, 8619.310455163572, 8622.310215182895, 8809.779755506164, 1051.3376572759266, 1067.4449286371043, 1080.2276372393776, 1097.742030622803, 1121.9902088120593, 31121.68878311188, 31653.52519712206, 31852.681473185436, 31947.520787188212, 32377.51433728496, 1163.2787671643634, 1172.5485688010178, 1174.2426135142955, 1233.216907263894, 1234.4444999750149, 13643.522476713466, 16154.111523866066, 16291.185440727917, 16638.83361166373, 16950.660986780196, 403289.0, 405669.0, 406486.0, 407982.96008520364, 408327.0, 51914.48085519172, 52722.736386317825, 60716.69641651763, 61204.081938770956, 61259.38740612537, 1479.9186044767425, 1483.592012196633, 1496.4089184771954, 1500.7373709600777, 1503.1356969742224, 30913.536296955113, 31038.534421983237, 31209.687903121587, 31746.365072698878, 32711.0, 50229.246561300875, 50612.74693626508, 50621.746891265306, 50690.0, 50718.0, 249861.0, 250088.0, 252465.0, 252488.0, 252556.0, 32774.67225327716, 33340.499892501146, 33408.83295583507, 33479.16302092467, 33619.663803361655, 45714.54285457103, 48360.5163948356, 49259.26111108408, 49300.2604960933, 49394.75302623464, 40905.59094409018, 41419.1716165681, 41886.0, 41958.580414195465, 42595.787021064694, 41530.79234603808, 41799.164016720104, 42184.57815421806, 42671.57328426676, 43153.56846431496, 40981.590184097775, 40987.38518922159, 41424.0, 41493.37759933543, 41604.58395416128, 35013.8249308762, 37560.812195938845, 37751.2449751009, 37778.811105944296, 38406.80796595999, 28223.43553128967, 28267.85866070739, 28275.2931176714, 28573.0, 28574.285642859093], "unit": "ms", "name": "ext.html", "value": 21119.772076348305}, {"lowerIsBetter": false, "replicates": [385.7608282864624, 386.1504689682699, 389.92201559688067, 396.52813152348705, 401.11954260398403, 14082.804184952825, 18152.636947261046, 19401.805981940175, 19444.9027754861, 20753.377398678025, 20327.390178294674, 20329.18683252668, 20795.16819327229, 20818.16727330909, 20840.166393344287, 5822.796202132937, 5940.495057920082, 5949.6727679977585, 5952.55355848311, 5952.821415357546, 5213.2962050123315, 5336.119540275849, 5348.331458567679, 5928.318243402021, 5979.192808970777, 3862.1696335287907, 3970.50368703912, 5284.788608455658, 5290.021346050993, 5291.074062039147, 5072.061668591304, 5133.537981581676, 5171.198464238049, 5178.01617692641, 5182.041322355358, 4420.292753159479, 4492.8876778080485, 4517.887052823673, 4528.5245049270015, 4530.501644819067, 15698.136602486858, 15838.841611583852, 15943.282552285174, 15994.680106397924, 16092.59768505785, 2165.6859755335545, 2180.073468775764, 2191.4671180365444, 2198.362474882298, 2217.168561789329, 11218.831717524243, 11383.430828458544, 11397.943010284978, 11408.657740267723, 11432.942835285854, 1786.1337251433044, 1838.6322735452964, 1860.5999710062267, 1872.5880306332585, 1911.4838993471685, 69358.0, 69671.30328696698, 71817.28182718158, 71910.64044679796, 87483.0, 17296.0, 18055.909720451313, 18441.907790460962, 18547.814521854878, 18699.158537865558, 28212.43575128486, 28216.71783282141, 28556.85721571379, 28616.570751438747, 28640.85679571589, 9673.854892176623, 9911.10800028001, 9942.30403871722, 9986.800263994679, 10062.547185376676, 8500.65997360111, 8540.359473039547, 8551.914480855112, 8565.143485651384, 8598.39811213209, 8940.15068568485, 9040.18638322541, 9067.09329067088, 9081.818363632823, 9092.090660961272, 1645.3254165791968, 1678.127373765635, 2340.566995105911, 3315.5689760331, 3383.289509203095, 529.9284596579489, 546.158915270487, 551.3962211378534, 551.715866328841, 578.0260261459371, 12941.54704585335, 13091.672708182372, 13447.99140064499, 13600.795988060187, 13633.841123504415, 547.9232907392968, 552.8424399046253, 565.7878295639135, 573.627142357471, 575.0884847516733, 7228.783136505913, 7287.453440991947, 7341.963290183515, 7461.515001524884, 7534.434917381218, 503950.0, 544758.0, 549187.0, 614211.0, 638841.0, 47919.28121078187, 56975.0, 78437.0, 78603.21396786073, 78702.0, 599.5623195067537, 605.8636806718453, 606.7481994972126, 608.7838817219912, 611.843979785152, 12183.208091474025, 12348.0, 12395.814062788884, 12429.943454806442, 12489.37553122322, 40679.59320406758, 40903.795481022404, 41542.0, 41900.790496047324, 41907.161856763305, 55542.0, 56313.71843140758, 57665.0, 57847.42152578589, 58715.70642146762, 7441.739539116105, 7514.098308203051, 7551.207123251979, 7554.471187016856, 7571.962140189264, 13537.59387218385, 13583.52457663977, 13670.658233544242, 13741.03812733099, 13748.93125534366, 11148.554057837595, 11249.71875703114, 11584.0, 11993.520259189534, 12071.0, 11548.0761539075, 11550.59572914944, 11727.88272117302, 11823.467943942715, 11858.110641701734, 11670.183087183816, 11936.28382297065, 11941.343226122646, 12129.878701212874, 12216.450259738156, 7475.4019678426685, 7483.962580187064, 7531.8493630125995, 7555.471117021756, 7564.432667549846, 11786.0, 11849.822252666045, 11964.461599227881, 11966.623838258713, 12053.096017798876], "unit": "ms", "name": "jquery.html", "value": 8777.176307635627}, {"lowerIsBetter": false, "replicates": [1569.6108943584925, 1593.0282527658135, 1629.4215553478512, 1850.777906651202, 1947.211379391346, 7922.881156782646, 8108.432409731321, 8109.026916769981, 8110.067342255642, 8120.106788253287, 7224.277572242786, 7391.889121663179, 8018.959905200466, 8191.0989791123075, 8279.627416766245, 2433.355160882363, 2447.657327974085, 2474.4927289905527, 2475.9752402476015, 2478.479519300949, 2200.207925146946, 2415.215055107097, 2472.320111969205, 2484.8757562121914, 2490.402303447178, 2116.5449428372895, 2385.057902128658, 2434.9391265218333, 2436.561418944589, 2437.0251899240357, 2410.95178096438, 2414.082648593532, 2424.3939015246187, 2453.4970331082086, 2455.5702752018324, 2400.1479474786547, 2419.1532963462823, 2419.830611857171, 2461.8645974471397, 2519.924402267917, 8247.917520824774, 8305.792355191108, 8315.667373305061, 8315.916840831575, 8317.500949943023, 5028.647994640377, 5097.464766199532, 5109.182530795092, 5110.156824124015, 5110.156824124015, 3220.2754380264487, 3248.3340915112344, 3260.0382887048377, 3337.933241335184, 3358.5969683637877, 1058.227493929436, 1087.9401632910187, 1088.5319312695617, 1111.3887361950976, 1113.5323164271026, 11204.831927521094, 11246.212765106364, 11248.325100493914, 11262.380569068699, 11266.324020558875, 4668.8365907193765, 4715.22198837188, 4749.525047495292, 4761.833335833298, 4774.188387974053, 9654.903450965541, 9664.226861851035, 9664.71005869825, 9668.274879384075, 9674.08096230857, 5180.585553155738, 5180.611454140879, 5189.247559103909, 5192.9740351298005, 5193.428722840485, 4714.057188562301, 4742.549457801503, 4743.739094349844, 4748.501407352247, 4750.501197374295, 3971.0270983609203, 4000.4799376080923, 4016.6987475939422, 4025.1547175092974, 4039.939400908989, 1217.5860207529483, 1254.4605819497704, 1296.598054603076, 1336.6391074409855, 1361.156083228385, 1232.5562797392931, 1264.4373253902108, 1300.336828217616, 1545.65995480994, 1546.4741987724228, 7769.300762931363, 7970.322522585529, 7975.960120199478, 7984.960075199703, 7997.200279971957, 1174.301290732013, 1210.0, 1244.8941839943707, 1269.549309994957, 1272.4719241514854, 6380.904286435707, 6434.678266086677, 6478.0, 6486.189226346707, 6486.935130648728, 9677.564509597085, 9741.707748767549, 9787.559559819825, 9795.0, 9810.558524866397, 9510.09654082861, 9714.077162669535, 9744.415335079917, 9761.902380976242, 9769.316147869724, 7612.743897257002, 7663.731769388045, 7688.46180767341, 7713.4214933880185, 7763.417743669134, 7441.069866266716, 7645.426593005658, 7784.182660820758, 7850.6074696266105, 7852.41106916995, 8680.696175633822, 8850.291976641724, 8902.332325075517, 8926.598303076247, 8929.330300227639, 8834.29325653959, 9089.818203635758, 9098.090190980849, 9148.085191480799, 9179.449233046038, 7538.170801211752, 7613.0, 7627.0, 7630.0, 7634.045744281965, 7883.921160788319, 7922.881156782538, 7950.7217247396065, 7951.562664053301, 7959.562224077731, 6913.758018469531, 6914.9308506916295, 7611.924649634879, 7702.268284512962, 7707.537547747153, 7363.77908662741, 7494.962525187558, 7515.0, 7620.085589729268, 7636.389088872988, 7598.430117741302, 7632.542047477169, 7632.580208088609, 7641.617919103911, 7648.502847314907, 6844.110265665432, 6906.654667266717, 6910.343517365842, 6934.479914006369, 6934.653267336714, 7183.856322873408, 7211.71153153868, 7218.675159617725, 7221.494495385273, 7221.602811845399], "unit": "ms", "name": "mootools.html", "value": 4839.996220958347}, {"lowerIsBetter": false, "replicates": [370.27611020455015, 377.6789728730578, 379.04858804400965, 379.5218025288136, 381.8510780795492, 5857.6485410875375, 5991.730372133265, 6000.519958403328, 6040.003399439092, 6041.3354531001605, 5837.678927658988, 5897.587168898179, 5916.704164791754, 5936.584439089254, 5962.04607262838, 4681.74250416227, 4684.250519916823, 4689.812407503696, 4695.765211739416, 4702.788374523155, 4627.329037289591, 4675.625949924015, 4687.718736875781, 4687.742174180418, 4690.882727931795, 4571.268597024477, 4641.582257596816, 4655.4180727409075, 4707.882302942419, 4708.529147085298, 4704.153252414564, 4706.505816889246, 4724.4094488188975, 4724.929126063112, 4728.40894888139, 4023.1350259694304, 4208.74747515147, 4209.34755112958, 4214.072903961123, 4215.578442155791, 5572.944270557324, 5614.63504872182, 5670.914936275959, 5673.914891276634, 5696.857578560528, 5338.492843179892, 5358.3837858645975, 5359.437259087778, 5362.570994320484, 5363.249145119687, 6200.596961197508, 6203.720832562501, 6205.658688772116, 6208.348123447064, 6218.937810621881, 4495.57292057254, 4577.633789296849, 4581.289900065495, 4593.586577208067, 4638.466576343696, 6534.836629084263, 6547.247066587356, 6548.214214294268, 6559.442447391977, 6577.04632828242, 4777.976110119427, 4780.49804770501, 4784.330193772875, 4786.44955830084, 4786.497417771084, 4741.407324084489, 4813.205821039559, 4816.253480710496, 4823.927641085386, 4826.372571565674, 5430.701311427831, 5432.891342173134, 5444.346678398618, 5445.074337362658, 5447.319085114361, 6302.968485157545, 6318.557700960981, 6319.33646967071, 6330.68346582669, 6333.493320534346, 1738.3568079810298, 1756.0517320646964, 1760.1111438723362, 1760.172718822177, 1760.4454596801966, 1178.3048001679051, 1199.310396521986, 1199.9340036298088, 1211.0372254058045, 1226.4480983557435, 6096.512279017668, 6104.389561043949, 6112.633242005494, 6116.938830611726, 6117.908231376533, 6488.124103246096, 6495.902561461582, 6527.0, 6538.1500404948, 6548.607083575001, 5975.551833612496, 6003.699815009232, 6080.969595151996, 6084.269887613515, 6086.452219300284, 3588.0312315674623, 3610.422332426799, 3621.4748861414946, 3623.710103191738, 3627.7460577759302, 6511.348865113546, 6575.309592492815, 6642.86714265712, 6704.363085506869, 6730.865382692319, 6663.966680166568, 6670.933290667128, 6685.39831415175, 6688.866222675617, 6690.698918548677, 6712.0, 6717.193936727526, 6719.361660642231, 6726.899096513556, 6730.158730158632, 6399.77600783989, 6502.967485162544, 6526.771562995273, 6535.705893234911, 6537.5096867734155, 6484.059811327237, 6499.902501462388, 6516.73933042673, 6521.02184672303, 6523.478121750154, 6805.0472933788315, 6809.046733457221, 6812.591244525344, 6817.761378351734, 6821.795346139624, 4594.655400844883, 4692.41344831896, 4696.319033740157, 4696.365990591363, 4699.741514216751, 6587.2753997061245, 6589.868202636017, 6595.604263744191, 6605.735770569124, 6609.504287178384, 6388.712507937061, 6409.230892292955, 6410.038494225717, 6411.070394792636, 6418.775342862978, 6292.056191571301, 6395.840103997251, 6399.168108146097, 6400.583962042452, 6404.679766011587, 6236.314005459305, 6296.496280297657, 6300.905486417616, 6301.0863424804065, 6308.022256550242, 6408.29508754046, 6441.420272175339, 6448.032795080588, 6449.838754031187, 6449.838754031187, 6183.84540386494, 6280.654563999024, 6286.905696414466, 6288.779892703734, 6289.245290565162], "unit": "ms", "name": "prototype.html", "value": 4879.5636394913845}, {"lowerIsBetter": false, "replicates": [889.186394449079, 977.1108291454773, 977.379364103794, 987.891331953485, 997.8553109799075, 13731.17612943224, 14227.075240109385, 14231.999999999987, 15858.603534911634, 16239.594010149753, 15606.609834754137, 15650.765238521431, 15694.921525392358, 15756.527304180865, 15812.0, 786.0174781523068, 798.5368486277974, 922.3220932614523, 924.791921817589, 936.0405584276137, 912.0742446416892, 918.7014220378404, 919.7562645898843, 920.7836158502746, 920.8710780490703, 980.0248752491245, 991.9404835709845, 993.9652112176075, 994.0357852882728, 994.3735446668588, 806.4959400374765, 819.1071731812324, 980.2011360741009, 997.1225321716843, 1001.0990108901974, 719.0364911019207, 724.3770357492552, 731.9414446844293, 732.4982387064878, 732.5275197497638, 14811.925940370229, 14822.777658335133, 14874.405023799036, 14915.403383864632, 14932.47736329234, 12462.252264864137, 12621.305828179446, 13006.284654344006, 13759.10565813219, 13810.792838107436, 11149.331040137537, 11203.159763017806, 11378.431078446045, 11746.412679366083, 11798.115141364346, 3955.4462375267485, 3968.3253846846105, 3980.6019398060253, 4019.376996565537, 4101.384792281182, 52241.47758522442, 52860.0, 52899.47100529022, 53369.93260134814, 53373.0, 10473.528691208914, 10478.109360704426, 10525.578976840994, 10730.517126729315, 10775.084117849914, 21428.03573839181, 21624.351269461942, 21700.891495542735, 21710.45723856916, 21732.13071477155, 7950.363970882431, 8019.5990200490905, 8098.83802323962, 8178.386621003329, 8196.262336389753, 4495.988402609452, 4550.34020067095, 4563.38394316763, 4572.908541829212, 4752.025834703877, 3447.6897079262985, 3462.0306314232057, 3473.600535938349, 3491.860325586948, 3497.772644778132, 504.4173979054125, 516.431924882626, 518.7587771686184, 523.8376103407955, 527.2828952624428, 620.0729908786303, 634.8000379880323, 642.3640595810115, 663.3466035954581, 667.3893387550337, 8770.3422243332, 9022.413543119676, 9079.909200907907, 9187.81624367509, 9273.165415112673, 620.6865532905854, 661.5865084322298, 671.2046225223127, 674.0933444517117, 685.2496516314627, 4354.716943398669, 4364.301711726109, 4404.141192467502, 4432.601065904084, 4538.432695913011, 64301.35698643047, 64368.0, 64432.67783661051, 65216.0, 65438.0, 56796.71601642048, 57392.426075739546, 57429.71285143548, 57555.42444575501, 58453.41546584565, 50571.0, 50878.23682644689, 50949.7452512735, 51271.74364128155, 51770.74114629478, 9260.53697315118, 9510.716053332711, 9531.761705957406, 9693.660721874983, 9715.222782177267, 39483.0, 40296.597034030456, 40910.1817963645, 41018.79490602528, 41130.38304425496, 48148.75925620349, 53577.0, 53617.0, 54055.0, 54206.18690719563, 8850.933999371788, 8982.11631109782, 9038.502882341534, 9170.724878253663, 9343.766405839908, 13123.803142953055, 13323.800142997668, 13423.932880335537, 13505.932470337979, 13562.525311614047, 10274.383536987805, 10308.814486333868, 10544.525496352837, 10623.521941512496, 10923.945380273048, 11692.532298708296, 12172.330521821385, 12227.266364018187, 12229.0, 12282.38588070575, 10983.61557345489, 11681.0, 11728.530858765555, 11900.523979040741, 12368.072394570623, 10148.0, 10868.891311087104, 10961.890381096087, 11045.502952367002, 11099.334039957628, 9959.900400995897, 10173.389596624227, 10270.229732769923, 10496.107830834464, 10519.368837869752], "unit": "ms", "name": "yui.html", "value": 6845.591178729645}], "name": "dromaeo_css", "value": 7418.187389654831}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 105.0, 110.0, 112.0, 114.0, 102.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 109.3}, {"lowerIsBetter": true, "replicates": [149.0, 120.0, 147.0, 163.0, 122.0, 151.0, 149.0, 122.0, 160.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.7}, {"lowerIsBetter": true, "replicates": [160.0, 159.0, 159.0, 159.0, 158.0, 159.0, 159.0, 161.0, 161.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 159.5}, {"lowerIsBetter": true, "replicates": [71.0, 70.0, 71.0, 157.0, 70.0, 71.0, 72.0, 81.0, 155.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 88.9}, {"lowerIsBetter": true, "replicates": [89.0, 90.0, 135.0, 89.0, 89.0, 90.0, 146.0, 89.0, 89.0, 132.0], "unit": "ms", "name": "audio-oscillator", "value": 103.8}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 145.0, 147.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.0}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [54.0, 54.0, 56.0, 54.0, 54.0, 55.0, 54.0, 54.0, 52.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 54.0}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 63.0, 64.0, 63.0, 63.0, 63.0, 62.0, 64.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.1}, {"lowerIsBetter": true, "replicates": [75.0, 76.0, 77.0, 77.0, 86.0, 79.0, 78.0, 76.0, 77.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.8}, {"lowerIsBetter": true, "replicates": [113.0, 116.0, 108.0, 108.0, 111.0, 111.0, 109.0, 111.0, 108.0, 109.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 110.4}, {"lowerIsBetter": true, "replicates": [137.0, 137.0, 137.0, 139.0, 137.0, 132.0, 137.0, 138.0, 138.0, 138.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 137.0}, {"lowerIsBetter": true, "replicates": [64.0, 62.0, 61.0, 62.0, 63.0, 63.0, 61.0, 64.0, 63.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 62.7}], "name": "kraken", "value": 1457.9}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.18537859007833], "unit": "score", "name": "Richards", "value": 216.33065600000006}, {"lowerIsBetter": false, "replicates": [170.85255424568598], "unit": "score", "name": "DeltaBlue", "value": 386.9886539999999}, {"lowerIsBetter": false, "replicates": [279.6420581655481], "unit": "score", "name": "Encrypt", "value": 951.8632560000003}, {"lowerIsBetter": false, "replicates": [5030.150753768844], "unit": "score", "name": "Decrypt", "value": 52.91710189810189}, {"lowerIsBetter": false, "replicates": [963.3911368015414], "unit": "score", "name": "RayTrace", "value": 768.1085820000003}, {"lowerIsBetter": false, "replicates": [563.063063063063], "unit": "score", "name": "Earley", "value": 1183.638288}, {"lowerIsBetter": false, "replicates": [10489.583333333334], "unit": "score", "name": "Boyer", "value": 63.5356981132075}, {"lowerIsBetter": false, "replicates": [24238.095238095237], "unit": "score", "name": "RegExp", "value": 37.5848428290766}, {"lowerIsBetter": false, "replicates": [521.376433785193], "unit": "score", "name": "Splay", "value": 156.29973800000002}, {"lowerIsBetter": false, "replicates": [4817.307692307692], "unit": "score", "name": "NavierStokes", "value": 308.055888223553}], "name": "v8_7", "value": 23230.5637433381}]} 18:25:40 INFO - RETURN: dromaeo_css: 7418.19 18:25:40 INFO - RETURN: kraken: 1457.90 18:25:40 INFO - RETURN: v8_7: 23230.56 18:25:40 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23230.56"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1457.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7418.19"}}} 18:25:40 INFO - Return code: 0 18:25:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 18:25:40 INFO - # TBPL SUCCESS # 18:25:40 INFO - Running post-action listener: _resource_record_post_action 18:25:40 INFO - Running post-run listener: _resource_record_post_run 18:25:41 INFO - Total resource usage - Wall time: 1220s; CPU: 13.0%; Read bytes: 315392; Write bytes: 498520064; Read time: 48; Write time: 492320 18:25:41 INFO - install - Wall time: 14s; CPU: 15.0%; Read bytes: 0; Write bytes: 234496000; Read time: 0; Write time: 322196 18:25:41 INFO - run-tests - Wall time: 1206s; CPU: 13.0%; Read bytes: 315392; Write bytes: 264024064; Read time: 48; Write time: 170124 18:25:41 INFO - Running post-run listener: _upload_blobber_files 18:25:41 INFO - Blob upload gear active. 18:25:41 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:25:41 INFO - Copying logs to upload dir... 18:25:41 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1269.428684 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-01-14 18:25:40.245465) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-14 18:25:40.248938) ========= 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=33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010008 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452814920/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.02 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-14 18:25:40.279438) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 18:25:40.279772) ========= 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=33d5923976266a1c518c4bf7000001ab-1452821261.874063-698650733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004537 ========= master_lag: 0.43 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 18:25:40.715484) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-14 18:25:40.718833) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-14 18:25:40.725862) ========= ========= Total master_lag: 0.68 =========