builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-029 starttime: 1456418295.8 results: success (0) buildid: 20160225061532 builduid: d46e6d6001da41c28cab5a30b6f08297 revision: 80ce3f1ffe03 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:15.800357) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:15.800872) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:15.801212) ========= 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=0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.008614 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:15.823801) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:15.824083) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:15.837130) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:15.837432) ========= 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=0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004592 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:15.886091) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:15.886428) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:15.886804) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:15.887097) ========= 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=0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-25 08:38:16-- 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% 30.0M=0s 2016-02-25 08:38:16 (30.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097626 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:15.997170) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:15.997497) ========= 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=0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022636 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:16.048140) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:16.048534) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 80ce3f1ffe03 --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 80ce3f1ffe03 --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=0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-25 08:38:16,403 Setting DEBUG logging. 2016-02-25 08:38:16,403 attempt 1/10 2016-02-25 08:38:16,403 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/80ce3f1ffe03?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-25 08:38:17,050 unpacking tar archive at: mozilla-beta-80ce3f1ffe03/testing/mozharness/ program finished with exit code 0 elapsedTime=0.983050 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:17.045273) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:17.045616) ========= echo 80ce3f1ffe03 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'80ce3f1ffe03'] 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=0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 80ce3f1ffe03 program finished with exit code 0 elapsedTime=0.003762 script_repo_revision: '80ce3f1ffe03' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:17.094106) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:17.094478) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 08:38:17.104485) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-02-25 08:38:17.104874) ========= /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=0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:38:17 INFO - MultiFileLogger online at 20160225 08:38:17 in /builds/slave/test-pgo 08:38:17 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 08:38:17 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 08:38:17 INFO - {'append_to_log': False, 08:38:17 INFO - 'base_work_dir': '/builds/slave/test-pgo', 08:38:17 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:38:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 08:38:17 INFO - 'branch': 'Mozilla-Beta', 08:38:17 INFO - 'buildbot_json_path': 'buildprops.json', 08:38:17 INFO - 'config_files': ('talos/linux_config.py',), 08:38:17 INFO - 'default_actions': ('clobber', 08:38:17 INFO - 'read-buildbot-config', 08:38:17 INFO - 'download-and-extract', 08:38:17 INFO - 'populate-webroot', 08:38:17 INFO - 'create-virtualenv', 08:38:17 INFO - 'install', 08:38:17 INFO - 'run-tests'), 08:38:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:38:17 INFO - 'download_minidump_stackwalk': True, 08:38:17 INFO - 'download_symbols': 'ondemand', 08:38:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:38:17 INFO - 'tooltool.py': '/tools/tooltool.py', 08:38:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:38:17 INFO - '/tools/misc-python/virtualenv.py')}, 08:38:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:38:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:38:17 INFO - 'installer_path': 'installer.exe', 08:38:17 INFO - 'log_level': 'info', 08:38:17 INFO - 'log_name': 'talos', 08:38:17 INFO - 'log_to_console': True, 08:38:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:38:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:38:17 INFO - 'opt_config_files': (), 08:38:17 INFO - 'pip_index': False, 08:38:17 INFO - 'sps_profile': False, 08:38:17 INFO - 'sps_profile_interval': 0, 08:38:17 INFO - 'suite': 'dromaeojs', 08:38:17 INFO - 'system_bits': '32', 08:38:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:38:17 INFO - 'title': 'talos-linux64-ix-029', 08:38:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:38:17 INFO - 'use_talos_json': True, 08:38:17 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 08:38:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:38:17 INFO - 'work_dir': 'build'} 08:38:17 INFO - ##### 08:38:17 INFO - ##### Running clobber step. 08:38:17 INFO - ##### 08:38:17 INFO - Running pre-action listener: _resource_record_pre_action 08:38:17 INFO - Running main action method: clobber 08:38:17 INFO - rmtree: /builds/slave/test-pgo/build 08:38:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 08:38:18 INFO - Running post-action listener: _resource_record_post_action 08:38:18 INFO - ##### 08:38:18 INFO - ##### Running read-buildbot-config step. 08:38:18 INFO - ##### 08:38:18 INFO - Running pre-action listener: _resource_record_pre_action 08:38:18 INFO - Running main action method: read_buildbot_config 08:38:18 INFO - Using buildbot properties: 08:38:18 INFO - { 08:38:18 INFO - "properties": { 08:38:18 INFO - "buildnumber": 96, 08:38:18 INFO - "product": "firefox", 08:38:18 INFO - "script_repo_revision": "production", 08:38:18 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 08:38:18 INFO - "repository": "", 08:38:18 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 08:38:18 INFO - "buildid": "20160225061532", 08:38:18 INFO - "slavename": "talos-linux64-ix-029", 08:38:18 INFO - "pgo_build": "True", 08:38:18 INFO - "basedir": "/builds/slave/test-pgo", 08:38:18 INFO - "project": "", 08:38:18 INFO - "platform": "ubuntu64_hw", 08:38:18 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:38:18 INFO - "slavebuilddir": "test", 08:38:18 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 08:38:18 INFO - "branch": "mozilla-beta", 08:38:18 INFO - "repo_path": "releases/mozilla-beta", 08:38:18 INFO - "stage_platform": "linux64-pgo", 08:38:18 INFO - "builduid": "d46e6d6001da41c28cab5a30b6f08297", 08:38:18 INFO - "revision": "80ce3f1ffe03" 08:38:18 INFO - }, 08:38:18 INFO - "sourcestamp": { 08:38:18 INFO - "repository": "", 08:38:18 INFO - "hasPatch": false, 08:38:18 INFO - "project": "", 08:38:18 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 08:38:18 INFO - "changes": [ 08:38:18 INFO - { 08:38:18 INFO - "category": null, 08:38:18 INFO - "files": [ 08:38:18 INFO - { 08:38:18 INFO - "url": null, 08:38:18 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.tar.bz2" 08:38:18 INFO - } 08:38:18 INFO - ], 08:38:18 INFO - "repository": "", 08:38:18 INFO - "rev": "80ce3f1ffe03", 08:38:18 INFO - "who": "sendchange", 08:38:18 INFO - "when": 1456418278, 08:38:18 INFO - "number": 7653600, 08:38:18 INFO - "comments": "Bug 1228754, r=mak r=bz, a=sylvestre", 08:38:18 INFO - "project": "", 08:38:18 INFO - "at": "Thu 25 Feb 2016 08:37:58", 08:38:18 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 08:38:18 INFO - "revlink": "", 08:38:18 INFO - "properties": [ 08:38:18 INFO - [ 08:38:18 INFO - "buildid", 08:38:18 INFO - "20160225061532", 08:38:18 INFO - "Change" 08:38:18 INFO - ], 08:38:18 INFO - [ 08:38:18 INFO - "builduid", 08:38:18 INFO - "d46e6d6001da41c28cab5a30b6f08297", 08:38:18 INFO - "Change" 08:38:18 INFO - ], 08:38:18 INFO - [ 08:38:18 INFO - "pgo_build", 08:38:18 INFO - "True", 08:38:18 INFO - "Change" 08:38:18 INFO - ] 08:38:18 INFO - ], 08:38:18 INFO - "revision": "80ce3f1ffe03" 08:38:18 INFO - } 08:38:18 INFO - ], 08:38:18 INFO - "revision": "80ce3f1ffe03" 08:38:18 INFO - } 08:38:18 INFO - } 08:38:18 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.tar.bz2. 08:38:18 INFO - Running post-action listener: _resource_record_post_action 08:38:18 INFO - ##### 08:38:18 INFO - ##### Running download-and-extract step. 08:38:18 INFO - ##### 08:38:18 INFO - Running pre-action listener: _resource_record_pre_action 08:38:18 INFO - Running main action method: download_and_extract 08:38:18 INFO - mkdir: /builds/slave/test-pgo/build/tests 08:38:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:18 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/test_packages.json 08:38:18 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 08:38:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 08:38:18 INFO - Downloaded 1270 bytes. 08:38:18 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 08:38:18 INFO - Using the following test package requirements: 08:38:18 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:38:18 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:38:18 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 08:38:18 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:38:18 INFO - u'jsshell-linux-x86_64.zip'], 08:38:18 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:38:18 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 08:38:18 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:38:18 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:38:18 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 08:38:18 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:38:18 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 08:38:18 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:38:18 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 08:38:18 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:38:18 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:38:18 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 08:38:18 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:38:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:18 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:38:18 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:38:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:38:18 INFO - Downloaded 21583409 bytes. 08:38:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 08:38:18 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:38:18 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 08:38:19 INFO - Return code: 0 08:38:19 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:38:19 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')]} 08:38:19 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:38:19 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:38:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:38:20 INFO - Downloaded 21583409 bytes. 08:38:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 08:38:20 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:38:20 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 08:38:21 INFO - Return code: 0 08:38:21 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')]} 08:38:21 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 08:38:21 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 08:38:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:38:23 INFO - Downloaded 11132038 bytes. 08:38:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 08:38:23 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 08:38:23 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 08:38:23 INFO - Return code: 0 08:38:23 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')]} 08:38:23 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:38:23 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:38:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:38:27 INFO - Downloaded 57938789 bytes. 08:38:27 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:38:27 INFO - mkdir: /builds/slave/test-pgo/properties 08:38:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 08:38:27 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 08:38:27 INFO - Contents: 08:38:27 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:38:27 INFO - Running post-action listener: _resource_record_post_action 08:38:27 INFO - Running post-action listener: set_extra_try_arguments 08:38:27 INFO - ##### 08:38:27 INFO - ##### Running populate-webroot step. 08:38:27 INFO - ##### 08:38:27 INFO - Running pre-action listener: _resource_record_pre_action 08:38:27 INFO - Running main action method: populate_webroot 08:38:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:38:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:38:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:38:27 INFO - '--tppagecycles', 08:38:27 INFO - '7'], 08:38:27 INFO - 'tests': ['tsvgm']}}, 08:38:27 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 08:38:27 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 08:38:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:38:27 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:38:27 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 08:38:27 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:38:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:38:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:27 INFO - 'talos_options': ['--e10s'], 08:38:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:38:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:27 INFO - 'tests': ['damp', 'tps']}, 08:38:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:27 INFO - 'talos_options': ['--e10s'], 08:38:27 INFO - 'tests': ['damp', 'tps']}, 08:38:27 INFO - 'g3': {'tests': ['dromaeo_dom']}, 08:38:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:38:27 INFO - 'other': {'tests': ['a11yr', 08:38:27 INFO - 'ts_paint', 08:38:27 INFO - 'tpaint', 08:38:27 INFO - 'sessionrestore', 08:38:27 INFO - 'sessionrestore_no_auto_restore']}, 08:38:27 INFO - 'other-e10s': {'talos_options': ['--e10s'], 08:38:27 INFO - 'tests': ['a11yr', 08:38:27 INFO - 'ts_paint', 08:38:27 INFO - 'tpaint', 08:38:27 INFO - 'sessionrestore', 08:38:27 INFO - 'sessionrestore_no_auto_restore']}, 08:38:27 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 08:38:27 INFO - 'tests': ['a11yr', 08:38:27 INFO - 'ts_paint', 08:38:27 INFO - 'tpaint', 08:38:27 INFO - 'sessionrestore', 08:38:27 INFO - 'sessionrestore_no_auto_restore']}, 08:38:27 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 08:38:27 INFO - 'tests': ['a11yr', 08:38:27 INFO - 'ts_paint', 08:38:27 INFO - 'tpaint', 08:38:27 INFO - 'sessionrestore', 08:38:27 INFO - 'sessionrestore_no_auto_restore']}, 08:38:27 INFO - 'other_l64': {'tests': ['a11yr', 08:38:27 INFO - 'ts_paint', 08:38:27 INFO - 'tpaint', 08:38:27 INFO - 'sessionrestore', 08:38:27 INFO - 'sessionrestore_no_auto_restore']}, 08:38:27 INFO - 'other_nol64': {'tests': ['a11yr', 08:38:27 INFO - 'ts_paint', 08:38:27 INFO - 'tpaint', 08:38:27 INFO - 'sessionrestore', 08:38:27 INFO - 'sessionrestore_no_auto_restore']}, 08:38:27 INFO - 'svgr': {'tests': ['tsvgx', 08:38:27 INFO - 'tsvgr_opacity', 08:38:27 INFO - 'tart', 08:38:27 INFO - 'tscrollx', 08:38:27 INFO - 'cart']}, 08:38:27 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 08:38:27 INFO - 'tests': ['tsvgx', 08:38:27 INFO - 'tsvgr_opacity', 08:38:27 INFO - 'tart', 08:38:27 INFO - 'tscrollx', 08:38:27 INFO - 'cart']}, 08:38:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:27 INFO - 'tests': ['tp5o']}, 08:38:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:27 INFO - 'talos_options': ['--e10s'], 08:38:27 INFO - 'tests': ['tp5o']}, 08:38:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:27 INFO - 'talos_options': ['--xperf_path', 08:38:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:38:27 INFO - 'tests': ['tp5n']}, 08:38:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:27 INFO - 'talos_options': ['--e10s', 08:38:27 INFO - '--xperf_path', 08:38:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:38:27 INFO - 'tests': ['tp5n']}}, 08:38:27 INFO - 'talos.zip': {'path': '', 08:38:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:38:27 INFO - Running post-action listener: _resource_record_post_action 08:38:27 INFO - ##### 08:38:27 INFO - ##### Running create-virtualenv step. 08:38:27 INFO - ##### 08:38:27 INFO - Running pre-action listener: _resource_record_pre_action 08:38:27 INFO - Running main action method: create_virtualenv 08:38:27 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 08:38:27 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 08:38:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 08:38:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:38:27 INFO - Using real prefix '/usr' 08:38:27 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 08:38:28 INFO - Installing distribute.............................................................................................................................................................................................done. 08:38:30 INFO - Installing pip.................done. 08:38:30 INFO - Return code: 0 08:38:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 08:38:30 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')]} 08:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:30 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')]} 08:38:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:30 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fa27502bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c39600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8350>, '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 0x2cc9250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc96e0>, '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': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', '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 08:38:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 08:38:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 08:38:30 INFO - Using env: {'DISPLAY': ':0', 08:38:30 INFO - 'HOME': '/home/cltbld', 08:38:30 INFO - 'LANG': 'en_US.UTF-8', 08:38:30 INFO - 'LANGUAGE': 'en_US:en', 08:38:30 INFO - 'LOGNAME': 'cltbld', 08:38:30 INFO - 'MAIL': '/var/mail/cltbld', 08:38:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:30 INFO - 'MOZ_NO_REMOTE': '1', 08:38:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:30 INFO - 'NO_EM_RESTART': '1', 08:38:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:38:30 INFO - 'PWD': '/builds/slave/test-pgo', 08:38:30 INFO - 'SHELL': '/bin/bash', 08:38:30 INFO - 'SHLVL': '1', 08:38:30 INFO - 'TERM': 'linux', 08:38:30 INFO - 'TMOUT': '86400', 08:38:30 INFO - 'USER': 'cltbld', 08:38:30 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', 08:38:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:30 INFO - '_': '/tools/buildbot/bin/python'} 08:38:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:30 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 08:38:30 INFO - Cleaning up... 08:38:30 INFO - Return code: 0 08:38:30 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 08:38:30 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')]} 08:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:30 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')]} 08:38:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:30 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fa27502bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c39600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8350>, '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 0x2cc9250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc96e0>, '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': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', '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 08:38:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 08:38:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 08:38:30 INFO - Using env: {'DISPLAY': ':0', 08:38:30 INFO - 'HOME': '/home/cltbld', 08:38:30 INFO - 'LANG': 'en_US.UTF-8', 08:38:30 INFO - 'LANGUAGE': 'en_US:en', 08:38:30 INFO - 'LOGNAME': 'cltbld', 08:38:30 INFO - 'MAIL': '/var/mail/cltbld', 08:38:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:30 INFO - 'MOZ_NO_REMOTE': '1', 08:38:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:30 INFO - 'NO_EM_RESTART': '1', 08:38:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:38:30 INFO - 'PWD': '/builds/slave/test-pgo', 08:38:30 INFO - 'SHELL': '/bin/bash', 08:38:30 INFO - 'SHLVL': '1', 08:38:30 INFO - 'TERM': 'linux', 08:38:30 INFO - 'TMOUT': '86400', 08:38:30 INFO - 'USER': 'cltbld', 08:38:30 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', 08:38:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:30 INFO - '_': '/tools/buildbot/bin/python'} 08:38:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:30 INFO - Downloading/unpacking psutil>=0.7.1 08:38:30 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 08:38:30 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 08:38:30 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 08:38:30 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 08:38:30 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 08:38:30 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 08:38:32 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 08:38:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:38:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:38:32 INFO - Installing collected packages: psutil 08:38:32 INFO - Running setup.py install for psutil 08:38:32 INFO - building 'psutil._psutil_linux' extension 08:38:32 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 08:38:32 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 08:38:32 INFO - building 'psutil._psutil_posix' extension 08:38:32 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 08:38:32 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 08:38:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:38:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:38:32 INFO - Successfully installed psutil 08:38:32 INFO - Cleaning up... 08:38:32 INFO - Return code: 0 08:38:32 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 08:38: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')]} 08:38:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38: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')]} 08:38:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:32 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fa27502bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c39600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8350>, '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 0x2cc9250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc96e0>, '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': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', '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 08:38:32 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 08:38:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 08:38:32 INFO - Using env: {'DISPLAY': ':0', 08:38:32 INFO - 'HOME': '/home/cltbld', 08:38:32 INFO - 'LANG': 'en_US.UTF-8', 08:38:32 INFO - 'LANGUAGE': 'en_US:en', 08:38:32 INFO - 'LOGNAME': 'cltbld', 08:38:32 INFO - 'MAIL': '/var/mail/cltbld', 08:38:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:32 INFO - 'MOZ_NO_REMOTE': '1', 08:38:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:32 INFO - 'NO_EM_RESTART': '1', 08:38:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:38:32 INFO - 'PWD': '/builds/slave/test-pgo', 08:38:32 INFO - 'SHELL': '/bin/bash', 08:38:32 INFO - 'SHLVL': '1', 08:38:32 INFO - 'TERM': 'linux', 08:38:32 INFO - 'TMOUT': '86400', 08:38:32 INFO - 'USER': 'cltbld', 08:38:32 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', 08:38:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:32 INFO - '_': '/tools/buildbot/bin/python'} 08:38:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:33 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:38:33 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 08:38:33 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 08:38:33 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 08:38:33 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 08:38:33 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 08:38:33 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 08:38:34 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:38:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:38:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:38:35 INFO - Installing collected packages: mozsystemmonitor 08:38:35 INFO - Running setup.py install for mozsystemmonitor 08:38:35 INFO - Successfully installed mozsystemmonitor 08:38:35 INFO - Cleaning up... 08:38:35 INFO - Return code: 0 08:38:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 08:38: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')]} 08:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38: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')]} 08:38:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:35 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fa27502bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c39600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8350>, '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 0x2cc9250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc96e0>, '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': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', '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 08:38:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 08:38:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 08:38:35 INFO - Using env: {'DISPLAY': ':0', 08:38:35 INFO - 'HOME': '/home/cltbld', 08:38:35 INFO - 'LANG': 'en_US.UTF-8', 08:38:35 INFO - 'LANGUAGE': 'en_US:en', 08:38:35 INFO - 'LOGNAME': 'cltbld', 08:38:35 INFO - 'MAIL': '/var/mail/cltbld', 08:38:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:35 INFO - 'MOZ_NO_REMOTE': '1', 08:38:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:35 INFO - 'NO_EM_RESTART': '1', 08:38:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:38:35 INFO - 'PWD': '/builds/slave/test-pgo', 08:38:35 INFO - 'SHELL': '/bin/bash', 08:38:35 INFO - 'SHLVL': '1', 08:38:35 INFO - 'TERM': 'linux', 08:38:35 INFO - 'TMOUT': '86400', 08:38:35 INFO - 'USER': 'cltbld', 08:38:35 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', 08:38:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:35 INFO - '_': '/tools/buildbot/bin/python'} 08:38:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:35 INFO - Downloading/unpacking blobuploader==1.2.4 08:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:37 INFO - Downloading blobuploader-1.2.4.tar.gz 08:38:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:38:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:38:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 08:38:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:38:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:37 INFO - Downloading docopt-0.6.1.tar.gz 08:38:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 08:38:37 INFO - Installing collected packages: blobuploader, requests, docopt 08:38:37 INFO - Running setup.py install for blobuploader 08:38:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:38:38 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 08:38:38 INFO - Running setup.py install for requests 08:38:38 INFO - Running setup.py install for docopt 08:38:38 INFO - Successfully installed blobuploader requests docopt 08:38:38 INFO - Cleaning up... 08:38:38 INFO - Return code: 0 08:38:38 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:38:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:38 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 0x7fa27502bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c39600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8350>, '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 0x2cc9250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc96e0>, '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': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', '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 08:38:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 08:38:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --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 08:38:38 INFO - Using env: {'DISPLAY': ':0', 08:38:38 INFO - 'HOME': '/home/cltbld', 08:38:38 INFO - 'LANG': 'en_US.UTF-8', 08:38:38 INFO - 'LANGUAGE': 'en_US:en', 08:38:38 INFO - 'LOGNAME': 'cltbld', 08:38:38 INFO - 'MAIL': '/var/mail/cltbld', 08:38:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:38 INFO - 'MOZ_NO_REMOTE': '1', 08:38:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:38 INFO - 'NO_EM_RESTART': '1', 08:38:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:38:38 INFO - 'PWD': '/builds/slave/test-pgo', 08:38:38 INFO - 'SHELL': '/bin/bash', 08:38:38 INFO - 'SHLVL': '1', 08:38:38 INFO - 'TERM': 'linux', 08:38:38 INFO - 'TMOUT': '86400', 08:38:38 INFO - 'USER': 'cltbld', 08:38:38 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', 08:38:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:38 INFO - '_': '/tools/buildbot/bin/python'} 08:38:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:38:38 INFO - Running setup.py (path:/tmp/pip-AH3rOu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:38:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:38:38 INFO - Running setup.py (path:/tmp/pip-LAz8_C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:38:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:38:38 INFO - Running setup.py (path:/tmp/pip-ZEv1Dp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:38:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:38:38 INFO - Running setup.py (path:/tmp/pip-fw42EG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:38:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:38:39 INFO - Running setup.py (path:/tmp/pip-ZMPsKM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:38:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:38:39 INFO - Running setup.py (path:/tmp/pip-wlk5r_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:38:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:38:39 INFO - Running setup.py (path:/tmp/pip-Fh1Fhm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:38:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:38:39 INFO - Running setup.py (path:/tmp/pip-7i2lZ7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:38:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:38:39 INFO - Running setup.py (path:/tmp/pip-de6Gaw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:38:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:38:39 INFO - Running setup.py (path:/tmp/pip-Lnh7I1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:38:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:38:39 INFO - Running setup.py (path:/tmp/pip-WM0lWw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:38:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:38:39 INFO - Running setup.py (path:/tmp/pip-a31uk3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:38:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:38:39 INFO - Running setup.py (path:/tmp/pip-LsjeSQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:38:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:38:39 INFO - Running setup.py (path:/tmp/pip-0A_FSy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:38:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:38:39 INFO - Running setup.py (path:/tmp/pip-UvR3MG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:38:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:38:39 INFO - Running setup.py (path:/tmp/pip-OwFHbV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:38:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:38:39 INFO - Running setup.py (path:/tmp/pip-VvXLlf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:38:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:38:39 INFO - Running setup.py install for manifestparser 08:38:40 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 08:38:40 INFO - Running setup.py install for mozcrash 08:38:40 INFO - Running setup.py install for mozdebug 08:38:40 INFO - Running setup.py install for mozdevice 08:38:40 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 08:38:40 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 08:38:40 INFO - Running setup.py install for mozfile 08:38:40 INFO - Running setup.py install for mozhttpd 08:38:40 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 08:38:40 INFO - Running setup.py install for mozinfo 08:38:40 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 08:38:40 INFO - Running setup.py install for mozInstall 08:38:40 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 08:38:40 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 08:38:40 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 08:38:40 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 08:38:40 INFO - Running setup.py install for mozleak 08:38:40 INFO - Running setup.py install for mozlog 08:38:41 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 08:38:41 INFO - Running setup.py install for moznetwork 08:38:41 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 08:38:41 INFO - Running setup.py install for mozprocess 08:38:41 INFO - Running setup.py install for mozprofile 08:38:41 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 08:38:41 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 08:38:41 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 08:38:41 INFO - Running setup.py install for mozrunner 08:38:41 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 08:38:41 INFO - Running setup.py install for mozscreenshot 08:38:41 INFO - Running setup.py install for moztest 08:38:41 INFO - Running setup.py install for mozversion 08:38:41 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 08:38:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:38:41 INFO - Cleaning up... 08:38:41 INFO - Return code: 0 08:38:41 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:38:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:41 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fa27502bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c39600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8350>, '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 0x2cc9250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc96e0>, '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': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', '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 08:38:41 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 08:38:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 08:38:41 INFO - Using env: {'DISPLAY': ':0', 08:38:41 INFO - 'HOME': '/home/cltbld', 08:38:41 INFO - 'LANG': 'en_US.UTF-8', 08:38:41 INFO - 'LANGUAGE': 'en_US:en', 08:38:41 INFO - 'LOGNAME': 'cltbld', 08:38:41 INFO - 'MAIL': '/var/mail/cltbld', 08:38:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:41 INFO - 'MOZ_NO_REMOTE': '1', 08:38:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:41 INFO - 'NO_EM_RESTART': '1', 08:38:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:38:41 INFO - 'PWD': '/builds/slave/test-pgo', 08:38:41 INFO - 'SHELL': '/bin/bash', 08:38:41 INFO - 'SHLVL': '1', 08:38:41 INFO - 'TERM': 'linux', 08:38:41 INFO - 'TMOUT': '86400', 08:38:41 INFO - 'USER': 'cltbld', 08:38:41 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', 08:38:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:41 INFO - '_': '/tools/buildbot/bin/python'} 08:38:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:38:41 INFO - Running setup.py (path:/tmp/pip-o7bdIZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:38:42 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)) 08:38:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:38:42 INFO - Running setup.py (path:/tmp/pip-dznPUH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:38:42 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)) 08:38:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:38:42 INFO - Running setup.py (path:/tmp/pip-Psjmnz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:38:42 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)) 08:38:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:38:42 INFO - Running setup.py (path:/tmp/pip-nA3b5f-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:38:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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)) 08:38:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:38:42 INFO - Running setup.py (path:/tmp/pip-PKG8yW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:38:42 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)) 08:38:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:38:42 INFO - Running setup.py (path:/tmp/pip-FNCMUp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:38:42 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)) 08:38:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:38:42 INFO - Running setup.py (path:/tmp/pip-3vSEkj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:38:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 08:38:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:38:42 INFO - Running setup.py (path:/tmp/pip-IPPqWl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:38:42 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)) 08:38:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:38:42 INFO - Running setup.py (path:/tmp/pip-RKGbyY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:38:42 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)) 08:38:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:38:42 INFO - Running setup.py (path:/tmp/pip-R2_67W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:38:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 08:38:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:38:42 INFO - Running setup.py (path:/tmp/pip-7vFht0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:38:42 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)) 08:38:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:38:42 INFO - Running setup.py (path:/tmp/pip-3Qt1qB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:38:42 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)) 08:38:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:38:42 INFO - Running setup.py (path:/tmp/pip-PkVE46-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:38:42 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)) 08:38:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:38:42 INFO - Running setup.py (path:/tmp/pip-tEJUvJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:38:43 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)) 08:38:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:38:43 INFO - Running setup.py (path:/tmp/pip-GAKLSY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:38:43 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)) 08:38:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:38:43 INFO - Running setup.py (path:/tmp/pip-hH8iY6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:38:43 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)) 08:38:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:38:43 INFO - Running setup.py (path:/tmp/pip-aJFzIQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:38:43 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)) 08:38:43 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)) 08:38:43 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)) 08:38:43 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)) 08:38:43 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.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 08:38:43 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.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 08:38:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 08:38:43 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 08:38:43 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 08:38:43 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 08:38:43 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 08:38:43 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 08:38:43 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 08:38:45 INFO - Downloading blessings-1.6.tar.gz 08:38:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 08:38:45 INFO - Installing collected packages: blessings 08:38:45 INFO - Running setup.py install for blessings 08:38:45 INFO - Successfully installed blessings 08:38:45 INFO - Cleaning up... 08:38:45 INFO - Return code: 0 08:38:45 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 08:38:45 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:38:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:38:45 INFO - Reading from file tmpfile_stdout 08:38:45 INFO - Current package versions: 08:38:45 INFO - argparse == 1.2.1 08:38:45 INFO - blessings == 1.6 08:38:45 INFO - blobuploader == 1.2.4 08:38:45 INFO - docopt == 0.6.1 08:38:45 INFO - manifestparser == 1.1 08:38:45 INFO - mozInstall == 1.12 08:38:45 INFO - mozcrash == 0.16 08:38:45 INFO - mozdebug == 0.1 08:38:45 INFO - mozdevice == 0.47 08:38:45 INFO - mozfile == 1.2 08:38:45 INFO - mozhttpd == 0.7 08:38:45 INFO - mozinfo == 0.9 08:38:45 INFO - mozleak == 0.1 08:38:45 INFO - mozlog == 3.1 08:38:45 INFO - moznetwork == 0.27 08:38:45 INFO - mozprocess == 0.22 08:38:45 INFO - mozprofile == 0.27 08:38:45 INFO - mozrunner == 6.11 08:38:45 INFO - mozscreenshot == 0.1 08:38:45 INFO - mozsystemmonitor == 0.0 08:38:45 INFO - moztest == 0.7 08:38:45 INFO - mozversion == 1.4 08:38:45 INFO - psutil == 3.1.1 08:38:45 INFO - requests == 1.2.3 08:38:45 INFO - wsgiref == 0.1.2 08:38:45 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:38:45 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')]} 08:38:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:45 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')]} 08:38:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fa27502bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c39600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8350>, '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 0x2cc9250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc96e0>, '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': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', '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 08:38:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 08:38:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 08:38:45 INFO - Using env: {'DISPLAY': ':0', 08:38:45 INFO - 'HOME': '/home/cltbld', 08:38:45 INFO - 'LANG': 'en_US.UTF-8', 08:38:45 INFO - 'LANGUAGE': 'en_US:en', 08:38:45 INFO - 'LOGNAME': 'cltbld', 08:38:45 INFO - 'MAIL': '/var/mail/cltbld', 08:38:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:45 INFO - 'MOZ_NO_REMOTE': '1', 08:38:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:45 INFO - 'NO_EM_RESTART': '1', 08:38:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:38:45 INFO - 'PWD': '/builds/slave/test-pgo', 08:38:45 INFO - 'SHELL': '/bin/bash', 08:38:45 INFO - 'SHLVL': '1', 08:38:45 INFO - 'TERM': 'linux', 08:38:45 INFO - 'TMOUT': '86400', 08:38:45 INFO - 'USER': 'cltbld', 08:38:45 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', 08:38:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:45 INFO - '_': '/tools/buildbot/bin/python'} 08:38:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:45 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)) 08:38:45 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)) 08:38:45 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)) 08:38:45 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)) 08:38:45 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)) 08:38:45 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)) 08:38:45 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)) 08:38:45 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)) 08:38:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 08:38:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 08:38:45 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)) 08:38:45 INFO - Cleaning up... 08:38:45 INFO - Return code: 0 08:38:45 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 08:38:45 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')]} 08:38:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:45 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')]} 08:38:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fa27502bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c39600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8350>, '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 0x2cc9250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc96e0>, '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': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', '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 08:38:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 08:38:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 08:38:45 INFO - Using env: {'DISPLAY': ':0', 08:38:45 INFO - 'HOME': '/home/cltbld', 08:38:45 INFO - 'LANG': 'en_US.UTF-8', 08:38:45 INFO - 'LANGUAGE': 'en_US:en', 08:38:45 INFO - 'LOGNAME': 'cltbld', 08:38:45 INFO - 'MAIL': '/var/mail/cltbld', 08:38:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:45 INFO - 'MOZ_NO_REMOTE': '1', 08:38:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:45 INFO - 'NO_EM_RESTART': '1', 08:38:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:38:45 INFO - 'PWD': '/builds/slave/test-pgo', 08:38:45 INFO - 'SHELL': '/bin/bash', 08:38:45 INFO - 'SHLVL': '1', 08:38:45 INFO - 'TERM': 'linux', 08:38:45 INFO - 'TMOUT': '86400', 08:38:45 INFO - 'USER': 'cltbld', 08:38:45 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', 08:38:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:45 INFO - '_': '/tools/buildbot/bin/python'} 08:38:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:45 INFO - Downloading/unpacking jsonschema 08:38:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:38:47 INFO - Downloading/unpacking functools32 (from jsonschema) 08:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:47 INFO - Downloading functools32-3.2.3-2.tar.gz 08:38:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 08:38:48 INFO - warning: no files found matching '*.txt' 08:38:48 INFO - no previously-included directories found matching 'build' 08:38:48 INFO - no previously-included directories found matching 'dist' 08:38:48 INFO - no previously-included directories found matching '.git*' 08:38:48 INFO - Installing collected packages: jsonschema, functools32 08:38:48 INFO - Running setup.py install for functools32 08:38:48 INFO - warning: no files found matching '*.txt' 08:38:48 INFO - no previously-included directories found matching 'build' 08:38:48 INFO - no previously-included directories found matching 'dist' 08:38:48 INFO - no previously-included directories found matching '.git*' 08:38:48 INFO - Successfully installed jsonschema functools32 08:38:48 INFO - Cleaning up... 08:38:48 INFO - Return code: 0 08:38:48 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 08:38: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')]} 08:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38: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')]} 08:38:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fa27502bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c39600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8350>, '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 0x2cc9250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc96e0>, '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': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', '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 08:38:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 08:38:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 08:38:48 INFO - Using env: {'DISPLAY': ':0', 08:38:48 INFO - 'HOME': '/home/cltbld', 08:38:48 INFO - 'LANG': 'en_US.UTF-8', 08:38:48 INFO - 'LANGUAGE': 'en_US:en', 08:38:48 INFO - 'LOGNAME': 'cltbld', 08:38:48 INFO - 'MAIL': '/var/mail/cltbld', 08:38:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:48 INFO - 'MOZ_NO_REMOTE': '1', 08:38:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:48 INFO - 'NO_EM_RESTART': '1', 08:38:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:38:48 INFO - 'PWD': '/builds/slave/test-pgo', 08:38:48 INFO - 'SHELL': '/bin/bash', 08:38:48 INFO - 'SHLVL': '1', 08:38:48 INFO - 'TERM': 'linux', 08:38:48 INFO - 'TMOUT': '86400', 08:38:48 INFO - 'USER': 'cltbld', 08:38:48 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', 08:38:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:48 INFO - '_': '/tools/buildbot/bin/python'} 08:38:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:48 INFO - Downloading/unpacking flake8 08:38:48 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 08:38:48 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 08:38:48 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 08:38:48 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 08:38:48 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 08:38:48 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 08:38:50 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:38:50 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:38:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:50 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 08:38:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:38:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:38:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:38:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:38:50 INFO - no previously-included directories found matching 'docs/_build' 08:38:50 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:38:51 INFO - Running setup.py install for pep8 08:38:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:38:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:38:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:38:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:38:51 INFO - no previously-included directories found matching 'docs/_build' 08:38:51 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 08:38:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:38:51 INFO - Cleaning up... 08:38:51 INFO - Return code: 0 08:38:51 INFO - Running post-action listener: _resource_record_post_action 08:38:51 INFO - Running post-action listener: _start_resource_monitoring 08:38:51 INFO - Starting resource monitoring. 08:38:51 INFO - ##### 08:38:51 INFO - ##### Running install step. 08:38:51 INFO - ##### 08:38:51 INFO - Running pre-action listener: _resource_record_pre_action 08:38:51 INFO - Running main action method: install 08:38:51 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:38:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:38:51 INFO - Reading from file tmpfile_stdout 08:38:51 INFO - Detecting whether we're running mozinstall >=1.0... 08:38:51 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 08:38:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 08:38:51 INFO - Reading from file tmpfile_stdout 08:38:51 INFO - Output received: 08:38:51 INFO - Usage: mozinstall [options] installer 08:38:51 INFO - Options: 08:38:51 INFO - -h, --help show this help message and exit 08:38:51 INFO - -d DEST, --destination=DEST 08:38:51 INFO - Directory to install application into. [default: 08:38:51 INFO - "/builds/slave/test-pgo"] 08:38:51 INFO - --app=APP Application being installed. [default: firefox] 08:38:51 INFO - mkdir: /builds/slave/test-pgo/build/application 08:38:51 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 08:38:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 08:39:06 INFO - Reading from file tmpfile_stdout 08:39:06 INFO - Output received: 08:39:06 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 08:39:06 INFO - Running post-action listener: _resource_record_post_action 08:39:06 INFO - ##### 08:39:06 INFO - ##### Running run-tests step. 08:39:06 INFO - ##### 08:39:06 INFO - Running pre-action listener: _resource_record_pre_action 08:39:06 INFO - Running main action method: run_tests 08:39:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 08:39:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 08:39:06 INFO - Python 2.7.3 08:39:06 INFO - Return code: 0 08:39:06 INFO - grabbing minidump binary from tooltool 08:39:06 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')]} 08:39:06 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 0x2cc8350>, '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 0x2cc9250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cc96e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 08:39:06 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 08:39:06 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 08:39:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:39:06 INFO - Return code: 0 08:39:06 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 08:39:06 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 08:39:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 08:39:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 08:39:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 08:39:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 08:39:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 08:39:07 INFO - Return code: 0 08:39:07 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/1456409732/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 08:39:07 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/1456409732/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --webServer localhost 08:39:07 INFO - Using env: {'DISPLAY': ':0', 08:39:07 INFO - 'HOME': '/home/cltbld', 08:39:07 INFO - 'LANG': 'en_US.UTF-8', 08:39:07 INFO - 'LANGUAGE': 'en_US:en', 08:39:07 INFO - 'LOGNAME': 'cltbld', 08:39:07 INFO - 'MAIL': '/var/mail/cltbld', 08:39:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:39:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 08:39:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:07 INFO - 'MOZ_NO_REMOTE': '1', 08:39:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:39:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:07 INFO - 'NO_EM_RESTART': '1', 08:39:07 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:39:07 INFO - 'PWD': '/builds/slave/test-pgo', 08:39:07 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 08:39:07 INFO - 'SHELL': '/bin/bash', 08:39:07 INFO - 'SHLVL': '1', 08:39:07 INFO - 'TERM': 'linux', 08:39:07 INFO - 'TMOUT': '86400', 08:39:07 INFO - 'USER': 'cltbld', 08:39:07 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415', 08:39:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:07 INFO - '_': '/tools/buildbot/bin/python'} 08:39:07 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/1456409732/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost'] with output_timeout 3600 08:39:07 INFO - mozversion INFO | application_buildid: 20160225061532 08:39:07 INFO - mozversion INFO | application_changeset: 80ce3f1ffe03de3e0cd64d14531d4c4aa531d03f 08:39:07 INFO - mozversion INFO | application_display_name: Nightly 08:39:07 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:39:07 INFO - mozversion INFO | application_name: Firefox 08:39:07 INFO - mozversion INFO | application_remotingname: firefox 08:39:07 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:39:07 INFO - mozversion INFO | application_vendor: Mozilla 08:39:07 INFO - mozversion INFO | application_version: 45.0 08:39:07 INFO - mozversion INFO | platform_buildid: 20160225061532 08:39:07 INFO - mozversion INFO | platform_changeset: 80ce3f1ffe03de3e0cd64d14531d4c4aa531d03f 08:39:07 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:39:07 INFO - mozversion INFO | platform_version: 45.0 08:39:07 INFO - 2016-02-25 08:39:07,750 DEBUG : using testdate: 1456418347 08:39:07 INFO - 2016-02-25 08:39:07,750 DEBUG : actual date: 1456418347 08:39:07 INFO - 2016-02-25 08:39:07,760 INFO : starting webserver on 'localhost:34752' 08:39:07 INFO - 2016-02-25 08:39:07,761 INFO : Starting test suite talos-linux64-ix-029 08:39:07 INFO - 2016-02-25 08:39:07,762 INFO : Starting test dromaeo_css 08:39:07 INFO - 2016-02-25 08:39:07,762 DEBUG : operating with platform_type : linux_ 08:39:07 INFO - 2016-02-25 08:39:07,762 INFO : Initialising browser for dromaeo_css test... 08:39:07 INFO - 2016-02-25 08:39:07,778 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpqkWOEj/profile http://localhost:34752/getInfo.html 08:39:11 INFO - 2016-02-25 08:39:11,367 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:39:11 INFO - 2016-02-25 08:39:11,367 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:39:11 INFO - 2016-02-25 08:39:11,367 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:39:11 INFO - 2016-02-25 08:39:11,367 DEBUG : BROWSER_OUTPUT: __metrics 08:39:11 INFO - 2016-02-25 08:39:11,504 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] 08:39:12 INFO - 2016-02-25 08:39:12,486 INFO : Browser initialized. 08:39:12 INFO - 2016-02-25 08:39:12,487 INFO : Running cycle 1/1 for dromaeo_css test... 08:39:12 INFO - 2016-02-25 08:39:12,487 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpqkWOEj/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:39:24 INFO - 2016-02-25 08:39:24,078 DEBUG : BROWSER_OUTPUT: RSS: Main: 133967872 08:39:24 INFO - 2016-02-25 08:39:24,078 DEBUG : BROWSER_OUTPUT: 08:40:14 INFO - 2016-02-25 08:40:14,838 DEBUG : BROWSER_OUTPUT: 1456418414836 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] 08:42:23 INFO - 2016-02-25 08:42:23,107 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34752/tests/dromaeo/cssquery-ext.html) 08:42:24 INFO - 2016-02-25 08:42:24,223 DEBUG : BROWSER_OUTPUT: RSS: Main: 232222720 08:42:24 INFO - 2016-02-25 08:42:24,224 DEBUG : BROWSER_OUTPUT: 08:45:26 INFO - 2016-02-25 08:45:26,271 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/dromaeo/cssquery-ext.html (next: http://localhost:34752/tests/dromaeo/cssquery-jquery.html) 08:45:26 INFO - 2016-02-25 08:45:26,824 DEBUG : BROWSER_OUTPUT: RSS: Main: 209944576 08:45:26 INFO - 2016-02-25 08:45:26,824 DEBUG : BROWSER_OUTPUT: 08:48:28 INFO - 2016-02-25 08:48:28,680 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34752/tests/dromaeo/cssquery-mootools.html) 08:48:29 INFO - 2016-02-25 08:48:29,233 DEBUG : BROWSER_OUTPUT: RSS: Main: 214327296 08:48:29 INFO - 2016-02-25 08:48:29,233 DEBUG : BROWSER_OUTPUT: 08:51:30 INFO - 2016-02-25 08:51:30,862 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34752/tests/dromaeo/cssquery-prototype.html) 08:51:31 INFO - 2016-02-25 08:51:31,428 DEBUG : BROWSER_OUTPUT: RSS: Main: 197128192 08:51:31 INFO - 2016-02-25 08:51:31,428 DEBUG : BROWSER_OUTPUT: 08:54:33 INFO - 2016-02-25 08:54:33,880 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34752/tests/dromaeo/cssquery-yui.html) 08:54:34 INFO - 2016-02-25 08:54:34,441 DEBUG : BROWSER_OUTPUT: RSS: Main: 197918720 08:54:34 INFO - 2016-02-25 08:54:34,441 DEBUG : BROWSER_OUTPUT: 08:57:36 INFO - 2016-02-25 08:57:36,605 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/dromaeo/cssquery-yui.html (next: http://localhost:34752/tests/dromaeo/cssquery-dojo.html) 08:57:36 INFO - 2016-02-25 08:57:36,856 DEBUG : BROWSER_OUTPUT: RSS: Main: 240947200 08:57:36 INFO - 2016-02-25 08:57:36,856 DEBUG : BROWSER_OUTPUT: 08:57:36 INFO - 2016-02-25 08:57:36,856 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:57:36 INFO - 2016-02-25 08:57:36,856 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:57:36 INFO - 2016-02-25 08:57:36,856 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:57:36 INFO - 2016-02-25 08:57:36,856 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:57:36 INFO - 2016-02-25 08:57:36,856 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3298.2414044769703;3368.4947257911326;3388.4070287699647;3388.542546756187;3411.914702132447;5644.407337229593;5658.207850900867;5674.375818659944;5674.829755107348;5676.858078548038;5703.800366987156;5706.3722990471015;5714.857128571788;5715.228444160037;5715.399883012286;4976.62675299354;5145.845624631248;5188.6367954243215;5253.422123566424;5262.4211336753115;5012.674176178555;5246.370435547739;5259.710715910624;5261.947380526203;5264.6051546134;5190.6107041972;5195.454477279888;5288.444713305086;5297;5328;5231.73841307935;5234.136367499358;5248.160294352923;5250.81622143227;5283.815066472655;5062.898742025176;5105.259737338102;5117.820876269313;5144;5150.3047088642925;5532.889342213173;5547.057000309957;5550.583706222009;5560.582956278255;5564.026295398329;6122.540809439265;6138.14066030756;6160.87678246437;6160.87678246437;6165.630062196237;5955.970220148872;6072.483838873701;6104.511639068864;6177.598456100384;6226.59527130735;5680.147977803321;5680.403557626472;5681.744321505584;5683.261176047087;5708.65748055118;6160.322364539897;6170.444659980623;6176.722047507873;6177.444030037318;6181.227346581677;5753.942460575341;5922.733476993546;5997.490213331828;6112.8160236355625;6125.551374386306;6069.119977603223;6069.211002569726;6070.878582428327;6075.513958883367;6091.756329746849;5907.822765317047;5973.79091731796;6039.8490037748525;6040.214772079602;6045.486133678686;5904.586678932434;5998.070299103646;6002.339742628307;6037.667928263988;6051.092336149525;4591.862244132682;4637;4639.652026097989;4656.231721765871;4684.461286951976;4066.959330406717;4180.853670121531;4187.5184353799095;4196.706230563892;4211;5655.604107712503;5732.054211055212;5750.2237197978575;5755.107958266475;5792.4497172768515;6199.132121502993;6226.657533835682;6237.251529816451;6239.500839932794;6247.094171345129;5489.451054894478;5576.749046292926;5855.882882342415;5896.1450589664255;5911.349751527328;5120.615953803405;5185.948140518622;5188.558972487305;5189.143791274398;5192.4807519248525;6307.905381419282;6365.331640177715;6369.649669268235;6371.585846919936;6373.553851230369;6005.129256257906;6055.1522786809;6140.785072522619;6177.84555386101;6178.4439400452375;6181.505479561534;6191.81424557264;6196.380361963767;6197.690115494297;6202.193714817045;5936.406359364029;5946.07835785454;5952.404759524186;5954.047352423591;5975.940240597713;5960.582759206987;6044.455998960115;6045.75816967334;6077.544184186116;6145.8156255312415;5934.347221805511;5975;5978.491828194652;5984.102384642339;5985.970070149621;5926.081457374114;5934.19888315072;5991.7603295867675;5994.880102398015;6005.8288633716;5638.689872057076;5754.9136762947755;5829.271341082364;5867.413258674269;5940.346561878104;5957.225560677084;5972.104184372379;5972.223610930551;5983.55123365758;5989.670568118621;5888.852778680567;5891.941080589139;5892.35184129754;5894.616849904743;5898.233229680286 08:57:36 INFO - 2016-02-25 08:57:36,856 DEBUG : BROWSER_OUTPUT: |1;ext.html;591.3760982163816;644.6164532103398;651.1957732200731;663.2339647706895;668.7594512179907;33236;34012;34080.318393632115;41701.37447938283;41839.58160418395;46431.76784116075;46938;46959.76520117395;47038.29442558364;47078.29382559264;8183.468074575163;9106.499142547174;9216.769580760501;9284.628614855432;9293.349465537414;8328.791780205484;8438.57807109642;10935.070519005894;11230.15773816963;11241.60654377097;10866;10967.122630189566;10973.122150227962;10980.121590272758;10988.175886808482;10842.403667798268;10927.836082458769;10939.83590246147;10941.507632156572;10950.178736594748;10653.307535010277;10695.465226738632;10753.946230268877;10774.245802793808;10790.190735694854;42121;42432.151356972994;42466;42526.57473425288;42562;3688.2992231475932;3722.236941427001;3738.9813050934845;4114.382842573608;4361.563843615645;32027.679723202702;32051.358972820646;32381.838090809397;32525.51211731826;32766;4372.584604462571;4434.22401079813;4462.955370446319;4482.260427029536;4487.8429254976245;303860;305211;305838;306502.9999999978;307791;55128.72435637876;55904.16143757847;63652;64159.679201604624;64208;75253.62373188099;75879.24120758832;76158.61920690472;76314;76620;17386.3045478182;17469;17505.12474376276;17620.118994050503;17629.382971595933;13190.340482975813;13248.668783280302;13269.336533173302;13312.334383280797;13374.13068150567;20777.792222077587;21114.894425527775;21118.47203819917;21118.78881211199;21119.366419007456;8118.675252989932;8378.49729016261;8404.025054112279;8419.873701894476;8541.572921354033;959.3092973059386;1087.586717047517;1163.5927425401042;1178.911581631381;1200.8498937632796;29623.25941851471;29954;30149.849250754043;30635.8468207662;30796.538051929238;1126.9549218031186;1155.3530023187043;1188.1504724122294;1247.2641141726424;1259.0494176896384;13132.934335328262;13731.45074197041;13883.236421996888;14100.85899141016;15607.687846243012;361826;363905;364459;364606;364759;47131.764341178074;47238.52761472341;53705.46294537005;54667;55366.44633553694;1472.9410823567152;1498.655309278872;1513.3946421431515;1514.3866733972538;1518.5444366689949;26236.21291361262;27149.457010860067;27310.590341145296;27393.86303068472;30011.69988300089;47151.52848471609;47302.526974729815;47407.76296118635;47453.76273118612;47543.286850696575;212607;213578;214013;214673;214724;33137;33140.83429582837;33377;33940.66059339375;34026.829865850515;43448.56551434445;43674.12651747011;46966.29550556813;47676.523234767206;47915.520844791106;38805.80597096997;39548;39871.60128398679;39896.80051599723;40370.56850167651;36592.45111323279;39342.606573933896;40049.79975100106;40057.599424006556;40332.1933561333;34099.488507672904;34374.65625343715;34807.82596087003;35089;35314.11714707153;30788.384232315675;31222.375552488367;31338.686613133577;31534.053978380685;32246.35507289888;26378;26720.599191012538;26727.86636066807;26888.05891793778;27105 08:57:36 INFO - 2016-02-25 08:57:36,857 DEBUG : BROWSER_OUTPUT: |2;jquery.html;392.20186919618584;397.3007506788054;401.8151650240889;401.85533208045103;401.95377531583875;15889.761653575177;16404.917975410106;17860.464186074405;18226.362077327296;18251.90874045631;15402.768958465633;17116.31534738612;17302.56743581411;17440.3023879045;17476.300947962067;5085.771140298677;5279.894402111955;5283.524482796547;5286.101362768339;5291.259223708684;4054.776987265699;4172.561881002482;5255.369355677324;5289.814856480024;5686.943130568703;5774.191613174159;5800.709964501779;5801.7389217485315;5809.535237181015;5811.476967072963;5766.769329226826;5954.225950626413;6256.78101266458;6303.149074874925;6316.115743795872;5606.55147588192;5633.211350410946;5642.633228840113;5642.915356269659;5649.8587535311535;20524.5895082099;20822.687659684965;21229.787702122936;21250.256241031646;21296.615719978152;2317.582835089683;2340.368100612825;2361.6929799126174;2429.7448767879473;2446.755324467539;10713.78572428547;11300.604478843283;13718.519851805238;13857.89136869048;14469.421223151061;1949.6783030799902;2023.5345870449878;2039.277564451459;2054.4863784053987;2065.75210974684;63137;65505.3449465504;65747.67126164386;65866.99999999952;67329.66335168293;10971.396573188551;11137.05335046514;11146.108311335236;11160.665180044612;11212.66362009141;15102.471413500474;15169.696606067817;15197.848021519865;15221.847781522043;15235.542933711784;8790.560471976503;8791.120887911158;8813.515256660945;8821.206091451671;8843.51360675169;8656.740297791077;8667.479951202948;8699.652013919373;8746.300295976307;8749.693760718344;4758.904821903542;4862.41651001882;4869.756512174377;4871.780769865364;4905.166121759309;1768.8419073564412;1838.9080545972647;1906.5043088797013;1956.6575849226329;2027.7161197432372;496.3225197605315;1016.3546148195949;1019.0268293779571;1053.2631819060907;1055.5724931402794;11206.831897521544;11766.117541184445;11977.161598688008;11999.760004799855;12039.879601203877;457.3482787028471;512.5643203277184;516.8681986093598;517.3920643244173;521.4576840086344;6579.967100164468;6626.900596491057;6739.427148692318;6757.256701762803;6788.966055169792;597866;600010;609861;610306;615751;83752;86191;86641.56679216564;88406;89669.55165224132;547.5209191957038;550.1115698147543;552.0835413214046;552.5193082018669;553.6456667732574;11361.715957100809;11435.828462573234;11449.828252576051;11459.598914037968;11519.481623327138;37173.25653486861;37272.627273728;37655;37866.0533486665;37894.81052594719;49720;51464;53459.46540534545;54114.45885541094;54863.45136548583;10227.948860255949;10307.072363487323;10519.26365154432;10730.356178629307;10735.570577176826;12483.063770217088;12536.373181340714;13065.869341306845;18138.818611813713;18443.26226950907;15206.847931520542;15302.77045844291;15382.538523844303;15496.690066198387;15774.107107147216;15417.229138543253;15686.607834804221;16023.919880400523;16038;16154.838451615333;11361.829572556584;14989.175595341925;15639.452619158275;16008.839911600735;16024.679506410039;10166.085052345325;10350.5342259597;10379.221558383;10442.321249118782;10496.790064198825;10310.793784124126;10384.740381490523;11508.88491115078;15148.772768408262;15431.691366172838 08:57:36 INFO - 2016-02-25 08:57:36,859 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1552.0997821263668;1554.3627112883712;1821.8542516598657;1865.80409057049;1878.323803430765;7996.960015199923;8466.534340611272;8505.872411913811;8506.149385061492;8508.36187285953;8344.374171937114;8368.874466882986;8407.075221725621;8455.661773529051;8479.78800529987;2375.964360534593;2392.9401764955883;2403.831731778776;2433.2578563538113;2433.6592876997147;2362.433016076138;2384;2414.8188885833547;2414.8792560372;2417.407735104893;2021.82814460771;2092.811646951774;2094.8428867834896;2343.929682109531;2362.0669835415;2086.488810241485;2354.9411264718433;2404.651325557802;2408.4099395648004;2410;2345.7067866516686;2349.6710460535537;2362.65741467488;2377.227401094634;2394.0663141374876;7117.288271172842;7284.453665975073;8126.878096828552;8159.877601835978;8183.6726530938695;4540.682152249344;4668.253079507263;4674.088552732219;4682.274247491644;4686.95313046872;3070.355225402668;3118.937621247585;3136.6079240094987;3185.2514659055105;3202.8558714857654;1099.8405231241504;1107.4518113533852;1130.4799792095646;1131.4625552862408;1172.1209093180114;10858.077063449537;11045.116390688725;11054.778904421946;11063.502142403611;11074.224804263706;4516.751578663139;4586.770661466913;4598.195315819785;4719.834805781781;4729.69256998294;9832.262580306362;9838.458884761263;9843;9849.45827979468;9854.605815767432;5030.647854650212;5053.065182941167;5057.595392368602;5059.19052951526;5059.62052846038;4540.432445944257;4588.977055114769;4591.449026116888;4594.264917613166;4599.379083823708;3848.942265866014;3890.2414029264023;3916.1580260244186;3922.1371298314325;3950.328444164499;1185.4961641302411;1186.602488166474;1196.8025275829564;1196.904247660185;1200.117913333699;1061.4798748613123;1097.286763603664;1138.7836311100864;1159.5709587452698;1165.8018136916576;7972.720954766555;7979.241972012649;7995.480293780885;8008.559529225832;8015.639296231683;1206.8853458921387;1208.3535308610028;1220.5936568985953;1225.381182502848;1229.1518851992275;5757.453041961007;6405.423511883952;6455.193100862392;6457.2574153971955;6459.321771214049;9744.220462362993;9895.901040989642;9920.553575089138;9942.552585133686;10026.19790416765;9736.221102311798;9794.706158815246;9853.75365615851;9854.655087071918;10198.949005254926;7921.960390198012;7947.4436789425345;7950.602469876483;7974.082980457206;8027.598620068973;7836.608169591384;7998.9200108000505;8013.759587212393;8026.036875574969;8035.517868927883;8947.865782013405;9073.954630226806;9084.636614535344;9100.77248068777;9113.817723645623;9159.450432974043;9181.908180918372;9314.394564353295;9444.197243234403;9448.055194480497;7239.457040721862;7269.091363579552;7355.742549010759;7361.963190184015;7375.963120184365;8187.181281872004;8229.835403291781;8233;8239.382046346429;8244.29923456513;7723.884141737991;7817.491863028885;7842.607869606382;7887.250711182429;7913.089994650689;7457.32884040439;7602.581857997863;7633.31300182964;7662.885056724265;7739.535627862347;7319.048523691886;7398.223186565333;7401.444891633257;7402.4448166386655;7411.703531858665;6822.965885170741;6834.419074378733;6898.206706228848;6923.9653801730665;6929.861402771816;7270.527415717961;7321.304476074764;7322.52403593786;7324.304191101837;7325.304096110861 08:57:36 INFO - 2016-02-25 08:57:36,860 DEBUG : BROWSER_OUTPUT: |4;prototype.html;370.82756518219026;380.5870630366053;381.6812961177417;384.9268638958599;388.2332393522791;6140.7543698252075;6201.689915504229;6208.78269260576;6211.906821397677;6225.844353891155;5865.70671466426;5970.582059255843;6020.0969854521845;6122.295935967365;6136.110264011716;4753.809847606091;4772.475027746945;4776.570108690217;4777.04459108178;4778.52214778522;4528.02647430804;4781.187198176319;4785.306130611059;4793.7842797074045;4795.280707893827;4571.337156112361;4627.23650597651;4690.718556886581;4696.8590942271785;4721.645876559254;4692.249240121581;4731.148393289207;4735.621150307967;4741.525847415248;4746.905061898778;4231.534531201565;4238.321868501025;4239.364095385717;4240.724352917051;4247.87256382309;5731.226284451587;5822.1266809978415;5826.359100498942;5831.416858314177;5833.270841144857;5578.63738856977;5579.191017302509;5579.218909352694;5580.665160090367;5583.05088135018;6456.451201647866;6464.482841372679;6467.741290348427;6470.805875823733;6474.1583594132935;4708.717476951394;4711.575958163782;4714.033623107255;4719.056188762262;4739.502352252998;6517.608943463408;6661.4004739573675;6675.866482670368;6677.799666010027;6741.258461569223;4699.459562150397;4729.976350118227;4731.361266229083;4732.313814496879;4735.786889589908;4784.4497882743235;4786.305985632064;4787.305840653085;4790.233562629963;4791.95208047922;5348.171033489737;5483.561315094863;5490.505854473116;5491.917621235684;5492.286002819608;6541.901871471931;6550.541462097703;6560.967195163994;6566.161474787067;6569;1670.106493026225;1671.2145291712875;1673.4477622384584;1673.6903672820567;1675.0368538090647;1141.7659379827282;1152.5735477873072;1153.331067980586;1159.7622487390065;1179.5517703272874;6277.1525844010475;6285.088662143963;6286.119943207955;6287.402696743802;6296.685165741695;6515.902261466082;6716.664166791641;6733.3939945405145;6733.494987875831;6735.764248251287;6258.74965001404;6293.748250069946;6441.19646498981;6445.677716114175;6448.613083215022;3615.891523254254;3674.3018826423427;3674.585284665423;3678.08047988003;3680.8895733128047;6876.071730316343;6998.720051197895;7013.719451221996;7016.333448322402;7025.789226323219;6940.895886561605;6942.375186233263;6984.301569843076;6996.440284777205;7004.684789184499;7028.2620324866175;7029.22678505364;7030.789076327718;7034.929650703428;7038.436925045984;6664.900026499703;6811.795646130624;6813.216480104653;6819.5908245505425;6834.179898412222;6753.696083676148;6764.323567643196;6769.695363708744;6775.356341147583;6776.457883369417;7027.859442811217;7030.50786444944;7037.437005039687;7042.50702450844;7049.506534542533;4735.052989402065;4826.903461930812;4858.46556878736;4865.537773911473;4884.9511504884495;6765.154355705537;6765.729370825112;6772.93227067723;6774.491913106438;6776.559523630948;6596.80209593713;6598.703058362289;6608.306127856506;6616.172978377703;6618.503612229199;6442.7436649852825;6583.3087525811015;6587.242467116343;6594.340565943367;6606;6457.225132983884;6458.483321334376;6467.385598368148;6472.644004579794;6479.254885688018;6579.96710016466;6580.979948108051;6599.934000659932;6603.075569420189;6604.669766511752;6276.152719382825;6345.7144428499905;6438.420542151229;6439.581427207217;6458.903116453163 08:57:36 INFO - 2016-02-25 08:57:36,860 DEBUG : BROWSER_OUTPUT: |5;yui.html;872.4634349874821;897.649916532552;902.151977141487;912.625823412401;917.4724533393298;13659.521916732916;13761.17432954021;14010.579682609537;14137.717245655082;14159.15045097295;13709.65725856854;13719.108257963226;13761.518346857862;13792.517261895811;13848.584542463717;781.281221276427;910.8178364327127;924.8936372317203;924.8982611912684;925.5788616179656;773.7214602743036;783.4085265624445;783.4163548156603;925.4586067150725;936.7845395559026;962.2830990911781;964.6671898195146;964.778101036762;966.381515829866;972;956.3449037409328;960.1550635440838;960.1646567486262;960.3997501561524;960.6829746183778;602.0277252237648;604.3261763134118;715.1704023332909;719.316649183278;719.3993015831787;14066.859331406657;14093.859061409357;14137.717245655133;14154.363053662508;14160.504382346568;13005.544805931748;13236.867631323756;13338;13436.798448023288;13458.092773243101;10325.89674103257;10487.685369438928;10928.836067458995;11189.160812939062;11198.496067676973;3775;3793.108619474421;3818.0836599215986;3834.1564855731694;3836.079340958178;48124.75937620325;48419.5158048422;48679.269810952865;50414;50596.747016264686;10557.894421055844;10586.841197382046;10788.244822862482;10840.67477975662;10972.341659500455;18536.536586585175;18538.629227415375;18549.979751113817;18603.348882789036;18612.348567800334;7730.188330225358;7834.177411371724;7841.176676449005;7936.920630793618;7985.760427187193;4584.289435137559;4588.174128656807;4591.288350305708;4611.976940115345;4760.666753327303;3244.951325730116;3257.03917344384;3262.5269335946155;3269.75476839238;3314.950275745866;612.2744547710977;614.6127939398151;619.789271647642;623.5790841182184;650.9674516274262;698.3644883156303;710.1974768511549;722.4762047515509;726.0996364508078;741.1550832051465;8626.870596941051;8633.179847914438;8641.30869530436;8742.344324175585;8850.336224783166;563.1861959468529;701.0780823217555;722.0901663903414;731.8536292741474;737.1265050914683;4225.556316586775;4227.556106608823;4254.7659878706345;4448.844290449819;4529.501754806968;56618.150727739114;57200;57533.424665752806;57706;58113.709431452575;49927;50900;50979.49020509747;51488.48511514912;51579.484205158216;46559.30161047587;46822.531774682495;46859;47182.76408618004;47676;8867.113288671082;8953.104689530996;9100;9247.39891907024;9268;35031.64968350284;35732.64267357293;37047.629523705495;38226.0443488915;39228.41157382585;44418.55581444144;45359.09281814411;45414;45451.31823022591;46092.769536152104;11560;11739.706507337385;11903.404829758305;11927.105467090176;12192.207506512048;12776.6805829855;12987.870121299044;13393.933030334785;13745.931270343584;14050.929745351208;11679.649610511698;11728.296302221894;11743.588974385855;11775.587854425054;12445;11818.763624727286;11819.723674161867;11885.108616853599;12134.211276267015;12276.263424194576;10101.191904647463;11200.327980321206;11428.714282143013;11560.884391155982;11911.821322679993;9746.805063898824;9855.310128291238;10114.595416183565;10382.584696612052;10472.895271047191;9665.27510436706;9978.700638980843;10000.449975251431;10009.548615450865;10336.121429678562 08:57:36 INFO - 2016-02-25 08:57:36,860 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:57:36 INFO - 2016-02-25 08:57:36,860 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:57:36 INFO - 2016-02-25 08:57:36,860 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,790 08:57:36 INFO - 2016-02-25 08:57:36,860 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:57:36 INFO - 2016-02-25 08:57:36,860 DEBUG : BROWSER_OUTPUT: __startTimestamp1456419456854__endTimestamp 08:57:36 INFO - 2016-02-25 08:57:36,860 DEBUG : BROWSER_OUTPUT: 08:57:36 INFO - 2016-02-25 08:57:36,860 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:57:36 INFO - 2016-02-25 08:57:36,861 DEBUG : BROWSER_OUTPUT: Number of tests: 6 08:57:36 INFO - 2016-02-25 08:57:36,861 DEBUG : BROWSER_OUTPUT: 08:57:36 INFO - 2016-02-25 08:57:36,861 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5650.31 Median:5896.15 stddev:631.34 (10.7%) stddev-sans-first:605.71 08:57:36 INFO - 2016-02-25 08:57:36,861 DEBUG : BROWSER_OUTPUT: Values: 3298.2 3368.5 3388.4 3388.5 3411.9 5644.4 5658.2 5674.4 5674.8 5676.9 5703.8 5706.4 5714.9 5715.2 5715.4 4976.6 5145.8 5188.6 5253.4 5262.4 5012.7 5246.4 5259.7 5261.9 5264.6 5190.6 5195.5 5288.4 5297.0 5328.0 5231.7 5234.1 5248.2 5250.8 5283.8 5062.9 5105.3 5117.8 5144.0 5150.3 5532.9 5547.1 5550.6 5560.6 5564.0 6122.5 6138.1 6160.9 6160.9 6165.6 5956.0 6072.5 6104.5 6177.6 6226.6 5680.1 5680.4 5681.7 5683.3 5708.7 6160.3 6170.4 6176.7 6177.4 6181.2 5753.9 5922.7 5997.5 6112.8 6125.6 6069.1 6069.2 6070.9 6075.5 6091.8 5907.8 5973.8 6039.8 6040.2 6045.5 5904.6 5998.1 6002.3 6037.7 6051.1 4591.9 4637.0 4639.7 4656.2 4684.5 4067.0 4180.9 4187.5 4196.7 4211.0 5655.6 5732.1 5750.2 5755.1 5792.4 6199.1 6226.7 6237.3 6239.5 6247.1 5489.5 5576.7 5855.9 5896.1 5911.3 5120.6 5185.9 5188.6 5189.1 5192.5 6307.9 6365.3 6369.6 6371.6 6373.6 6005.1 6055.2 6140.8 6177.8 6178.4 6181.5 6191.8 6196.4 6197.7 6202.2 5936.4 5946.1 5952.4 5954.0 5975.9 5960.6 6044.5 6045.8 6077.5 6145.8 5934.3 5975.0 5978.5 5984.1 5986.0 5926.1 5934.2 5991.8 5994.9 6005.8 5638.7 5754.9 5829.3 5867.4 5940.3 5957.2 5972.1 5972.2 5983.6 5989.7 5888.9 5891.9 5892.4 5894.6 5898.2 08:57:36 INFO - 2016-02-25 08:57:36,861 DEBUG : BROWSER_OUTPUT: 08:57:36 INFO - 2016-02-25 08:57:36,861 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:47943.81 Median:33658.83 stddev:78841.43 (234.2%) stddev-sans-first:78982.43 08:57:36 INFO - 2016-02-25 08:57:36,862 DEBUG : BROWSER_OUTPUT: Values: 591.4 644.6 651.2 663.2 668.8 33236.0 34012.0 34080.3 41701.4 41839.6 46431.8 46938.0 46959.8 47038.3 47078.3 8183.5 9106.5 9216.8 9284.6 9293.3 8328.8 8438.6 10935.1 11230.2 11241.6 10866.0 10967.1 10973.1 10980.1 10988.2 10842.4 10927.8 10939.8 10941.5 10950.2 10653.3 10695.5 10753.9 10774.2 10790.2 42121.0 42432.2 42466.0 42526.6 42562.0 3688.3 3722.2 3739.0 4114.4 4361.6 32027.7 32051.4 32381.8 32525.5 32766.0 4372.6 4434.2 4463.0 4482.3 4487.8 303860.0 305211.0 305838.0 306503.0 307791.0 55128.7 55904.2 63652.0 64159.7 64208.0 75253.6 75879.2 76158.6 76314.0 76620.0 17386.3 17469.0 17505.1 17620.1 17629.4 13190.3 13248.7 13269.3 13312.3 13374.1 20777.8 21114.9 21118.5 21118.8 21119.4 8118.7 8378.5 8404.0 8419.9 8541.6 959.3 1087.6 1163.6 1178.9 1200.8 29623.3 29954.0 30149.8 30635.8 30796.5 1127.0 1155.4 1188.2 1247.3 1259.0 13132.9 13731.5 13883.2 14100.9 15607.7 361826.0 363905.0 364459.0 364606.0 364759.0 47131.8 47238.5 53705.5 54667.0 55366.4 1472.9 1498.7 1513.4 1514.4 1518.5 26236.2 27149.5 27310.6 27393.9 30011.7 47151.5 47302.5 47407.8 47453.8 47543.3 212607.0 213578.0 214013.0 214673.0 214724.0 33137.0 33140.8 33377.0 33940.7 34026.8 43448.6 43674.1 46966.3 47676.5 47915.5 38805.8 39548.0 39871.6 39896.8 40370.6 36592.5 39342.6 40049.8 40057.6 40332.2 34099.5 34374.7 34807.8 35089.0 35314.1 30788.4 31222.4 31338.7 31534.1 32246.4 26378.0 26720.6 26727.9 26888.1 27105.0 08:57:36 INFO - 2016-02-25 08:57:36,862 DEBUG : BROWSER_OUTPUT: 08:57:36 INFO - 2016-02-25 08:57:36,862 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31307.82 Median:2351.03 stddev:99229.36 (4220.7%) stddev-sans-first:99480.57 08:57:36 INFO - 2016-02-25 08:57:36,864 DEBUG : BROWSER_OUTPUT: Values: 392.2 397.3 401.8 401.9 402.0 15889.8 16404.9 17860.5 18226.4 18251.9 15402.8 17116.3 17302.6 17440.3 17476.3 5085.8 5279.9 5283.5 5286.1 5291.3 4054.8 4172.6 5255.4 5289.8 5686.9 5774.2 5800.7 5801.7 5809.5 5811.5 5766.8 5954.2 6256.8 6303.1 6316.1 5606.6 5633.2 5642.6 5642.9 5649.9 20524.6 20822.7 21229.8 21250.3 21296.6 2317.6 2340.4 2361.7 2429.7 2446.8 10713.8 11300.6 13718.5 13857.9 14469.4 1949.7 2023.5 2039.3 2054.5 2065.8 63137.0 65505.3 65747.7 65867.0 67329.7 10971.4 11137.1 11146.1 11160.7 11212.7 15102.5 15169.7 15197.8 15221.8 15235.5 8790.6 8791.1 8813.5 8821.2 8843.5 8656.7 8667.5 8699.7 8746.3 8749.7 4758.9 4862.4 4869.8 4871.8 4905.2 1768.8 1838.9 1906.5 1956.7 2027.7 496.3 1016.4 1019.0 1053.3 1055.6 11206.8 11766.1 11977.2 11999.8 12039.9 457.3 512.6 516.9 517.4 521.5 6580.0 6626.9 6739.4 6757.3 6789.0 597866.0 600010.0 609861.0 610306.0 615751.0 83752.0 86191.0 86641.6 88406.0 89669.6 547.5 550.1 552.1 552.5 553.6 11361.7 11435.8 11449.8 11459.6 11519.5 37173.3 37272.6 37655.0 37866.1 37894.8 49720.0 51464.0 53459.5 54114.5 54863.5 10227.9 10307.1 10519.3 10730.4 10735.6 12483.1 12536.4 13065.9 18138.8 18443.3 15206.8 15302.8 15382.5 15496.7 15774.1 15417.2 15686.6 16023.9 16038.0 16154.8 11361.8 14989.2 15639.5 16008.8 16024.7 10166.1 10350.5 10379.2 10442.3 10496.8 10310.8 10384.7 11508.9 15148.8 15431.7 08:57:36 INFO - 2016-02-25 08:57:36,864 DEBUG : BROWSER_OUTPUT: 08:57:36 INFO - 2016-02-25 08:57:36,864 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5847.10 Median:6458.29 stddev:3045.04 (47.1%) stddev-sans-first:3036.47 08:57:36 INFO - 2016-02-25 08:57:36,865 DEBUG : BROWSER_OUTPUT: Values: 1552.1 1554.4 1821.9 1865.8 1878.3 7997.0 8466.5 8505.9 8506.1 8508.4 8344.4 8368.9 8407.1 8455.7 8479.8 2376.0 2392.9 2403.8 2433.3 2433.7 2362.4 2384.0 2414.8 2414.9 2417.4 2021.8 2092.8 2094.8 2343.9 2362.1 2086.5 2354.9 2404.7 2408.4 2410.0 2345.7 2349.7 2362.7 2377.2 2394.1 7117.3 7284.5 8126.9 8159.9 8183.7 4540.7 4668.3 4674.1 4682.3 4687.0 3070.4 3118.9 3136.6 3185.3 3202.9 1099.8 1107.5 1130.5 1131.5 1172.1 10858.1 11045.1 11054.8 11063.5 11074.2 4516.8 4586.8 4598.2 4719.8 4729.7 9832.3 9838.5 9843.0 9849.5 9854.6 5030.6 5053.1 5057.6 5059.2 5059.6 4540.4 4589.0 4591.4 4594.3 4599.4 3848.9 3890.2 3916.2 3922.1 3950.3 1185.5 1186.6 1196.8 1196.9 1200.1 1061.5 1097.3 1138.8 1159.6 1165.8 7972.7 7979.2 7995.5 8008.6 8015.6 1206.9 1208.4 1220.6 1225.4 1229.2 5757.5 6405.4 6455.2 6457.3 6459.3 9744.2 9895.9 9920.6 9942.6 10026.2 9736.2 9794.7 9853.8 9854.7 10198.9 7922.0 7947.4 7950.6 7974.1 8027.6 7836.6 7998.9 8013.8 8026.0 8035.5 8947.9 9074.0 9084.6 9100.8 9113.8 9159.5 9181.9 9314.4 9444.2 9448.1 7239.5 7269.1 7355.7 7362.0 7376.0 8187.2 8229.8 8233.0 8239.4 8244.3 7723.9 7817.5 7842.6 7887.3 7913.1 7457.3 7602.6 7633.3 7662.9 7739.5 7319.0 7398.2 7401.4 7402.4 7411.7 6823.0 6834.4 6898.2 6924.0 6929.9 7270.5 7321.3 7322.5 7324.3 7325.3 08:57:36 INFO - 2016-02-25 08:57:36,865 DEBUG : BROWSER_OUTPUT: 08:57:36 INFO - 2016-02-25 08:57:36,865 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5504.54 Median:6267.45 stddev:1634.24 (26.1%) stddev-sans-first:1592.75 08:57:36 INFO - 2016-02-25 08:57:36,866 DEBUG : BROWSER_OUTPUT: Values: 370.8 380.6 381.7 384.9 388.2 6140.8 6201.7 6208.8 6211.9 6225.8 5865.7 5970.6 6020.1 6122.3 6136.1 4753.8 4772.5 4776.6 4777.0 4778.5 4528.0 4781.2 4785.3 4793.8 4795.3 4571.3 4627.2 4690.7 4696.9 4721.6 4692.2 4731.1 4735.6 4741.5 4746.9 4231.5 4238.3 4239.4 4240.7 4247.9 5731.2 5822.1 5826.4 5831.4 5833.3 5578.6 5579.2 5579.2 5580.7 5583.1 6456.5 6464.5 6467.7 6470.8 6474.2 4708.7 4711.6 4714.0 4719.1 4739.5 6517.6 6661.4 6675.9 6677.8 6741.3 4699.5 4730.0 4731.4 4732.3 4735.8 4784.4 4786.3 4787.3 4790.2 4792.0 5348.2 5483.6 5490.5 5491.9 5492.3 6541.9 6550.5 6561.0 6566.2 6569.0 1670.1 1671.2 1673.4 1673.7 1675.0 1141.8 1152.6 1153.3 1159.8 1179.6 6277.2 6285.1 6286.1 6287.4 6296.7 6515.9 6716.7 6733.4 6733.5 6735.8 6258.7 6293.7 6441.2 6445.7 6448.6 3615.9 3674.3 3674.6 3678.1 3680.9 6876.1 6998.7 7013.7 7016.3 7025.8 6940.9 6942.4 6984.3 6996.4 7004.7 7028.3 7029.2 7030.8 7034.9 7038.4 6664.9 6811.8 6813.2 6819.6 6834.2 6753.7 6764.3 6769.7 6775.4 6776.5 7027.9 7030.5 7037.4 7042.5 7049.5 4735.1 4826.9 4858.5 4865.5 4885.0 6765.2 6765.7 6772.9 6774.5 6776.6 6596.8 6598.7 6608.3 6616.2 6618.5 6442.7 6583.3 6587.2 6594.3 6606.0 6457.2 6458.5 6467.4 6472.6 6479.3 6580.0 6581.0 6599.9 6603.1 6604.7 6276.2 6345.7 6438.4 6439.6 6458.9 08:57:36 INFO - 2016-02-25 08:57:36,867 DEBUG : BROWSER_OUTPUT: 08:57:36 INFO - 2016-02-25 08:57:36,867 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14216.21 Median:4586.23 stddev:16078.82 (350.6%) stddev-sans-first:16092.70 08:57:36 INFO - 2016-02-25 08:57:36,867 DEBUG : BROWSER_OUTPUT: Values: 872.5 897.6 902.2 912.6 917.5 13659.5 13761.2 14010.6 14137.7 14159.2 13709.7 13719.1 13761.5 13792.5 13848.6 781.3 910.8 924.9 924.9 925.6 773.7 783.4 783.4 925.5 936.8 962.3 964.7 964.8 966.4 972.0 956.3 960.2 960.2 960.4 960.7 602.0 604.3 715.2 719.3 719.4 14066.9 14093.9 14137.7 14154.4 14160.5 13005.5 13236.9 13338.0 13436.8 13458.1 10325.9 10487.7 10928.8 11189.2 11198.5 3775.0 3793.1 3818.1 3834.2 3836.1 48124.8 48419.5 48679.3 50414.0 50596.7 10557.9 10586.8 10788.2 10840.7 10972.3 18536.5 18538.6 18550.0 18603.3 18612.3 7730.2 7834.2 7841.2 7936.9 7985.8 4584.3 4588.2 4591.3 4612.0 4760.7 3245.0 3257.0 3262.5 3269.8 3315.0 612.3 614.6 619.8 623.6 651.0 698.4 710.2 722.5 726.1 741.2 8626.9 8633.2 8641.3 8742.3 8850.3 563.2 701.1 722.1 731.9 737.1 4225.6 4227.6 4254.8 4448.8 4529.5 56618.2 57200.0 57533.4 57706.0 58113.7 49927.0 50900.0 50979.5 51488.5 51579.5 46559.3 46822.5 46859.0 47182.8 47676.0 8867.1 8953.1 9100.0 9247.4 9268.0 35031.6 35732.6 37047.6 38226.0 39228.4 44418.6 45359.1 45414.0 45451.3 46092.8 11560.0 11739.7 11903.4 11927.1 12192.2 12776.7 12987.9 13393.9 13745.9 14050.9 11679.6 11728.3 11743.6 11775.6 12445.0 11818.8 11819.7 11885.1 12134.2 12276.3 10101.2 11200.3 11428.7 11560.9 11911.8 9746.8 9855.3 10114.6 10382.6 10472.9 9665.3 9978.7 10000.4 10009.5 10336.1 08:57:36 INFO - 2016-02-25 08:57:36,867 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:57:36 INFO - 2016-02-25 08:57:36,867 DEBUG : BROWSER_OUTPUT: 08:57:37 INFO - 2016-02-25 08:57:37,430 INFO : Browser exited with error code: 0 08:57:37 INFO - 2016-02-25 08:57:37,448 INFO : Completed test dromaeo_css (00:18:29) 08:57:37 INFO - 2016-02-25 08:57:37,448 INFO : Starting test kraken 08:57:37 INFO - 2016-02-25 08:57:37,448 DEBUG : operating with platform_type : linux_ 08:57:37 INFO - 2016-02-25 08:57:37,448 INFO : Initialising browser for kraken test... 08:57:37 INFO - 2016-02-25 08:57:37,454 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaEUNtt/profile http://localhost:34752/getInfo.html 08:57:40 INFO - 2016-02-25 08:57:40,806 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:57:40 INFO - 2016-02-25 08:57:40,806 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:57:40 INFO - 2016-02-25 08:57:40,806 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:57:40 INFO - 2016-02-25 08:57:40,806 DEBUG : BROWSER_OUTPUT: __metrics 08:57:40 INFO - 2016-02-25 08:57:40,915 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] 08:57:41 INFO - 2016-02-25 08:57:41,966 INFO : Browser initialized. 08:57:41 INFO - 2016-02-25 08:57:41,966 INFO : Running cycle 1/1 for kraken test... 08:57:41 INFO - 2016-02-25 08:57:41,966 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaEUNtt/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:57:53 INFO - 2016-02-25 08:57:53,609 DEBUG : BROWSER_OUTPUT: RSS: Main: 132567040 08:57:53 INFO - 2016-02-25 08:57:53,609 DEBUG : BROWSER_OUTPUT: 08:57:56 INFO - 2016-02-25 08:57:56,015 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34752/tests/kraken/driver.html?testName=audio-beat-detection) 08:57:56 INFO - 2016-02-25 08:57:56,597 DEBUG : BROWSER_OUTPUT: RSS: Main: 164675584 08:57:56 INFO - 2016-02-25 08:57:56,598 DEBUG : BROWSER_OUTPUT: 08:57:59 INFO - 2016-02-25 08:57:59,184 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34752/tests/kraken/driver.html?testName=audio-dft) 08:57:59 INFO - 2016-02-25 08:57:59,745 DEBUG : BROWSER_OUTPUT: RSS: Main: 161628160 08:57:59 INFO - 2016-02-25 08:57:59,745 DEBUG : BROWSER_OUTPUT: 08:58:02 INFO - 2016-02-25 08:58:02,522 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34752/tests/kraken/driver.html?testName=audio-fft) 08:58:03 INFO - 2016-02-25 08:58:03,084 DEBUG : BROWSER_OUTPUT: RSS: Main: 159354880 08:58:03 INFO - 2016-02-25 08:58:03,084 DEBUG : BROWSER_OUTPUT: 08:58:05 INFO - 2016-02-25 08:58:05,049 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34752/tests/kraken/driver.html?testName=audio-oscillator) 08:58:05 INFO - 2016-02-25 08:58:05,610 DEBUG : BROWSER_OUTPUT: RSS: Main: 158625792 08:58:05 INFO - 2016-02-25 08:58:05,610 DEBUG : BROWSER_OUTPUT: 08:58:07 INFO - 2016-02-25 08:58:07,768 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34752/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:58:08 INFO - 2016-02-25 08:58:08,330 DEBUG : BROWSER_OUTPUT: RSS: Main: 158674944 08:58:08 INFO - 2016-02-25 08:58:08,330 DEBUG : BROWSER_OUTPUT: 08:58:11 INFO - 2016-02-25 08:58:11,211 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34752/tests/kraken/driver.html?testName=imaging-darkroom) 08:58:11 INFO - 2016-02-25 08:58:11,780 DEBUG : BROWSER_OUTPUT: RSS: Main: 157536256 08:58:11 INFO - 2016-02-25 08:58:11,780 DEBUG : BROWSER_OUTPUT: 08:58:15 INFO - 2016-02-25 08:58:15,101 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34752/tests/kraken/driver.html?testName=imaging-desaturate) 08:58:15 INFO - 2016-02-25 08:58:15,670 DEBUG : BROWSER_OUTPUT: RSS: Main: 157450240 08:58:15 INFO - 2016-02-25 08:58:15,670 DEBUG : BROWSER_OUTPUT: 08:58:18 INFO - 2016-02-25 08:58:18,379 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34752/tests/kraken/driver.html?testName=json-parse-financial) 08:58:18 INFO - 2016-02-25 08:58:18,946 DEBUG : BROWSER_OUTPUT: RSS: Main: 158695424 08:58:18 INFO - 2016-02-25 08:58:18,946 DEBUG : BROWSER_OUTPUT: 08:58:20 INFO - 2016-02-25 08:58:20,480 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34752/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:58:21 INFO - 2016-02-25 08:58:21,042 DEBUG : BROWSER_OUTPUT: RSS: Main: 165916672 08:58:21 INFO - 2016-02-25 08:58:21,042 DEBUG : BROWSER_OUTPUT: 08:58:22 INFO - 2016-02-25 08:58:22,794 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34752/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:58:23 INFO - 2016-02-25 08:58:23,389 DEBUG : BROWSER_OUTPUT: RSS: Main: 162189312 08:58:23 INFO - 2016-02-25 08:58:23,389 DEBUG : BROWSER_OUTPUT: 08:58:25 INFO - 2016-02-25 08:58:25,387 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34752/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:58:25 INFO - 2016-02-25 08:58:25,961 DEBUG : BROWSER_OUTPUT: RSS: Main: 170602496 08:58:25 INFO - 2016-02-25 08:58:25,961 DEBUG : BROWSER_OUTPUT: 08:58:28 INFO - 2016-02-25 08:58:28,296 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34752/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:58:28 INFO - 2016-02-25 08:58:28,870 DEBUG : BROWSER_OUTPUT: RSS: Main: 177913856 08:58:28 INFO - 2016-02-25 08:58:28,870 DEBUG : BROWSER_OUTPUT: 08:58:31 INFO - 2016-02-25 08:58:31,324 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34752/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:58:31 INFO - 2016-02-25 08:58:31,898 DEBUG : BROWSER_OUTPUT: RSS: Main: 176537600 08:58:31 INFO - 2016-02-25 08:58:31,898 DEBUG : BROWSER_OUTPUT: 08:58:33 INFO - 2016-02-25 08:58:33,541 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34752/tests/kraken/driver.html?testName=ai-astar) 08:58:33 INFO - 2016-02-25 08:58:33,800 DEBUG : BROWSER_OUTPUT: RSS: Main: 234921984 08:58:33 INFO - 2016-02-25 08:58:33,800 DEBUG : BROWSER_OUTPUT: 08:58:33 INFO - 2016-02-25 08:58:33,800 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:58:33 INFO - 2016-02-25 08:58:33,800 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:58:33 INFO - 2016-02-25 08:58:33,800 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:58:33 INFO - 2016-02-25 08:58:33,800 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:58:33 INFO - 2016-02-25 08:58:33,800 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;102;108;110;109;98;108;108;109 08:58:33 INFO - 2016-02-25 08:58:33,800 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;124;159;164;130;145;154;123;160;127 08:58:33 INFO - 2016-02-25 08:58:33,800 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;159;159;161;161;160;161;162;161;161 08:58:33 INFO - 2016-02-25 08:58:33,801 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;72;71;144;73;73;73;71;151;74 08:58:33 INFO - 2016-02-25 08:58:33,801 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;92;138;92;92;92;151;92;92;139 08:58:33 INFO - 2016-02-25 08:58:33,801 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;108;109;109;109;109;108;109;109 08:58:33 INFO - 2016-02-25 08:58:33,801 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;146;145;145;145;145;145;146;145 08:58:33 INFO - 2016-02-25 08:58:33,801 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;95;95;94;95;95;95;95 08:58:33 INFO - 2016-02-25 08:58:33,801 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;56;58;56;59;57;58;56;55;54 08:58:33 INFO - 2016-02-25 08:58:33,801 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;64;64;64;65;66;64;64;65 08:58:33 INFO - 2016-02-25 08:58:33,801 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;77;79;81;78;87;79;78;76;80 08:58:33 INFO - 2016-02-25 08:58:33,801 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;114;109;108;119;109;115;117;110;112;118 08:58:33 INFO - 2016-02-25 08:58:33,802 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;142;139;141;140;139;132;139;140;145;128 08:58:33 INFO - 2016-02-25 08:58:33,802 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;61;62;65;62;63;62;62;65;62;62 08:58:33 INFO - 2016-02-25 08:58:33,802 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:58:33 INFO - 2016-02-25 08:58:33,802 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:58:33 INFO - 2016-02-25 08:58:33,802 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,879 08:58:33 INFO - 2016-02-25 08:58:33,802 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:58:33 INFO - 2016-02-25 08:58:33,802 DEBUG : BROWSER_OUTPUT: __startTimestamp1456419513794__endTimestamp 08:58:33 INFO - 2016-02-25 08:58:33,803 DEBUG : BROWSER_OUTPUT: 08:58:33 INFO - 2016-02-25 08:58:33,803 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:58:33 INFO - 2016-02-25 08:58:33,803 DEBUG : BROWSER_OUTPUT: Number of tests: 14 08:58:33 INFO - 2016-02-25 08:58:33,803 DEBUG : BROWSER_OUTPUT: 08:58:33 INFO - 2016-02-25 08:58:33,803 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.00 Median:109.00 stddev:3.89 (3.6%) stddev-sans-first:4.11 08:58:33 INFO - 2016-02-25 08:58:33,803 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 102.0 108.0 110.0 109.0 98.0 108.0 108.0 109.0 08:58:33 INFO - 2016-02-25 08:58:33,804 DEBUG : BROWSER_OUTPUT: 08:58:33 INFO - 2016-02-25 08:58:33,805 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.50 Median:151.50 stddev:16.09 (10.6%) stddev-sans-first:16.94 08:58:33 INFO - 2016-02-25 08:58:33,805 DEBUG : BROWSER_OUTPUT: Values: 149.0 124.0 159.0 164.0 130.0 145.0 154.0 123.0 160.0 127.0 08:58:33 INFO - 2016-02-25 08:58:33,805 DEBUG : BROWSER_OUTPUT: 08:58:33 INFO - 2016-02-25 08:58:33,805 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 08:58:33 INFO - 2016-02-25 08:58:33,805 DEBUG : BROWSER_OUTPUT: Values: 160.0 159.0 159.0 161.0 161.0 160.0 161.0 162.0 161.0 161.0 08:58:33 INFO - 2016-02-25 08:58:33,805 DEBUG : BROWSER_OUTPUT: 08:58:33 INFO - 2016-02-25 08:58:33,806 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.20 Median:72.50 stddev:31.85 (43.9%) stddev-sans-first:33.16 08:58:33 INFO - 2016-02-25 08:58:33,806 DEBUG : BROWSER_OUTPUT: Values: 70.0 72.0 71.0 144.0 73.0 73.0 73.0 71.0 151.0 74.0 08:58:33 INFO - 2016-02-25 08:58:33,806 DEBUG : BROWSER_OUTPUT: 08:58:33 INFO - 2016-02-25 08:58:33,806 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.20 Median:92.00 stddev:24.71 (26.9%) stddev-sans-first:25.59 08:58:33 INFO - 2016-02-25 08:58:33,806 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 138.0 92.0 92.0 92.0 151.0 92.0 92.0 139.0 08:58:33 INFO - 2016-02-25 08:58:33,806 DEBUG : BROWSER_OUTPUT: 08:58:33 INFO - 2016-02-25 08:58:33,806 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:108.80 Median:109.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 08:58:33 INFO - 2016-02-25 08:58:33,806 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 108.0 109.0 109.0 109.0 109.0 108.0 109.0 109.0 08:58:33 INFO - 2016-02-25 08:58:33,806 DEBUG : BROWSER_OUTPUT: 08:58:33 INFO - 2016-02-25 08:58:33,806 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 08:58:33 INFO - 2016-02-25 08:58:33,806 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 146.0 145.0 145.0 145.0 145.0 145.0 146.0 145.0 08:58:33 INFO - 2016-02-25 08:58:33,806 DEBUG : BROWSER_OUTPUT: 08:58:33 INFO - 2016-02-25 08:58:33,806 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 08:58:33 INFO - 2016-02-25 08:58:33,806 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 08:58:33 INFO - 2016-02-25 08:58:33,806 DEBUG : BROWSER_OUTPUT: 08:58:33 INFO - 2016-02-25 08:58:33,807 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:1.51 (2.6%) stddev-sans-first:1.59 08:58:33 INFO - 2016-02-25 08:58:33,807 DEBUG : BROWSER_OUTPUT: Values: 57.0 56.0 58.0 56.0 59.0 57.0 58.0 56.0 55.0 54.0 08:58:33 INFO - 2016-02-25 08:58:33,807 DEBUG : BROWSER_OUTPUT: 08:58:33 INFO - 2016-02-25 08:58:33,807 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.40 Median:64.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 08:58:33 INFO - 2016-02-25 08:58:33,807 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 64.0 64.0 64.0 65.0 66.0 64.0 64.0 65.0 08:58:33 INFO - 2016-02-25 08:58:33,807 DEBUG : BROWSER_OUTPUT: 08:58:33 INFO - 2016-02-25 08:58:33,807 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:79.50 stddev:3.22 (4.1%) stddev-sans-first:3.21 08:58:33 INFO - 2016-02-25 08:58:33,807 DEBUG : BROWSER_OUTPUT: Values: 83.0 77.0 79.0 81.0 78.0 87.0 79.0 78.0 76.0 80.0 08:58:33 INFO - 2016-02-25 08:58:33,807 DEBUG : BROWSER_OUTPUT: 08:58:33 INFO - 2016-02-25 08:58:33,807 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:113.10 Median:114.50 stddev:4.07 (3.6%) stddev-sans-first:4.30 08:58:33 INFO - 2016-02-25 08:58:33,807 DEBUG : BROWSER_OUTPUT: Values: 114.0 109.0 108.0 119.0 109.0 115.0 117.0 110.0 112.0 118.0 08:58:33 INFO - 2016-02-25 08:58:33,807 DEBUG : BROWSER_OUTPUT: 08:58:33 INFO - 2016-02-25 08:58:33,807 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.50 Median:140.00 stddev:4.93 (3.5%) stddev-sans-first:5.06 08:58:33 INFO - 2016-02-25 08:58:33,807 DEBUG : BROWSER_OUTPUT: Values: 142.0 139.0 141.0 140.0 139.0 132.0 139.0 140.0 145.0 128.0 08:58:33 INFO - 2016-02-25 08:58:33,807 DEBUG : BROWSER_OUTPUT: 08:58:33 INFO - 2016-02-25 08:58:33,807 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:62.00 stddev:1.35 (2.2%) stddev-sans-first:1.30 08:58:33 INFO - 2016-02-25 08:58:33,808 DEBUG : BROWSER_OUTPUT: Values: 61.0 62.0 65.0 62.0 63.0 62.0 62.0 65.0 62.0 62.0 08:58:33 INFO - 2016-02-25 08:58:33,808 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:58:33 INFO - 2016-02-25 08:58:33,808 DEBUG : BROWSER_OUTPUT: 08:58:34 INFO - 2016-02-25 08:58:34,458 INFO : Browser exited with error code: 0 08:58:34 INFO - 2016-02-25 08:58:34,506 INFO : Completed test kraken (00:00:57) 08:58:34 INFO - 2016-02-25 08:58:34,506 INFO : Starting test v8_7 08:58:34 INFO - 2016-02-25 08:58:34,506 DEBUG : operating with platform_type : linux_ 08:58:34 INFO - 2016-02-25 08:58:34,506 INFO : Initialising browser for v8_7 test... 08:58:34 INFO - 2016-02-25 08:58:34,512 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRwJssm/profile http://localhost:34752/getInfo.html 08:58:38 INFO - 2016-02-25 08:58:38,016 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:58:38 INFO - 2016-02-25 08:58:38,016 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:58:38 INFO - 2016-02-25 08:58:38,016 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:58:38 INFO - 2016-02-25 08:58:38,016 DEBUG : BROWSER_OUTPUT: __metrics 08:58:38 INFO - 2016-02-25 08:58:38,145 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] 08:58:38 INFO - 2016-02-25 08:58:38,990 INFO : Browser initialized. 08:58:38 INFO - 2016-02-25 08:58:38,990 INFO : Running cycle 1/1 for v8_7 test... 08:58:38 INFO - 2016-02-25 08:58:38,990 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRwJssm/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:58:50 INFO - 2016-02-25 08:58:50,709 DEBUG : BROWSER_OUTPUT: RSS: Main: 134905856 08:58:50 INFO - 2016-02-25 08:58:50,709 DEBUG : BROWSER_OUTPUT: 08:59:12 INFO - 2016-02-25 08:59:12,098 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34752/tests/v8_7/run.html (next: http://localhost:34752/tests/v8_7/run.html) 08:59:12 INFO - 2016-02-25 08:59:12,348 DEBUG : BROWSER_OUTPUT: RSS: Main: 421572608 08:59:12 INFO - 2016-02-25 08:59:12,349 DEBUG : BROWSER_OUTPUT: 08:59:12 INFO - 2016-02-25 08:59:12,350 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:59:12 INFO - 2016-02-25 08:59:12,350 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:59:12 INFO - 2016-02-25 08:59:12,350 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:59:12 INFO - 2016-02-25 08:59:12,350 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:59:12 INFO - 2016-02-25 08:59:12,351 DEBUG : BROWSER_OUTPUT: |0;Richards;168.83336147222693 08:59:12 INFO - 2016-02-25 08:59:12,351 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;166.03021749958492 08:59:12 INFO - 2016-02-25 08:59:12,351 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.08676307007784 08:59:12 INFO - 2016-02-25 08:59:12,351 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5138.461538461538 08:59:12 INFO - 2016-02-25 08:59:12,351 DEBUG : BROWSER_OUTPUT: |4;RayTrace;975.609756097561 08:59:12 INFO - 2016-02-25 08:59:12,351 DEBUG : BROWSER_OUTPUT: |5;Earley;542.0054200542005 08:59:12 INFO - 2016-02-25 08:59:12,351 DEBUG : BROWSER_OUTPUT: |6;Boyer;10547.368421052632 08:59:12 INFO - 2016-02-25 08:59:12,352 DEBUG : BROWSER_OUTPUT: |7;RegExp;24756.09756097561 08:59:12 INFO - 2016-02-25 08:59:12,352 DEBUG : BROWSER_OUTPUT: |8;Splay;509.1649694501018 08:59:12 INFO - 2016-02-25 08:59:12,352 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 08:59:12 INFO - 2016-02-25 08:59:12,352 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:59:12 INFO - 2016-02-25 08:59:12,352 DEBUG : BROWSER_OUTPUT: __startTimestamp1456419552349__endTimestamp 08:59:12 INFO - 2016-02-25 08:59:12,353 DEBUG : BROWSER_OUTPUT: 08:59:12 INFO - 2016-02-25 08:59:12,353 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:59:12 INFO - 2016-02-25 08:59:12,353 DEBUG : BROWSER_OUTPUT: Number of tests: 10 08:59:12 INFO - 2016-02-25 08:59:12,353 DEBUG : BROWSER_OUTPUT: 08:59:12 INFO - 2016-02-25 08:59:12,353 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:168.83 Median:168.83 stddev:0.00 (0.0%) 08:59:12 INFO - 2016-02-25 08:59:12,354 DEBUG : BROWSER_OUTPUT: Values: 168.8 08:59:12 INFO - 2016-02-25 08:59:12,354 DEBUG : BROWSER_OUTPUT: 08:59:12 INFO - 2016-02-25 08:59:12,354 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:166.03 Median:166.03 stddev:0.00 (0.0%) 08:59:12 INFO - 2016-02-25 08:59:12,354 DEBUG : BROWSER_OUTPUT: Values: 166.0 08:59:12 INFO - 2016-02-25 08:59:12,354 DEBUG : BROWSER_OUTPUT: 08:59:12 INFO - 2016-02-25 08:59:12,354 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.09 Median:278.09 stddev:0.00 (0.0%) 08:59:12 INFO - 2016-02-25 08:59:12,356 DEBUG : BROWSER_OUTPUT: Values: 278.1 08:59:12 INFO - 2016-02-25 08:59:12,356 DEBUG : BROWSER_OUTPUT: 08:59:12 INFO - 2016-02-25 08:59:12,356 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5138.46 Median:5138.46 stddev:0.00 (0.0%) 08:59:12 INFO - 2016-02-25 08:59:12,356 DEBUG : BROWSER_OUTPUT: Values: 5138.5 08:59:12 INFO - 2016-02-25 08:59:12,356 DEBUG : BROWSER_OUTPUT: 08:59:12 INFO - 2016-02-25 08:59:12,356 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:975.61 Median:975.61 stddev:0.00 (0.0%) 08:59:12 INFO - 2016-02-25 08:59:12,356 DEBUG : BROWSER_OUTPUT: Values: 975.6 08:59:12 INFO - 2016-02-25 08:59:12,357 DEBUG : BROWSER_OUTPUT: 08:59:12 INFO - 2016-02-25 08:59:12,357 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:542.01 Median:542.01 stddev:0.00 (0.0%) 08:59:12 INFO - 2016-02-25 08:59:12,357 DEBUG : BROWSER_OUTPUT: Values: 542.0 08:59:12 INFO - 2016-02-25 08:59:12,357 DEBUG : BROWSER_OUTPUT: 08:59:12 INFO - 2016-02-25 08:59:12,357 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10547.37 Median:10547.37 stddev:0.00 (0.0%) 08:59:12 INFO - 2016-02-25 08:59:12,357 DEBUG : BROWSER_OUTPUT: Values: 10547.4 08:59:12 INFO - 2016-02-25 08:59:12,357 DEBUG : BROWSER_OUTPUT: 08:59:12 INFO - 2016-02-25 08:59:12,358 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24756.10 Median:24756.10 stddev:0.00 (0.0%) 08:59:12 INFO - 2016-02-25 08:59:12,358 DEBUG : BROWSER_OUTPUT: Values: 24756.1 08:59:12 INFO - 2016-02-25 08:59:12,358 DEBUG : BROWSER_OUTPUT: 08:59:12 INFO - 2016-02-25 08:59:12,358 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:509.16 Median:509.16 stddev:0.00 (0.0%) 08:59:12 INFO - 2016-02-25 08:59:12,358 DEBUG : BROWSER_OUTPUT: Values: 509.2 08:59:12 INFO - 2016-02-25 08:59:12,358 DEBUG : BROWSER_OUTPUT: 08:59:12 INFO - 2016-02-25 08:59:12,358 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 08:59:12 INFO - 2016-02-25 08:59:12,358 DEBUG : BROWSER_OUTPUT: Values: 4826.9 08:59:12 INFO - 2016-02-25 08:59:12,359 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:59:12 INFO - 2016-02-25 08:59:12,359 DEBUG : BROWSER_OUTPUT: 08:59:12 INFO - 2016-02-25 08:59:12,993 INFO : Browser exited with error code: 0 08:59:13 INFO - 2016-02-25 08:59:13,010 INFO : Completed test v8_7 (00:00:38) 08:59:13 INFO - 2016-02-25 08:59:13,384 INFO : Completed test suite (00:20:05) 08:59:13 INFO - 2016-02-25 08:59:13,384 DEBUG : Working with test: dromaeo_css 08:59:13 INFO - 2016-02-25 08:59:13,385 DEBUG : Generating results file: dromaeo_css 08:59:13 INFO - 2016-02-25 08:59:13,386 DEBUG : Working with test: kraken 08:59:13 INFO - 2016-02-25 08:59:13,387 DEBUG : Generating results file: kraken 08:59:13 INFO - 2016-02-25 08:59:13,387 INFO : javascript benchmark 08:59:13 INFO - 2016-02-25 08:59:13,387 DEBUG : Working with test: v8_7 08:59:13 INFO - 2016-02-25 08:59:13,387 DEBUG : Generating results file: v8_7 08:59:13 INFO - 2016-02-25 08:59:13,388 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:59:13 INFO - 2016-02-25 08:59:13,453 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 08:59:13 INFO - 2016-02-25 08:59:13,453 DEBUG : process_Request line: dromaeo_css 7341.54 graph.html#tests=[[72,53,35]] 08:59:13 INFO - 2016-02-25 08:59:13,453 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:59:13 INFO - 2016-02-25 08:59:13,487 DEBUG : process_Request line: kraken 1469.40 graph.html#tests=[[232,53,35]] 08:59:13 INFO - 2016-02-25 08:59:13,487 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:59:13 INFO - 2016-02-25 08:59:13,529 DEBUG : process_Request line: v8_7 23231.54 graph.html#tests=[[230,53,35]] 08:59:13 INFO - 2016-02-25 08:59:13,530 INFO : javascript benchmark 08:59:13 INFO - 2016-02-25 08:59:13,534 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3298.2414044769703, 3368.4947257911326, 3388.4070287699647, 3388.542546756187, 3411.914702132447, 5644.407337229593, 5658.207850900867, 5674.375818659944, 5674.829755107348, 5676.858078548038, 5703.800366987156, 5706.3722990471015, 5714.857128571788, 5715.228444160037, 5715.399883012286, 4976.62675299354, 5145.845624631248, 5188.6367954243215, 5253.422123566424, 5262.4211336753115, 5012.674176178555, 5246.370435547739, 5259.710715910624, 5261.947380526203, 5264.6051546134, 5190.6107041972, 5195.454477279888, 5288.444713305086, 5297.0, 5328.0, 5231.73841307935, 5234.136367499358, 5248.160294352923, 5250.81622143227, 5283.815066472655, 5062.898742025176, 5105.259737338102, 5117.820876269313, 5144.0, 5150.3047088642925, 5532.889342213173, 5547.057000309957, 5550.583706222009, 5560.582956278255, 5564.026295398329, 6122.540809439265, 6138.14066030756, 6160.87678246437, 6160.87678246437, 6165.630062196237, 5955.970220148872, 6072.483838873701, 6104.511639068864, 6177.598456100384, 6226.59527130735, 5680.147977803321, 5680.403557626472, 5681.744321505584, 5683.261176047087, 5708.65748055118, 6160.322364539897, 6170.444659980623, 6176.722047507873, 6177.444030037318, 6181.227346581677, 5753.942460575341, 5922.733476993546, 5997.490213331828, 6112.8160236355625, 6125.551374386306, 6069.119977603223, 6069.211002569726, 6070.878582428327, 6075.513958883367, 6091.756329746849, 5907.822765317047, 5973.79091731796, 6039.8490037748525, 6040.214772079602, 6045.486133678686, 5904.586678932434, 5998.070299103646, 6002.339742628307, 6037.667928263988, 6051.092336149525, 4591.862244132682, 4637.0, 4639.652026097989, 4656.231721765871, 4684.461286951976, 4066.959330406717, 4180.853670121531, 4187.5184353799095, 4196.706230563892, 4211.0, 5655.604107712503, 5732.054211055212, 5750.2237197978575, 5755.107958266475, 5792.4497172768515, 6199.132121502993, 6226.657533835682, 6237.251529816451, 6239.500839932794, 6247.094171345129, 5489.451054894478, 5576.749046292926, 5855.882882342415, 5896.1450589664255, 5911.349751527328, 5120.615953803405, 5185.948140518622, 5188.558972487305, 5189.143791274398, 5192.4807519248525, 6307.905381419282, 6365.331640177715, 6369.649669268235, 6371.585846919936, 6373.553851230369, 6005.129256257906, 6055.1522786809, 6140.785072522619, 6177.84555386101, 6178.4439400452375, 6181.505479561534, 6191.81424557264, 6196.380361963767, 6197.690115494297, 6202.193714817045, 5936.406359364029, 5946.07835785454, 5952.404759524186, 5954.047352423591, 5975.940240597713, 5960.582759206987, 6044.455998960115, 6045.75816967334, 6077.544184186116, 6145.8156255312415, 5934.347221805511, 5975.0, 5978.491828194652, 5984.102384642339, 5985.970070149621, 5926.081457374114, 5934.19888315072, 5991.7603295867675, 5994.880102398015, 6005.8288633716, 5638.689872057076, 5754.9136762947755, 5829.271341082364, 5867.413258674269, 5940.346561878104, 5957.225560677084, 5972.104184372379, 5972.223610930551, 5983.55123365758, 5989.670568118621, 5888.852778680567, 5891.941080589139, 5892.35184129754, 5894.616849904743, 5898.233229680286], "unit": "ms", "name": "dojo.html", "value": 5608.910284329904}, {"lowerIsBetter": false, "replicates": [591.3760982163816, 644.6164532103398, 651.1957732200731, 663.2339647706895, 668.7594512179907, 33236.0, 34012.0, 34080.318393632115, 41701.37447938283, 41839.58160418395, 46431.76784116075, 46938.0, 46959.76520117395, 47038.29442558364, 47078.29382559264, 8183.468074575163, 9106.499142547174, 9216.769580760501, 9284.628614855432, 9293.349465537414, 8328.791780205484, 8438.57807109642, 10935.070519005894, 11230.15773816963, 11241.60654377097, 10866.0, 10967.122630189566, 10973.122150227962, 10980.121590272758, 10988.175886808482, 10842.403667798268, 10927.836082458769, 10939.83590246147, 10941.507632156572, 10950.178736594748, 10653.307535010277, 10695.465226738632, 10753.946230268877, 10774.245802793808, 10790.190735694854, 42121.0, 42432.151356972994, 42466.0, 42526.57473425288, 42562.0, 3688.2992231475932, 3722.236941427001, 3738.9813050934845, 4114.382842573608, 4361.563843615645, 32027.679723202702, 32051.358972820646, 32381.838090809397, 32525.51211731826, 32766.0, 4372.584604462571, 4434.22401079813, 4462.955370446319, 4482.260427029536, 4487.8429254976245, 303860.0, 305211.0, 305838.0, 306502.9999999978, 307791.0, 55128.72435637876, 55904.16143757847, 63652.0, 64159.679201604624, 64208.0, 75253.62373188099, 75879.24120758832, 76158.61920690472, 76314.0, 76620.0, 17386.3045478182, 17469.0, 17505.12474376276, 17620.118994050503, 17629.382971595933, 13190.340482975813, 13248.668783280302, 13269.336533173302, 13312.334383280797, 13374.13068150567, 20777.792222077587, 21114.894425527775, 21118.47203819917, 21118.78881211199, 21119.366419007456, 8118.675252989932, 8378.49729016261, 8404.025054112279, 8419.873701894476, 8541.572921354033, 959.3092973059386, 1087.586717047517, 1163.5927425401042, 1178.911581631381, 1200.8498937632796, 29623.25941851471, 29954.0, 30149.849250754043, 30635.8468207662, 30796.538051929238, 1126.9549218031186, 1155.3530023187043, 1188.1504724122294, 1247.2641141726424, 1259.0494176896384, 13132.934335328262, 13731.45074197041, 13883.236421996888, 14100.85899141016, 15607.687846243012, 361826.0, 363905.0, 364459.0, 364606.0, 364759.0, 47131.764341178074, 47238.52761472341, 53705.46294537005, 54667.0, 55366.44633553694, 1472.9410823567152, 1498.655309278872, 1513.3946421431515, 1514.3866733972538, 1518.5444366689949, 26236.21291361262, 27149.457010860067, 27310.590341145296, 27393.86303068472, 30011.69988300089, 47151.52848471609, 47302.526974729815, 47407.76296118635, 47453.76273118612, 47543.286850696575, 212607.0, 213578.0, 214013.0, 214673.0, 214724.0, 33137.0, 33140.83429582837, 33377.0, 33940.66059339375, 34026.829865850515, 43448.56551434445, 43674.12651747011, 46966.29550556813, 47676.523234767206, 47915.520844791106, 38805.80597096997, 39548.0, 39871.60128398679, 39896.80051599723, 40370.56850167651, 36592.45111323279, 39342.606573933896, 40049.79975100106, 40057.599424006556, 40332.1933561333, 34099.488507672904, 34374.65625343715, 34807.82596087003, 35089.0, 35314.11714707153, 30788.384232315675, 31222.375552488367, 31338.686613133577, 31534.053978380685, 32246.35507289888, 26378.0, 26720.599191012538, 26727.86636066807, 26888.05891793778, 27105.0], "unit": "ms", "name": "ext.html", "value": 19895.805207243804}, {"lowerIsBetter": false, "replicates": [392.20186919618584, 397.3007506788054, 401.8151650240889, 401.85533208045103, 401.95377531583875, 15889.761653575177, 16404.917975410106, 17860.464186074405, 18226.362077327296, 18251.90874045631, 15402.768958465633, 17116.31534738612, 17302.56743581411, 17440.3023879045, 17476.300947962067, 5085.771140298677, 5279.894402111955, 5283.524482796547, 5286.101362768339, 5291.259223708684, 4054.776987265699, 4172.561881002482, 5255.369355677324, 5289.814856480024, 5686.943130568703, 5774.191613174159, 5800.709964501779, 5801.7389217485315, 5809.535237181015, 5811.476967072963, 5766.769329226826, 5954.225950626413, 6256.78101266458, 6303.149074874925, 6316.115743795872, 5606.55147588192, 5633.211350410946, 5642.633228840113, 5642.915356269659, 5649.8587535311535, 20524.5895082099, 20822.687659684965, 21229.787702122936, 21250.256241031646, 21296.615719978152, 2317.582835089683, 2340.368100612825, 2361.6929799126174, 2429.7448767879473, 2446.755324467539, 10713.78572428547, 11300.604478843283, 13718.519851805238, 13857.89136869048, 14469.421223151061, 1949.6783030799902, 2023.5345870449878, 2039.277564451459, 2054.4863784053987, 2065.75210974684, 63137.0, 65505.3449465504, 65747.67126164386, 65866.99999999952, 67329.66335168293, 10971.396573188551, 11137.05335046514, 11146.108311335236, 11160.665180044612, 11212.66362009141, 15102.471413500474, 15169.696606067817, 15197.848021519865, 15221.847781522043, 15235.542933711784, 8790.560471976503, 8791.120887911158, 8813.515256660945, 8821.206091451671, 8843.51360675169, 8656.740297791077, 8667.479951202948, 8699.652013919373, 8746.300295976307, 8749.693760718344, 4758.904821903542, 4862.41651001882, 4869.756512174377, 4871.780769865364, 4905.166121759309, 1768.8419073564412, 1838.9080545972647, 1906.5043088797013, 1956.6575849226329, 2027.7161197432372, 496.3225197605315, 1016.3546148195949, 1019.0268293779571, 1053.2631819060907, 1055.5724931402794, 11206.831897521544, 11766.117541184445, 11977.161598688008, 11999.760004799855, 12039.879601203877, 457.3482787028471, 512.5643203277184, 516.8681986093598, 517.3920643244173, 521.4576840086344, 6579.967100164468, 6626.900596491057, 6739.427148692318, 6757.256701762803, 6788.966055169792, 597866.0, 600010.0, 609861.0, 610306.0, 615751.0, 83752.0, 86191.0, 86641.56679216564, 88406.0, 89669.55165224132, 547.5209191957038, 550.1115698147543, 552.0835413214046, 552.5193082018669, 553.6456667732574, 11361.715957100809, 11435.828462573234, 11449.828252576051, 11459.598914037968, 11519.481623327138, 37173.25653486861, 37272.627273728, 37655.0, 37866.0533486665, 37894.81052594719, 49720.0, 51464.0, 53459.46540534545, 54114.45885541094, 54863.45136548583, 10227.948860255949, 10307.072363487323, 10519.26365154432, 10730.356178629307, 10735.570577176826, 12483.063770217088, 12536.373181340714, 13065.869341306845, 18138.818611813713, 18443.26226950907, 15206.847931520542, 15302.77045844291, 15382.538523844303, 15496.690066198387, 15774.107107147216, 15417.229138543253, 15686.607834804221, 16023.919880400523, 16038.0, 16154.838451615333, 11361.829572556584, 14989.175595341925, 15639.452619158275, 16008.839911600735, 16024.679506410039, 10166.085052345325, 10350.5342259597, 10379.221558383, 10442.321249118782, 10496.790064198825, 10310.793784124126, 10384.740381490523, 11508.88491115078, 15148.772768408262, 15431.691366172838], "unit": "ms", "name": "jquery.html", "value": 8825.474497673904}, {"lowerIsBetter": false, "replicates": [1552.0997821263668, 1554.3627112883712, 1821.8542516598657, 1865.80409057049, 1878.323803430765, 7996.960015199923, 8466.534340611272, 8505.872411913811, 8506.149385061492, 8508.36187285953, 8344.374171937114, 8368.874466882986, 8407.075221725621, 8455.661773529051, 8479.78800529987, 2375.964360534593, 2392.9401764955883, 2403.831731778776, 2433.2578563538113, 2433.6592876997147, 2362.433016076138, 2384.0, 2414.8188885833547, 2414.8792560372, 2417.407735104893, 2021.82814460771, 2092.811646951774, 2094.8428867834896, 2343.929682109531, 2362.0669835415, 2086.488810241485, 2354.9411264718433, 2404.651325557802, 2408.4099395648004, 2410.0, 2345.7067866516686, 2349.6710460535537, 2362.65741467488, 2377.227401094634, 2394.0663141374876, 7117.288271172842, 7284.453665975073, 8126.878096828552, 8159.877601835978, 8183.6726530938695, 4540.682152249344, 4668.253079507263, 4674.088552732219, 4682.274247491644, 4686.95313046872, 3070.355225402668, 3118.937621247585, 3136.6079240094987, 3185.2514659055105, 3202.8558714857654, 1099.8405231241504, 1107.4518113533852, 1130.4799792095646, 1131.4625552862408, 1172.1209093180114, 10858.077063449537, 11045.116390688725, 11054.778904421946, 11063.502142403611, 11074.224804263706, 4516.751578663139, 4586.770661466913, 4598.195315819785, 4719.834805781781, 4729.69256998294, 9832.262580306362, 9838.458884761263, 9843.0, 9849.45827979468, 9854.605815767432, 5030.647854650212, 5053.065182941167, 5057.595392368602, 5059.19052951526, 5059.62052846038, 4540.432445944257, 4588.977055114769, 4591.449026116888, 4594.264917613166, 4599.379083823708, 3848.942265866014, 3890.2414029264023, 3916.1580260244186, 3922.1371298314325, 3950.328444164499, 1185.4961641302411, 1186.602488166474, 1196.8025275829564, 1196.904247660185, 1200.117913333699, 1061.4798748613123, 1097.286763603664, 1138.7836311100864, 1159.5709587452698, 1165.8018136916576, 7972.720954766555, 7979.241972012649, 7995.480293780885, 8008.559529225832, 8015.639296231683, 1206.8853458921387, 1208.3535308610028, 1220.5936568985953, 1225.381182502848, 1229.1518851992275, 5757.453041961007, 6405.423511883952, 6455.193100862392, 6457.2574153971955, 6459.321771214049, 9744.220462362993, 9895.901040989642, 9920.553575089138, 9942.552585133686, 10026.19790416765, 9736.221102311798, 9794.706158815246, 9853.75365615851, 9854.655087071918, 10198.949005254926, 7921.960390198012, 7947.4436789425345, 7950.602469876483, 7974.082980457206, 8027.598620068973, 7836.608169591384, 7998.9200108000505, 8013.759587212393, 8026.036875574969, 8035.517868927883, 8947.865782013405, 9073.954630226806, 9084.636614535344, 9100.77248068777, 9113.817723645623, 9159.450432974043, 9181.908180918372, 9314.394564353295, 9444.197243234403, 9448.055194480497, 7239.457040721862, 7269.091363579552, 7355.742549010759, 7361.963190184015, 7375.963120184365, 8187.181281872004, 8229.835403291781, 8233.0, 8239.382046346429, 8244.29923456513, 7723.884141737991, 7817.491863028885, 7842.607869606382, 7887.250711182429, 7913.089994650689, 7457.32884040439, 7602.581857997863, 7633.31300182964, 7662.885056724265, 7739.535627862347, 7319.048523691886, 7398.223186565333, 7401.444891633257, 7402.4448166386655, 7411.703531858665, 6822.965885170741, 6834.419074378733, 6898.206706228848, 6923.9653801730665, 6929.861402771816, 7270.527415717961, 7321.304476074764, 7322.52403593786, 7324.304191101837, 7325.304096110861], "unit": "ms", "name": "mootools.html", "value": 4780.006653017655}, {"lowerIsBetter": false, "replicates": [370.82756518219026, 380.5870630366053, 381.6812961177417, 384.9268638958599, 388.2332393522791, 6140.7543698252075, 6201.689915504229, 6208.78269260576, 6211.906821397677, 6225.844353891155, 5865.70671466426, 5970.582059255843, 6020.0969854521845, 6122.295935967365, 6136.110264011716, 4753.809847606091, 4772.475027746945, 4776.570108690217, 4777.04459108178, 4778.52214778522, 4528.02647430804, 4781.187198176319, 4785.306130611059, 4793.7842797074045, 4795.280707893827, 4571.337156112361, 4627.23650597651, 4690.718556886581, 4696.8590942271785, 4721.645876559254, 4692.249240121581, 4731.148393289207, 4735.621150307967, 4741.525847415248, 4746.905061898778, 4231.534531201565, 4238.321868501025, 4239.364095385717, 4240.724352917051, 4247.87256382309, 5731.226284451587, 5822.1266809978415, 5826.359100498942, 5831.416858314177, 5833.270841144857, 5578.63738856977, 5579.191017302509, 5579.218909352694, 5580.665160090367, 5583.05088135018, 6456.451201647866, 6464.482841372679, 6467.741290348427, 6470.805875823733, 6474.1583594132935, 4708.717476951394, 4711.575958163782, 4714.033623107255, 4719.056188762262, 4739.502352252998, 6517.608943463408, 6661.4004739573675, 6675.866482670368, 6677.799666010027, 6741.258461569223, 4699.459562150397, 4729.976350118227, 4731.361266229083, 4732.313814496879, 4735.786889589908, 4784.4497882743235, 4786.305985632064, 4787.305840653085, 4790.233562629963, 4791.95208047922, 5348.171033489737, 5483.561315094863, 5490.505854473116, 5491.917621235684, 5492.286002819608, 6541.901871471931, 6550.541462097703, 6560.967195163994, 6566.161474787067, 6569.0, 1670.106493026225, 1671.2145291712875, 1673.4477622384584, 1673.6903672820567, 1675.0368538090647, 1141.7659379827282, 1152.5735477873072, 1153.331067980586, 1159.7622487390065, 1179.5517703272874, 6277.1525844010475, 6285.088662143963, 6286.119943207955, 6287.402696743802, 6296.685165741695, 6515.902261466082, 6716.664166791641, 6733.3939945405145, 6733.494987875831, 6735.764248251287, 6258.74965001404, 6293.748250069946, 6441.19646498981, 6445.677716114175, 6448.613083215022, 3615.891523254254, 3674.3018826423427, 3674.585284665423, 3678.08047988003, 3680.8895733128047, 6876.071730316343, 6998.720051197895, 7013.719451221996, 7016.333448322402, 7025.789226323219, 6940.895886561605, 6942.375186233263, 6984.301569843076, 6996.440284777205, 7004.684789184499, 7028.2620324866175, 7029.22678505364, 7030.789076327718, 7034.929650703428, 7038.436925045984, 6664.900026499703, 6811.795646130624, 6813.216480104653, 6819.5908245505425, 6834.179898412222, 6753.696083676148, 6764.323567643196, 6769.695363708744, 6775.356341147583, 6776.457883369417, 7027.859442811217, 7030.50786444944, 7037.437005039687, 7042.50702450844, 7049.506534542533, 4735.052989402065, 4826.903461930812, 4858.46556878736, 4865.537773911473, 4884.9511504884495, 6765.154355705537, 6765.729370825112, 6772.93227067723, 6774.491913106438, 6776.559523630948, 6596.80209593713, 6598.703058362289, 6608.306127856506, 6616.172978377703, 6618.503612229199, 6442.7436649852825, 6583.3087525811015, 6587.242467116343, 6594.340565943367, 6606.0, 6457.225132983884, 6458.483321334376, 6467.385598368148, 6472.644004579794, 6479.254885688018, 6579.96710016466, 6580.979948108051, 6599.934000659932, 6603.075569420189, 6604.669766511752, 6276.152719382825, 6345.7144428499905, 6438.420542151229, 6439.581427207217, 6458.903116453163], "unit": "ms", "name": "prototype.html", "value": 4985.5060648429635}, {"lowerIsBetter": false, "replicates": [872.4634349874821, 897.649916532552, 902.151977141487, 912.625823412401, 917.4724533393298, 13659.521916732916, 13761.17432954021, 14010.579682609537, 14137.717245655082, 14159.15045097295, 13709.65725856854, 13719.108257963226, 13761.518346857862, 13792.517261895811, 13848.584542463717, 781.281221276427, 910.8178364327127, 924.8936372317203, 924.8982611912684, 925.5788616179656, 773.7214602743036, 783.4085265624445, 783.4163548156603, 925.4586067150725, 936.7845395559026, 962.2830990911781, 964.6671898195146, 964.778101036762, 966.381515829866, 972.0, 956.3449037409328, 960.1550635440838, 960.1646567486262, 960.3997501561524, 960.6829746183778, 602.0277252237648, 604.3261763134118, 715.1704023332909, 719.316649183278, 719.3993015831787, 14066.859331406657, 14093.859061409357, 14137.717245655133, 14154.363053662508, 14160.504382346568, 13005.544805931748, 13236.867631323756, 13338.0, 13436.798448023288, 13458.092773243101, 10325.89674103257, 10487.685369438928, 10928.836067458995, 11189.160812939062, 11198.496067676973, 3775.0, 3793.108619474421, 3818.0836599215986, 3834.1564855731694, 3836.079340958178, 48124.75937620325, 48419.5158048422, 48679.269810952865, 50414.0, 50596.747016264686, 10557.894421055844, 10586.841197382046, 10788.244822862482, 10840.67477975662, 10972.341659500455, 18536.536586585175, 18538.629227415375, 18549.979751113817, 18603.348882789036, 18612.348567800334, 7730.188330225358, 7834.177411371724, 7841.176676449005, 7936.920630793618, 7985.760427187193, 4584.289435137559, 4588.174128656807, 4591.288350305708, 4611.976940115345, 4760.666753327303, 3244.951325730116, 3257.03917344384, 3262.5269335946155, 3269.75476839238, 3314.950275745866, 612.2744547710977, 614.6127939398151, 619.789271647642, 623.5790841182184, 650.9674516274262, 698.3644883156303, 710.1974768511549, 722.4762047515509, 726.0996364508078, 741.1550832051465, 8626.870596941051, 8633.179847914438, 8641.30869530436, 8742.344324175585, 8850.336224783166, 563.1861959468529, 701.0780823217555, 722.0901663903414, 731.8536292741474, 737.1265050914683, 4225.556316586775, 4227.556106608823, 4254.7659878706345, 4448.844290449819, 4529.501754806968, 56618.150727739114, 57200.0, 57533.424665752806, 57706.0, 58113.709431452575, 49927.0, 50900.0, 50979.49020509747, 51488.48511514912, 51579.484205158216, 46559.30161047587, 46822.531774682495, 46859.0, 47182.76408618004, 47676.0, 8867.113288671082, 8953.104689530996, 9100.0, 9247.39891907024, 9268.0, 35031.64968350284, 35732.64267357293, 37047.629523705495, 38226.0443488915, 39228.41157382585, 44418.55581444144, 45359.09281814411, 45414.0, 45451.31823022591, 46092.769536152104, 11560.0, 11739.706507337385, 11903.404829758305, 11927.105467090176, 12192.207506512048, 12776.6805829855, 12987.870121299044, 13393.933030334785, 13745.931270343584, 14050.929745351208, 11679.649610511698, 11728.296302221894, 11743.588974385855, 11775.587854425054, 12445.0, 11818.763624727286, 11819.723674161867, 11885.108616853599, 12134.211276267015, 12276.263424194576, 10101.191904647463, 11200.327980321206, 11428.714282143013, 11560.884391155982, 11911.821322679993, 9746.805063898824, 9855.310128291238, 10114.595416183565, 10382.584696612052, 10472.895271047191, 9665.27510436706, 9978.700638980843, 10000.449975251431, 10009.548615450865, 10336.121429678562], "unit": "ms", "name": "yui.html", "value": 6670.699513395208}], "name": "dromaeo_css", "value": 7341.542213865585}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 102.0, 108.0, 110.0, 109.0, 98.0, 108.0, 108.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 107.0}, {"lowerIsBetter": true, "replicates": [149.0, 124.0, 159.0, 164.0, 130.0, 145.0, 154.0, 123.0, 160.0, 127.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.5}, {"lowerIsBetter": true, "replicates": [160.0, 159.0, 159.0, 161.0, 161.0, 160.0, 161.0, 162.0, 161.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.5}, {"lowerIsBetter": true, "replicates": [70.0, 72.0, 71.0, 144.0, 73.0, 73.0, 73.0, 71.0, 151.0, 74.0], "unit": "ms", "name": "audio-fft", "value": 87.2}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 138.0, 92.0, 92.0, 92.0, 151.0, 92.0, 92.0, 139.0], "unit": "ms", "name": "audio-oscillator", "value": 107.2}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 108.0, 109.0, 109.0, 109.0, 109.0, 108.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 108.8}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.2}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.0}, {"lowerIsBetter": true, "replicates": [57.0, 56.0, 58.0, 56.0, 59.0, 57.0, 58.0, 56.0, 55.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 56.6}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 64.0, 64.0, 64.0, 65.0, 66.0, 64.0, 64.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.4}, {"lowerIsBetter": true, "replicates": [83.0, 77.0, 79.0, 81.0, 78.0, 87.0, 79.0, 78.0, 76.0, 80.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.8}, {"lowerIsBetter": true, "replicates": [114.0, 109.0, 108.0, 119.0, 109.0, 115.0, 117.0, 110.0, 112.0, 118.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 113.1}, {"lowerIsBetter": true, "replicates": [142.0, 139.0, 141.0, 140.0, 139.0, 132.0, 139.0, 140.0, 145.0, 128.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 138.5}, {"lowerIsBetter": true, "replicates": [61.0, 62.0, 65.0, 62.0, 63.0, 62.0, 62.0, 65.0, 62.0, 62.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 62.6}], "name": "kraken", "value": 1469.3999999999996}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.83336147222693], "unit": "score", "name": "Richards", "value": 209.09374599999995}, {"lowerIsBetter": false, "replicates": [166.03021749958492], "unit": "score", "name": "DeltaBlue", "value": 398.22871400000014}, {"lowerIsBetter": false, "replicates": [278.08676307007784], "unit": "score", "name": "Encrypt", "value": 957.1868759999999}, {"lowerIsBetter": false, "replicates": [5138.461538461538], "unit": "score", "name": "Decrypt", "value": 51.80169161676644}, {"lowerIsBetter": false, "replicates": [975.609756097561], "unit": "score", "name": "RayTrace", "value": 758.4887250000003}, {"lowerIsBetter": false, "replicates": [542.0054200542005], "unit": "score", "name": "Earley", "value": 1229.624235}, {"lowerIsBetter": false, "replicates": [10547.368421052632], "unit": "score", "name": "Boyer", "value": 63.187609780439146}, {"lowerIsBetter": false, "replicates": [24756.09756097561], "unit": "score", "name": "RegExp", "value": 36.79840886699505}, {"lowerIsBetter": false, "replicates": [509.1649694501018], "unit": "score", "name": "Splay", "value": 160.04832400000006}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 23231.542456395586}]} 08:59:13 INFO - RETURN: dromaeo_css: 7341.54 08:59:13 INFO - RETURN: kraken: 1469.40 08:59:13 INFO - RETURN: v8_7: 23231.54 08:59:13 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23231.54"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1469.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7341.54"}}} 08:59:13 INFO - Return code: 0 08:59:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 08:59:13 INFO - # TBPL SUCCESS # 08:59:13 INFO - Running post-action listener: _resource_record_post_action 08:59:13 INFO - Running post-run listener: _resource_record_post_run 08:59:14 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 315392; Write bytes: 438194176; Read time: 60; Write time: 376828 08:59:14 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 171364352; Read time: 0; Write time: 171108 08:59:14 INFO - run-tests - Wall time: 1207s; CPU: 13.0%; Read bytes: 315392; Write bytes: 266543104; Read time: 60; Write time: 205688 08:59:14 INFO - Running post-run listener: _upload_blobber_files 08:59:14 INFO - Blob upload gear active. 08:59:14 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 08:59:14 INFO - Copying logs to upload dir... 08:59:14 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1257.347446 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-02-25 08:59:14.467653) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 08:59:14.471760) ========= 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=0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010093 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456409732/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 08:59:14.527608) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:59:14.528361) ========= 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=0274524eba3bdaad075c2247000001d4-1456418290.416933-1170369415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004710 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:59:14.581667) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 08:59:14.582257) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 08:59:14.582878) ========= ========= Total master_lag: 0.27 =========