builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-041 starttime: 1454344260.49 results: success (0) buildid: 20160201060427 builduid: 5e53a89f36ae4f5d9d0edf63aa81081f revision: a81f48a769a6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:00.494377) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:00.494773) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:00.495054) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006455 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:00.537145) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:00.537474) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:00.550687) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:00.551085) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004494 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:00.600213) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:00.600523) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:00.600879) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:00.601147) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-01 08:31:01-- 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% 219M=0s 2016-02-01 08:31:01 (219 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.155371 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:00.768656) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:00.768973) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014164 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:00.817977) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:00.818340) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a81f48a769a6 --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 a81f48a769a6 --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=8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-01 08:31:01,779 Setting DEBUG logging. 2016-02-01 08:31:01,779 attempt 1/10 2016-02-01 08:31:01,779 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a81f48a769a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-01 08:31:02,371 unpacking tar archive at: mozilla-beta-a81f48a769a6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.872002 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:01.704525) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:01.704853) ========= echo a81f48a769a6 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'a81f48a769a6'] 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=8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False a81f48a769a6 program finished with exit code 0 elapsedTime=0.004176 script_repo_revision: 'a81f48a769a6' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:01.754010) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:01.754308) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-01 08:31:01.767169) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-02-01 08:31:01.767488) ========= /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=8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:31:02 INFO - MultiFileLogger online at 20160201 08:31:02 in /builds/slave/test-pgo 08:31:02 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:31:02 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 08:31:02 INFO - {'append_to_log': False, 08:31:02 INFO - 'base_work_dir': '/builds/slave/test-pgo', 08:31:02 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:31:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 08:31:02 INFO - 'branch': 'Mozilla-Beta', 08:31:02 INFO - 'buildbot_json_path': 'buildprops.json', 08:31:02 INFO - 'config_files': ('talos/linux_config.py',), 08:31:02 INFO - 'default_actions': ('clobber', 08:31:02 INFO - 'read-buildbot-config', 08:31:02 INFO - 'download-and-extract', 08:31:02 INFO - 'populate-webroot', 08:31:02 INFO - 'create-virtualenv', 08:31:02 INFO - 'install', 08:31:02 INFO - 'run-tests'), 08:31:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:31:02 INFO - 'download_minidump_stackwalk': True, 08:31:02 INFO - 'download_symbols': 'ondemand', 08:31:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:31:02 INFO - 'tooltool.py': '/tools/tooltool.py', 08:31:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:31:02 INFO - '/tools/misc-python/virtualenv.py')}, 08:31:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:31:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:31:02 INFO - 'installer_path': 'installer.exe', 08:31:02 INFO - 'log_level': 'info', 08:31:02 INFO - 'log_name': 'talos', 08:31:02 INFO - 'log_to_console': True, 08:31:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:31:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:31:02 INFO - 'opt_config_files': (), 08:31:02 INFO - 'pip_index': False, 08:31:02 INFO - 'sps_profile': False, 08:31:02 INFO - 'sps_profile_interval': 0, 08:31:02 INFO - 'suite': 'dromaeojs', 08:31:02 INFO - 'system_bits': '32', 08:31:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:31:02 INFO - 'title': 'talos-linux64-ix-041', 08:31:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:31:02 INFO - 'use_talos_json': True, 08:31:02 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 08:31:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:31:02 INFO - 'work_dir': 'build'} 08:31:02 INFO - ##### 08:31:02 INFO - ##### Running clobber step. 08:31:02 INFO - ##### 08:31:02 INFO - Running pre-action listener: _resource_record_pre_action 08:31:02 INFO - Running main action method: clobber 08:31:02 INFO - rmtree: /builds/slave/test-pgo/build 08:31:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 08:31:03 INFO - Running post-action listener: _resource_record_post_action 08:31:03 INFO - ##### 08:31:03 INFO - ##### Running read-buildbot-config step. 08:31:03 INFO - ##### 08:31:03 INFO - Running pre-action listener: _resource_record_pre_action 08:31:03 INFO - Running main action method: read_buildbot_config 08:31:03 INFO - Using buildbot properties: 08:31:03 INFO - { 08:31:03 INFO - "properties": { 08:31:03 INFO - "buildnumber": 98, 08:31:03 INFO - "product": "firefox", 08:31:03 INFO - "script_repo_revision": "production", 08:31:03 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 08:31:03 INFO - "repository": "", 08:31:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 08:31:03 INFO - "buildid": "20160201060427", 08:31:03 INFO - "slavename": "talos-linux64-ix-041", 08:31:03 INFO - "pgo_build": "True", 08:31:03 INFO - "basedir": "/builds/slave/test-pgo", 08:31:03 INFO - "project": "", 08:31:03 INFO - "platform": "ubuntu64_hw", 08:31:03 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:31:03 INFO - "slavebuilddir": "test", 08:31:03 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 08:31:03 INFO - "branch": "mozilla-beta", 08:31:03 INFO - "repo_path": "releases/mozilla-beta", 08:31:03 INFO - "stage_platform": "linux64-pgo", 08:31:03 INFO - "builduid": "5e53a89f36ae4f5d9d0edf63aa81081f", 08:31:03 INFO - "revision": "a81f48a769a6" 08:31:03 INFO - }, 08:31:03 INFO - "sourcestamp": { 08:31:03 INFO - "repository": "", 08:31:03 INFO - "hasPatch": false, 08:31:03 INFO - "project": "", 08:31:03 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 08:31:03 INFO - "changes": [ 08:31:03 INFO - { 08:31:03 INFO - "category": null, 08:31:03 INFO - "files": [ 08:31:03 INFO - { 08:31:03 INFO - "url": null, 08:31:03 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/firefox-45.0.en-US.linux-x86_64.tar.bz2" 08:31:03 INFO - } 08:31:03 INFO - ], 08:31:03 INFO - "repository": "", 08:31:03 INFO - "rev": "a81f48a769a6", 08:31:03 INFO - "who": "sendchange", 08:31:03 INFO - "when": 1454344231, 08:31:03 INFO - "number": 7543572, 08:31:03 INFO - "comments": "Bug 1243942 - Bypass service workers for WebSocket handshake channels_ r=bkelly, a=sylvestre", 08:31:03 INFO - "project": "", 08:31:03 INFO - "at": "Mon 01 Feb 2016 08:30:31", 08:31:03 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 08:31:03 INFO - "revlink": "", 08:31:03 INFO - "properties": [ 08:31:03 INFO - [ 08:31:03 INFO - "buildid", 08:31:03 INFO - "20160201060427", 08:31:03 INFO - "Change" 08:31:03 INFO - ], 08:31:03 INFO - [ 08:31:03 INFO - "builduid", 08:31:03 INFO - "5e53a89f36ae4f5d9d0edf63aa81081f", 08:31:03 INFO - "Change" 08:31:03 INFO - ], 08:31:03 INFO - [ 08:31:03 INFO - "pgo_build", 08:31:03 INFO - "True", 08:31:03 INFO - "Change" 08:31:03 INFO - ] 08:31:03 INFO - ], 08:31:03 INFO - "revision": "a81f48a769a6" 08:31:03 INFO - } 08:31:03 INFO - ], 08:31:03 INFO - "revision": "a81f48a769a6" 08:31:03 INFO - } 08:31:03 INFO - } 08:31:03 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/firefox-45.0.en-US.linux-x86_64.tar.bz2. 08:31:03 INFO - Running post-action listener: _resource_record_post_action 08:31:03 INFO - ##### 08:31:03 INFO - ##### Running download-and-extract step. 08:31:03 INFO - ##### 08:31:03 INFO - Running pre-action listener: _resource_record_pre_action 08:31:03 INFO - Running main action method: download_and_extract 08:31:03 INFO - mkdir: /builds/slave/test-pgo/build/tests 08:31:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:03 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/test_packages.json 08:31:03 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 08:31:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 08:31:03 INFO - Downloaded 1270 bytes. 08:31:03 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 08:31:03 INFO - Using the following test package requirements: 08:31:03 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:31:03 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:31:03 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 08:31:03 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:31:03 INFO - u'jsshell-linux-x86_64.zip'], 08:31:03 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:31:03 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 08:31:03 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:31:03 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:31:03 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 08:31:03 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:31:03 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 08:31:03 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:31:03 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 08:31:03 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:31:03 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:31:03 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 08:31:03 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:31:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:03 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:31:03 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/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:31:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/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:31:10 INFO - Downloaded 21581188 bytes. 08:31:10 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:31:10 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:31:10 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:31:11 INFO - Return code: 0 08:31:11 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:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:11 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:31:11 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/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:31:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/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:31:12 INFO - Downloaded 21581188 bytes. 08:31:12 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:31:12 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:31:12 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:31:13 INFO - Return code: 0 08:31:13 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:31:13 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 08:31:13 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/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:31:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/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:31:15 INFO - Downloaded 11132038 bytes. 08:31:15 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:31:15 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 08:31:15 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:31:15 INFO - Return code: 0 08:31:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:15 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:31:15 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/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:31:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/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:31:20 INFO - Downloaded 57952094 bytes. 08:31:20 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:31:20 INFO - mkdir: /builds/slave/test-pgo/properties 08:31:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 08:31:20 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 08:31:20 INFO - Contents: 08:31:20 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:31:20 INFO - Running post-action listener: _resource_record_post_action 08:31:20 INFO - Running post-action listener: set_extra_try_arguments 08:31:20 INFO - ##### 08:31:20 INFO - ##### Running populate-webroot step. 08:31:20 INFO - ##### 08:31:20 INFO - Running pre-action listener: _resource_record_pre_action 08:31:20 INFO - Running main action method: populate_webroot 08:31:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:31:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:31:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:31:20 INFO - '--tppagecycles', 08:31:20 INFO - '7'], 08:31:20 INFO - 'tests': ['tsvgm']}}, 08:31:20 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 08:31:20 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 08:31:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:31:20 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:31:20 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 08:31:20 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:31:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:31:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:20 INFO - 'talos_options': ['--e10s'], 08:31:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:31:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:20 INFO - 'tests': ['damp', 'tps']}, 08:31:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:20 INFO - 'talos_options': ['--e10s'], 08:31:20 INFO - 'tests': ['damp', 'tps']}, 08:31:20 INFO - 'g3': {'tests': ['dromaeo_dom']}, 08:31:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:31:20 INFO - 'other': {'tests': ['a11yr', 08:31:20 INFO - 'ts_paint', 08:31:20 INFO - 'tpaint', 08:31:20 INFO - 'sessionrestore', 08:31:20 INFO - 'sessionrestore_no_auto_restore']}, 08:31:20 INFO - 'other-e10s': {'talos_options': ['--e10s'], 08:31:20 INFO - 'tests': ['a11yr', 08:31:20 INFO - 'ts_paint', 08:31:20 INFO - 'tpaint', 08:31:20 INFO - 'sessionrestore', 08:31:20 INFO - 'sessionrestore_no_auto_restore']}, 08:31:20 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 08:31:20 INFO - 'tests': ['a11yr', 08:31:20 INFO - 'ts_paint', 08:31:20 INFO - 'tpaint', 08:31:20 INFO - 'sessionrestore', 08:31:20 INFO - 'sessionrestore_no_auto_restore']}, 08:31:20 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 08:31:20 INFO - 'tests': ['a11yr', 08:31:20 INFO - 'ts_paint', 08:31:20 INFO - 'tpaint', 08:31:20 INFO - 'sessionrestore', 08:31:20 INFO - 'sessionrestore_no_auto_restore']}, 08:31:20 INFO - 'other_l64': {'tests': ['a11yr', 08:31:20 INFO - 'ts_paint', 08:31:20 INFO - 'tpaint', 08:31:20 INFO - 'sessionrestore', 08:31:20 INFO - 'sessionrestore_no_auto_restore']}, 08:31:20 INFO - 'other_nol64': {'tests': ['a11yr', 08:31:20 INFO - 'ts_paint', 08:31:20 INFO - 'tpaint', 08:31:20 INFO - 'sessionrestore', 08:31:20 INFO - 'sessionrestore_no_auto_restore']}, 08:31:20 INFO - 'svgr': {'tests': ['tsvgx', 08:31:20 INFO - 'tsvgr_opacity', 08:31:20 INFO - 'tart', 08:31:20 INFO - 'tscrollx', 08:31:20 INFO - 'cart']}, 08:31:20 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 08:31:20 INFO - 'tests': ['tsvgx', 08:31:20 INFO - 'tsvgr_opacity', 08:31:20 INFO - 'tart', 08:31:20 INFO - 'tscrollx', 08:31:20 INFO - 'cart']}, 08:31:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:20 INFO - 'tests': ['tp5o']}, 08:31:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:20 INFO - 'talos_options': ['--e10s'], 08:31:20 INFO - 'tests': ['tp5o']}, 08:31:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:20 INFO - 'talos_options': ['--xperf_path', 08:31:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:31:20 INFO - 'tests': ['tp5n']}, 08:31:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:20 INFO - 'talos_options': ['--e10s', 08:31:20 INFO - '--xperf_path', 08:31:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:31:20 INFO - 'tests': ['tp5n']}}, 08:31:20 INFO - 'talos.zip': {'path': '', 08:31:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:31:20 INFO - Running post-action listener: _resource_record_post_action 08:31:20 INFO - ##### 08:31:20 INFO - ##### Running create-virtualenv step. 08:31:20 INFO - ##### 08:31:20 INFO - Running pre-action listener: _resource_record_pre_action 08:31:20 INFO - Running main action method: create_virtualenv 08:31:20 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 08:31:20 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:31:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 08:31:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:31:20 INFO - Using real prefix '/usr' 08:31:20 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 08:31:20 INFO - Installing distribute.............................................................................................................................................................................................done. 08:31:22 INFO - Installing pip.................done. 08:31:22 INFO - Return code: 0 08:31:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 08:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:22 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 0x7fbbfaf61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1864350>, '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 0x1865250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18656e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', '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:31:22 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:31:22 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:31:22 INFO - Using env: {'DISPLAY': ':0', 08:31:22 INFO - 'HOME': '/home/cltbld', 08:31:22 INFO - 'LANG': 'en_US.UTF-8', 08:31:22 INFO - 'LANGUAGE': 'en_US:en', 08:31:22 INFO - 'LOGNAME': 'cltbld', 08:31:22 INFO - 'MAIL': '/var/mail/cltbld', 08:31:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:22 INFO - 'MOZ_NO_REMOTE': '1', 08:31:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:22 INFO - 'NO_EM_RESTART': '1', 08:31:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:31:22 INFO - 'PWD': '/builds/slave/test-pgo', 08:31:22 INFO - 'SHELL': '/bin/bash', 08:31:22 INFO - 'SHLVL': '1', 08:31:22 INFO - 'TERM': 'linux', 08:31:22 INFO - 'TMOUT': '86400', 08:31:22 INFO - 'USER': 'cltbld', 08:31:22 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', 08:31:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:22 INFO - '_': '/tools/buildbot/bin/python'} 08:31:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:22 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:31:22 INFO - Cleaning up... 08:31:22 INFO - Return code: 0 08:31:22 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 08:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:22 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 0x7fbbfaf61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1864350>, '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 0x1865250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18656e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', '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:31:22 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:31:22 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:31:22 INFO - Using env: {'DISPLAY': ':0', 08:31:22 INFO - 'HOME': '/home/cltbld', 08:31:22 INFO - 'LANG': 'en_US.UTF-8', 08:31:22 INFO - 'LANGUAGE': 'en_US:en', 08:31:22 INFO - 'LOGNAME': 'cltbld', 08:31:22 INFO - 'MAIL': '/var/mail/cltbld', 08:31:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:22 INFO - 'MOZ_NO_REMOTE': '1', 08:31:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:22 INFO - 'NO_EM_RESTART': '1', 08:31:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:31:22 INFO - 'PWD': '/builds/slave/test-pgo', 08:31:22 INFO - 'SHELL': '/bin/bash', 08:31:22 INFO - 'SHLVL': '1', 08:31:22 INFO - 'TERM': 'linux', 08:31:22 INFO - 'TMOUT': '86400', 08:31:22 INFO - 'USER': 'cltbld', 08:31:22 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', 08:31:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:22 INFO - '_': '/tools/buildbot/bin/python'} 08:31:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:23 INFO - Downloading/unpacking psutil>=0.7.1 08:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 08:31:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:31:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:31:24 INFO - Installing collected packages: psutil 08:31:24 INFO - Running setup.py install for psutil 08:31:24 INFO - building 'psutil._psutil_linux' extension 08:31:24 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:31:25 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:31:25 INFO - building 'psutil._psutil_posix' extension 08:31:25 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:31:25 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:31:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:31:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:31:25 INFO - Successfully installed psutil 08:31:25 INFO - Cleaning up... 08:31:25 INFO - Return code: 0 08:31:25 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 08:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:25 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 0x7fbbfaf61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1864350>, '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 0x1865250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18656e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', '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:31:25 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:31:25 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:31:25 INFO - Using env: {'DISPLAY': ':0', 08:31:25 INFO - 'HOME': '/home/cltbld', 08:31:25 INFO - 'LANG': 'en_US.UTF-8', 08:31:25 INFO - 'LANGUAGE': 'en_US:en', 08:31:25 INFO - 'LOGNAME': 'cltbld', 08:31:25 INFO - 'MAIL': '/var/mail/cltbld', 08:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:25 INFO - 'MOZ_NO_REMOTE': '1', 08:31:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:25 INFO - 'NO_EM_RESTART': '1', 08:31:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:31:25 INFO - 'PWD': '/builds/slave/test-pgo', 08:31:25 INFO - 'SHELL': '/bin/bash', 08:31:25 INFO - 'SHLVL': '1', 08:31:25 INFO - 'TERM': 'linux', 08:31:25 INFO - 'TMOUT': '86400', 08:31:25 INFO - 'USER': 'cltbld', 08:31:25 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', 08:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:25 INFO - '_': '/tools/buildbot/bin/python'} 08:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:25 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:27 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:31:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:31:27 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:31:27 INFO - Installing collected packages: mozsystemmonitor 08:31:27 INFO - Running setup.py install for mozsystemmonitor 08:31:27 INFO - Successfully installed mozsystemmonitor 08:31:27 INFO - Cleaning up... 08:31:27 INFO - Return code: 0 08:31:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 08:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:27 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 0x7fbbfaf61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1864350>, '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 0x1865250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18656e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', '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:31:27 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:31:27 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:31:27 INFO - Using env: {'DISPLAY': ':0', 08:31:27 INFO - 'HOME': '/home/cltbld', 08:31:27 INFO - 'LANG': 'en_US.UTF-8', 08:31:27 INFO - 'LANGUAGE': 'en_US:en', 08:31:27 INFO - 'LOGNAME': 'cltbld', 08:31:27 INFO - 'MAIL': '/var/mail/cltbld', 08:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:27 INFO - 'MOZ_NO_REMOTE': '1', 08:31:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:27 INFO - 'NO_EM_RESTART': '1', 08:31:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:31:27 INFO - 'PWD': '/builds/slave/test-pgo', 08:31:27 INFO - 'SHELL': '/bin/bash', 08:31:27 INFO - 'SHLVL': '1', 08:31:27 INFO - 'TERM': 'linux', 08:31:27 INFO - 'TMOUT': '86400', 08:31:27 INFO - 'USER': 'cltbld', 08:31:27 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', 08:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:27 INFO - '_': '/tools/buildbot/bin/python'} 08:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:27 INFO - Downloading/unpacking blobuploader==1.2.4 08:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:29 INFO - Downloading blobuploader-1.2.4.tar.gz 08:31:29 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:31:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:31:29 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:31:29 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:31:29 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:31:29 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:31:29 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:31:29 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:31:29 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 08:31:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:31: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:31: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:31: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:31: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:31: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:31: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:31:30 INFO - Downloading docopt-0.6.1.tar.gz 08:31:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 08:31:30 INFO - Installing collected packages: blobuploader, requests, docopt 08:31:30 INFO - Running setup.py install for blobuploader 08:31:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:31:30 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 08:31:30 INFO - Running setup.py install for requests 08:31:30 INFO - Running setup.py install for docopt 08:31:30 INFO - Successfully installed blobuploader requests docopt 08:31:30 INFO - Cleaning up... 08:31:30 INFO - Return code: 0 08:31:30 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:31: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:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31: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:31:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:30 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 0x7fbbfaf61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1864350>, '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 0x1865250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18656e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', '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:31:30 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:31:30 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:31:30 INFO - Using env: {'DISPLAY': ':0', 08:31:30 INFO - 'HOME': '/home/cltbld', 08:31:30 INFO - 'LANG': 'en_US.UTF-8', 08:31:30 INFO - 'LANGUAGE': 'en_US:en', 08:31:30 INFO - 'LOGNAME': 'cltbld', 08:31:30 INFO - 'MAIL': '/var/mail/cltbld', 08:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:30 INFO - 'MOZ_NO_REMOTE': '1', 08:31:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:30 INFO - 'NO_EM_RESTART': '1', 08:31:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:31:30 INFO - 'PWD': '/builds/slave/test-pgo', 08:31:30 INFO - 'SHELL': '/bin/bash', 08:31:30 INFO - 'SHLVL': '1', 08:31:30 INFO - 'TERM': 'linux', 08:31:30 INFO - 'TMOUT': '86400', 08:31:30 INFO - 'USER': 'cltbld', 08:31:30 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', 08:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:30 INFO - '_': '/tools/buildbot/bin/python'} 08:31:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:31:31 INFO - Running setup.py (path:/tmp/pip-W95FoR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:31:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:31:31 INFO - Running setup.py (path:/tmp/pip-GsK36l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:31:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:31:31 INFO - Running setup.py (path:/tmp/pip-3IlCZf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:31:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:31:31 INFO - Running setup.py (path:/tmp/pip-9wORYm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:31:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:31:31 INFO - Running setup.py (path:/tmp/pip-XDHCsh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:31:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:31:31 INFO - Running setup.py (path:/tmp/pip-_Rd_OS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:31:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:31:31 INFO - Running setup.py (path:/tmp/pip-HCMzgE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:31:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:31:31 INFO - Running setup.py (path:/tmp/pip-gvkmgo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:31:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:31:31 INFO - Running setup.py (path:/tmp/pip-T0xpKn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:31:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:31:31 INFO - Running setup.py (path:/tmp/pip-dmVij8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:31:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:31:31 INFO - Running setup.py (path:/tmp/pip-OFOSsF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:31:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:31:31 INFO - Running setup.py (path:/tmp/pip-GtPwWG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:31:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:31:32 INFO - Running setup.py (path:/tmp/pip-nKIMdI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:31:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:31:32 INFO - Running setup.py (path:/tmp/pip-t9n1fb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:31:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:31:32 INFO - Running setup.py (path:/tmp/pip-zf8ej7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:31:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:31:32 INFO - Running setup.py (path:/tmp/pip-f_nqMU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:31:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:31:32 INFO - Running setup.py (path:/tmp/pip-wqyY5G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:31:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:31:32 INFO - Running setup.py install for manifestparser 08:31:32 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 08:31:32 INFO - Running setup.py install for mozcrash 08:31:32 INFO - Running setup.py install for mozdebug 08:31:32 INFO - Running setup.py install for mozdevice 08:31:32 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 08:31:32 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 08:31:32 INFO - Running setup.py install for mozfile 08:31:32 INFO - Running setup.py install for mozhttpd 08:31:32 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 08:31:32 INFO - Running setup.py install for mozinfo 08:31:33 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 08:31:33 INFO - Running setup.py install for mozInstall 08:31:33 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 08:31:33 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 08:31:33 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 08:31:33 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 08:31:33 INFO - Running setup.py install for mozleak 08:31:33 INFO - Running setup.py install for mozlog 08:31:33 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 08:31:33 INFO - Running setup.py install for moznetwork 08:31:33 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 08:31:33 INFO - Running setup.py install for mozprocess 08:31:33 INFO - Running setup.py install for mozprofile 08:31:33 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 08:31:33 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 08:31:33 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 08:31:33 INFO - Running setup.py install for mozrunner 08:31:33 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 08:31:33 INFO - Running setup.py install for mozscreenshot 08:31:33 INFO - Running setup.py install for moztest 08:31:34 INFO - Running setup.py install for mozversion 08:31:34 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 08:31:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:31:34 INFO - Cleaning up... 08:31:34 INFO - Return code: 0 08:31:34 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:31:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:34 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 0x7fbbfaf61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1864350>, '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 0x1865250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18656e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', '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:31:34 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:31:34 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:31:34 INFO - Using env: {'DISPLAY': ':0', 08:31:34 INFO - 'HOME': '/home/cltbld', 08:31:34 INFO - 'LANG': 'en_US.UTF-8', 08:31:34 INFO - 'LANGUAGE': 'en_US:en', 08:31:34 INFO - 'LOGNAME': 'cltbld', 08:31:34 INFO - 'MAIL': '/var/mail/cltbld', 08:31:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:34 INFO - 'MOZ_NO_REMOTE': '1', 08:31:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:34 INFO - 'NO_EM_RESTART': '1', 08:31:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:31:34 INFO - 'PWD': '/builds/slave/test-pgo', 08:31:34 INFO - 'SHELL': '/bin/bash', 08:31:34 INFO - 'SHLVL': '1', 08:31:34 INFO - 'TERM': 'linux', 08:31:34 INFO - 'TMOUT': '86400', 08:31:34 INFO - 'USER': 'cltbld', 08:31:34 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', 08:31:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:34 INFO - '_': '/tools/buildbot/bin/python'} 08:31:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:31:34 INFO - Running setup.py (path:/tmp/pip-BSFV8X-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:31:34 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:31:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:31:34 INFO - Running setup.py (path:/tmp/pip-KdUDp9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:31:34 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:31:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:31:34 INFO - Running setup.py (path:/tmp/pip-tKZJTt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:31:34 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:31:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:31:34 INFO - Running setup.py (path:/tmp/pip-eYMuO_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:31:34 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:31:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:31:34 INFO - Running setup.py (path:/tmp/pip-blEiPU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:31:34 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:31:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:31:34 INFO - Running setup.py (path:/tmp/pip-cAB8Cv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:31:34 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:31:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:31:34 INFO - Running setup.py (path:/tmp/pip-sjavma-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:31:34 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:31:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:31:34 INFO - Running setup.py (path:/tmp/pip-1BlLe6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:31:34 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:31:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:31:34 INFO - Running setup.py (path:/tmp/pip-Psj_Og-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:31:35 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:31:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:31:35 INFO - Running setup.py (path:/tmp/pip-YqasAk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:31:35 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:31:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:31:35 INFO - Running setup.py (path:/tmp/pip-Dl9qud-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:31:35 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:31:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:31:35 INFO - Running setup.py (path:/tmp/pip-vEyC5a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:31:35 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:31:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:31:35 INFO - Running setup.py (path:/tmp/pip-5aqqrw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:31:35 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:31:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:31:35 INFO - Running setup.py (path:/tmp/pip-1Ii60h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:31:35 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:31:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:31:35 INFO - Running setup.py (path:/tmp/pip-fQyWXP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:31:35 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:31:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:31:35 INFO - Running setup.py (path:/tmp/pip-Gt20qx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:31:35 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:31:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:31:35 INFO - Running setup.py (path:/tmp/pip-Y6gtiz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:31:35 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:31:35 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:31:35 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:31:35 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:31:35 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:31:35 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:31:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 08:31: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:31: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:31: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:31: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:31: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:31: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:31:37 INFO - Downloading blessings-1.6.tar.gz 08:31:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 08:31:37 INFO - Installing collected packages: blessings 08:31:37 INFO - Running setup.py install for blessings 08:31:37 INFO - Successfully installed blessings 08:31:37 INFO - Cleaning up... 08:31:37 INFO - Return code: 0 08:31:37 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 08:31:37 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:31:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:31:37 INFO - Reading from file tmpfile_stdout 08:31:37 INFO - Current package versions: 08:31:37 INFO - argparse == 1.2.1 08:31:37 INFO - blessings == 1.6 08:31:37 INFO - blobuploader == 1.2.4 08:31:37 INFO - docopt == 0.6.1 08:31:37 INFO - manifestparser == 1.1 08:31:37 INFO - mozInstall == 1.12 08:31:37 INFO - mozcrash == 0.16 08:31:37 INFO - mozdebug == 0.1 08:31:37 INFO - mozdevice == 0.47 08:31:37 INFO - mozfile == 1.2 08:31:37 INFO - mozhttpd == 0.7 08:31:37 INFO - mozinfo == 0.9 08:31:37 INFO - mozleak == 0.1 08:31:37 INFO - mozlog == 3.1 08:31:37 INFO - moznetwork == 0.27 08:31:37 INFO - mozprocess == 0.22 08:31:37 INFO - mozprofile == 0.27 08:31:37 INFO - mozrunner == 6.11 08:31:37 INFO - mozscreenshot == 0.1 08:31:37 INFO - mozsystemmonitor == 0.0 08:31:37 INFO - moztest == 0.7 08:31:37 INFO - mozversion == 1.4 08:31:37 INFO - psutil == 3.1.1 08:31:37 INFO - requests == 1.2.3 08:31:37 INFO - wsgiref == 0.1.2 08:31:37 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:31:37 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:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:37 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:31:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:37 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 0x7fbbfaf61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1864350>, '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 0x1865250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18656e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', '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:31:37 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:31:37 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:31:37 INFO - Using env: {'DISPLAY': ':0', 08:31:37 INFO - 'HOME': '/home/cltbld', 08:31:37 INFO - 'LANG': 'en_US.UTF-8', 08:31:37 INFO - 'LANGUAGE': 'en_US:en', 08:31:37 INFO - 'LOGNAME': 'cltbld', 08:31:37 INFO - 'MAIL': '/var/mail/cltbld', 08:31:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:37 INFO - 'MOZ_NO_REMOTE': '1', 08:31:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:37 INFO - 'NO_EM_RESTART': '1', 08:31:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:31:37 INFO - 'PWD': '/builds/slave/test-pgo', 08:31:37 INFO - 'SHELL': '/bin/bash', 08:31:37 INFO - 'SHLVL': '1', 08:31:37 INFO - 'TERM': 'linux', 08:31:37 INFO - 'TMOUT': '86400', 08:31:37 INFO - 'USER': 'cltbld', 08:31:37 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', 08:31:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:37 INFO - '_': '/tools/buildbot/bin/python'} 08:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:38 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:31:38 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:31:38 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:31:38 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:31:38 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:31:38 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:31:38 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:31:38 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:31:38 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:31:38 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:31:38 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:31:38 INFO - Cleaning up... 08:31:38 INFO - Return code: 0 08:31:38 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 08:31: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:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31: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:31:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:38 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 0x7fbbfaf61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1864350>, '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 0x1865250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18656e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', '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:31:38 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:31:38 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:31:38 INFO - Using env: {'DISPLAY': ':0', 08:31:38 INFO - 'HOME': '/home/cltbld', 08:31:38 INFO - 'LANG': 'en_US.UTF-8', 08:31:38 INFO - 'LANGUAGE': 'en_US:en', 08:31:38 INFO - 'LOGNAME': 'cltbld', 08:31:38 INFO - 'MAIL': '/var/mail/cltbld', 08:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:38 INFO - 'MOZ_NO_REMOTE': '1', 08:31:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:38 INFO - 'NO_EM_RESTART': '1', 08:31:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:31:38 INFO - 'PWD': '/builds/slave/test-pgo', 08:31:38 INFO - 'SHELL': '/bin/bash', 08:31:38 INFO - 'SHLVL': '1', 08:31:38 INFO - 'TERM': 'linux', 08:31:38 INFO - 'TMOUT': '86400', 08:31:38 INFO - 'USER': 'cltbld', 08:31:38 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', 08:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:38 INFO - '_': '/tools/buildbot/bin/python'} 08:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:38 INFO - Downloading/unpacking jsonschema 08:31:38 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:31:38 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:31:38 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:31:38 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:31:38 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:31:38 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:31:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:31:39 INFO - Downloading/unpacking functools32 (from jsonschema) 08:31:39 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:31:39 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:31:39 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:31:39 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:31:39 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:31:39 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:31:40 INFO - Downloading functools32-3.2.3-2.tar.gz 08:31:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 08:31:40 INFO - warning: no files found matching '*.txt' 08:31:40 INFO - no previously-included directories found matching 'build' 08:31:40 INFO - no previously-included directories found matching 'dist' 08:31:40 INFO - no previously-included directories found matching '.git*' 08:31:40 INFO - Installing collected packages: jsonschema, functools32 08:31:40 INFO - Running setup.py install for functools32 08:31:40 INFO - warning: no files found matching '*.txt' 08:31:40 INFO - no previously-included directories found matching 'build' 08:31:40 INFO - no previously-included directories found matching 'dist' 08:31:40 INFO - no previously-included directories found matching '.git*' 08:31:40 INFO - Successfully installed jsonschema functools32 08:31:40 INFO - Cleaning up... 08:31:40 INFO - Return code: 0 08:31:40 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 08:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:40 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 0x7fbbfaf61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d5600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1864350>, '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 0x1865250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18656e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', '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:31:40 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:31:40 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:31:40 INFO - Using env: {'DISPLAY': ':0', 08:31:40 INFO - 'HOME': '/home/cltbld', 08:31:40 INFO - 'LANG': 'en_US.UTF-8', 08:31:40 INFO - 'LANGUAGE': 'en_US:en', 08:31:40 INFO - 'LOGNAME': 'cltbld', 08:31:40 INFO - 'MAIL': '/var/mail/cltbld', 08:31:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:40 INFO - 'MOZ_NO_REMOTE': '1', 08:31:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:40 INFO - 'NO_EM_RESTART': '1', 08:31:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:31:40 INFO - 'PWD': '/builds/slave/test-pgo', 08:31:40 INFO - 'SHELL': '/bin/bash', 08:31:40 INFO - 'SHLVL': '1', 08:31:40 INFO - 'TERM': 'linux', 08:31:40 INFO - 'TMOUT': '86400', 08:31:40 INFO - 'USER': 'cltbld', 08:31:40 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', 08:31:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:40 INFO - '_': '/tools/buildbot/bin/python'} 08:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:40 INFO - Downloading/unpacking flake8 08:31:40 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:31:40 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:31:40 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:31:40 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:31:40 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:31:40 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:31:42 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:31:42 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:31:42 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:31:42 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:31:42 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:31:42 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:31:42 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:31:42 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:31:42 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:31:42 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:31:42 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:31:42 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:31:42 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:31:42 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:31:42 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:31:42 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:31:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:31:42 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:31:42 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:31:42 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:31:42 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:31:42 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:31:42 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:31:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 08:31:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:31:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:31:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:31:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:31:43 INFO - no previously-included directories found matching 'docs/_build' 08:31:43 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:31:43 INFO - Running setup.py install for pep8 08:31:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:31:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:31:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:31:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:31:43 INFO - no previously-included directories found matching 'docs/_build' 08:31:43 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 08:31:43 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:31:43 INFO - Cleaning up... 08:31:43 INFO - Return code: 0 08:31:43 INFO - Running post-action listener: _resource_record_post_action 08:31:43 INFO - Running post-action listener: _start_resource_monitoring 08:31:43 INFO - Starting resource monitoring. 08:31:43 INFO - ##### 08:31:43 INFO - ##### Running install step. 08:31:43 INFO - ##### 08:31:43 INFO - Running pre-action listener: _resource_record_pre_action 08:31:43 INFO - Running main action method: install 08:31:43 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:31:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:31:43 INFO - Reading from file tmpfile_stdout 08:31:43 INFO - Detecting whether we're running mozinstall >=1.0... 08:31:43 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 08:31:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 08:31:43 INFO - Reading from file tmpfile_stdout 08:31:43 INFO - Output received: 08:31:43 INFO - Usage: mozinstall [options] installer 08:31:43 INFO - Options: 08:31:43 INFO - -h, --help show this help message and exit 08:31:43 INFO - -d DEST, --destination=DEST 08:31:43 INFO - Directory to install application into. [default: 08:31:43 INFO - "/builds/slave/test-pgo"] 08:31:43 INFO - --app=APP Application being installed. [default: firefox] 08:31:43 INFO - mkdir: /builds/slave/test-pgo/build/application 08:31:43 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:31:43 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:31:58 INFO - Reading from file tmpfile_stdout 08:31:58 INFO - Output received: 08:31:58 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 08:31:58 INFO - Running post-action listener: _resource_record_post_action 08:31:58 INFO - ##### 08:31:58 INFO - ##### Running run-tests step. 08:31:58 INFO - ##### 08:31:58 INFO - Running pre-action listener: _resource_record_pre_action 08:31:58 INFO - Running main action method: run_tests 08:31:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 08:31:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 08:31:58 INFO - Python 2.7.3 08:31:58 INFO - Return code: 0 08:31:58 INFO - grabbing minidump binary from tooltool 08:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:58 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 0x1864350>, '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 0x1865250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18656e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 08:31:58 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:31:58 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:31:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:31:58 INFO - Return code: 0 08:31:58 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 08:31:58 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 08:31:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 08:31:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 08:31:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 08:31:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 08:31:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 08:31:59 INFO - Return code: 0 08:31:59 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/1454335467/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 08:31:59 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/1454335467/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --webServer localhost 08:31:59 INFO - Using env: {'DISPLAY': ':0', 08:31:59 INFO - 'HOME': '/home/cltbld', 08:31:59 INFO - 'LANG': 'en_US.UTF-8', 08:31:59 INFO - 'LANGUAGE': 'en_US:en', 08:31:59 INFO - 'LOGNAME': 'cltbld', 08:31:59 INFO - 'MAIL': '/var/mail/cltbld', 08:31:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:31:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 08:31:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:59 INFO - 'MOZ_NO_REMOTE': '1', 08:31:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:31:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:59 INFO - 'NO_EM_RESTART': '1', 08:31:59 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:31:59 INFO - 'PWD': '/builds/slave/test-pgo', 08:31:59 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 08:31:59 INFO - 'SHELL': '/bin/bash', 08:31:59 INFO - 'SHLVL': '1', 08:31:59 INFO - 'TERM': 'linux', 08:31:59 INFO - 'TMOUT': '86400', 08:31:59 INFO - 'USER': 'cltbld', 08:31:59 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865', 08:31:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:59 INFO - '_': '/tools/buildbot/bin/python'} 08:31:59 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/1454335467/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost'] with output_timeout 3600 08:31:59 INFO - mozversion INFO | application_buildid: 20160201060427 08:31:59 INFO - mozversion INFO | application_changeset: a81f48a769a6d00346d765b9463b62c41e450896 08:31:59 INFO - mozversion INFO | application_display_name: Nightly 08:31:59 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:31:59 INFO - mozversion INFO | application_name: Firefox 08:31:59 INFO - mozversion INFO | application_remotingname: firefox 08:31:59 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:31:59 INFO - mozversion INFO | application_vendor: Mozilla 08:31:59 INFO - mozversion INFO | application_version: 45.0 08:31:59 INFO - mozversion INFO | platform_buildid: 20160201060427 08:31:59 INFO - mozversion INFO | platform_changeset: a81f48a769a6d00346d765b9463b62c41e450896 08:31:59 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:31:59 INFO - mozversion INFO | platform_version: 45.0 08:31:59 INFO - 2016-02-01 08:31:59,556 DEBUG : using testdate: 1454344319 08:31:59 INFO - 2016-02-01 08:31:59,556 DEBUG : actual date: 1454344319 08:31:59 INFO - 2016-02-01 08:31:59,564 INFO : starting webserver on 'localhost:38214' 08:31:59 INFO - 2016-02-01 08:31:59,565 INFO : Starting test suite talos-linux64-ix-041 08:31:59 INFO - 2016-02-01 08:31:59,566 INFO : Starting test dromaeo_css 08:31:59 INFO - 2016-02-01 08:31:59,566 DEBUG : operating with platform_type : linux_ 08:31:59 INFO - 2016-02-01 08:31:59,566 INFO : Initialising browser for dromaeo_css test... 08:31:59 INFO - 2016-02-01 08:31:59,577 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplmGweA/profile http://localhost:38214/getInfo.html 08:32:03 INFO - 2016-02-01 08:32:03,756 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:32:03 INFO - 2016-02-01 08:32:03,756 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:32:03 INFO - 2016-02-01 08:32:03,756 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:32:03 INFO - 2016-02-01 08:32:03,756 DEBUG : BROWSER_OUTPUT: __metrics 08:32:04 INFO - 2016-02-01 08:32:04,014 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:32:04 INFO - 2016-02-01 08:32:04,946 INFO : Browser initialized. 08:32:04 INFO - 2016-02-01 08:32:04,947 INFO : Running cycle 1/1 for dromaeo_css test... 08:32:04 INFO - 2016-02-01 08:32:04,947 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplmGweA/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:32:16 INFO - 2016-02-01 08:32:16,721 DEBUG : BROWSER_OUTPUT: RSS: Main: 134479872 08:32:16 INFO - 2016-02-01 08:32:16,721 DEBUG : BROWSER_OUTPUT: 08:33:07 INFO - 2016-02-01 08:33:07,621 DEBUG : BROWSER_OUTPUT: 1454344387617 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:35:15 INFO - 2016-02-01 08:35:15,888 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38214/tests/dromaeo/cssquery-ext.html) 08:35:17 INFO - 2016-02-01 08:35:17,125 DEBUG : BROWSER_OUTPUT: RSS: Main: 260149248 08:35:17 INFO - 2016-02-01 08:35:17,125 DEBUG : BROWSER_OUTPUT: 08:38:19 INFO - 2016-02-01 08:38:19,164 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/dromaeo/cssquery-ext.html (next: http://localhost:38214/tests/dromaeo/cssquery-jquery.html) 08:38:19 INFO - 2016-02-01 08:38:19,725 DEBUG : BROWSER_OUTPUT: RSS: Main: 215650304 08:38:19 INFO - 2016-02-01 08:38:19,725 DEBUG : BROWSER_OUTPUT: 08:41:21 INFO - 2016-02-01 08:41:21,508 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38214/tests/dromaeo/cssquery-mootools.html) 08:41:22 INFO - 2016-02-01 08:41:22,070 DEBUG : BROWSER_OUTPUT: RSS: Main: 209772544 08:41:22 INFO - 2016-02-01 08:41:22,071 DEBUG : BROWSER_OUTPUT: 08:44:23 INFO - 2016-02-01 08:44:23,809 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38214/tests/dromaeo/cssquery-prototype.html) 08:44:24 INFO - 2016-02-01 08:44:24,383 DEBUG : BROWSER_OUTPUT: RSS: Main: 195612672 08:44:24 INFO - 2016-02-01 08:44:24,383 DEBUG : BROWSER_OUTPUT: 08:47:26 INFO - 2016-02-01 08:47:26,772 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38214/tests/dromaeo/cssquery-yui.html) 08:47:27 INFO - 2016-02-01 08:47:27,334 DEBUG : BROWSER_OUTPUT: RSS: Main: 198447104 08:47:27 INFO - 2016-02-01 08:47:27,334 DEBUG : BROWSER_OUTPUT: 08:50:29 INFO - 2016-02-01 08:50:29,292 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/dromaeo/cssquery-yui.html (next: http://localhost:38214/tests/dromaeo/cssquery-dojo.html) 08:50:29 INFO - 2016-02-01 08:50:29,543 DEBUG : BROWSER_OUTPUT: RSS: Main: 242417664 08:50:29 INFO - 2016-02-01 08:50:29,544 DEBUG : BROWSER_OUTPUT: 08:50:29 INFO - 2016-02-01 08:50:29,545 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:50:29 INFO - 2016-02-01 08:50:29,545 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:50:29 INFO - 2016-02-01 08:50:29,545 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:50:29 INFO - 2016-02-01 08:50:29,545 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:50:29 INFO - 2016-02-01 08:50:29,549 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3347.8326083695815;3410.249745056087;3440.7419443541726;3442.7762195457294;3461.325041616885;5519.061759500884;5556.972215138929;5575.191597218406;5581;5606.327240731118;5608.43915608439;5618.129189975561;5629.971850140743;5645;5655.632383895044;4947.480514545975;5100.1584738518195;5142.871428214287;5201.141811601081;5247.816326428576;5039.370078740158;5050.090983622947;5052;5054.772535235905;5056.266841308008;5159.07136715393;5204.609654275944;5218.895622087575;5219.71291578963;5223.503767142115;5040.697558146505;5051.797928082854;5125.077486052509;5139.460356662534;5174.172132458826;5145.897082058375;5192.428832828386;5255.264263003182;5256.26412302278;5294.788208471657;5342.759575819097;5427.565794736412;5533.723313834292;5543.362513310981;5571.415001424833;6060.2424696912885;6097.939020609781;6118.1128736332985;6124.785632502885;6125.938740612582;6101.603395779304;6109.602875813058;6110.7861224856915;6114.877702445926;6115.48018418435;5443.727813609344;5491.148871924818;5534.08687566551;5578.386377498472;5641.379448260688;6055.091736239599;6101.206843110367;6106.419890110433;6106.572539922252;6135.601185922901;5941.465268125889;5942.732577034044;5943.375945525744;5947.464728174485;5953.047512397996;5951.702414879239;5982.162497250389;6014.037753959433;6036.5170786336985;6114.724837382329;5969.701514924236;6001.399860013964;6007.098935159674;6016.669083200466;6045.395460453919;6002.429769171922;6008.609440386361;6008.939910600838;6008.969955150196;6010.579259451883;4483.820647174141;4558.407407037064;4582.24392975162;4591.632669386441;4608.631309495232;4138.461999940048;4164.43780089684;4165.687573432005;4166.479190101238;4192.119654872511;5760.36635970043;5773.336066352339;5775.075987841925;5782.161586570008;5810.912836307459;5732.398098199712;5836.270466191726;5958.136070269787;6106.297775755756;6110.602810817282;5730.3410107838135;5781.421857814269;5784.334801497882;5787.450192231731;5790.565707571949;5075.365579302587;5295.285136506599;5301.628885978022;5315.149576067811;5318.282031925718;6040.456358927718;6100;6205.2553693557065;6210.285817131088;6210.378962103844;6008.188894499362;6182.1345011699295;6183.907241391473;6193.163922870355;6193.876122477435;6085;6201.162843016135;6284.214473190851;6311.400416960381;6343.270523889627;5777.480026797609;5864.472197502245;5867.413258674099;5875.3243377010485;5904.52763778888;6112.78605248832;6120.785772497941;6162.722677479614;6163.3528479509005;6174.135621012972;5741.569382296428;5788.160716696139;5852.4440178183;6008.669523176092;6020.759169633166;5798.913016304843;6007.879842403215;6012.549058820693;6023.969880150747;6027.336992930862;5879.7060146993335;5902;5908.822735317947;5914.7338369774325;5915.231019967377;5565.972170139124;5709.8287051388525;5711.029125048669;5926.525877929669;6002.879942401215;5743.540516758566;5754.741036653444;5769.8557536061935;5778.364379918122;5794.217780599565 08:50:29 INFO - 2016-02-01 08:50:29,549 DEBUG : BROWSER_OUTPUT: |1;ext.html;586.662668965345;630.8044506203076;633.6863252689914;637.9011253255748;644.7678835619173;33602.327953440945;42324.36513452297;42802.35796463048;42984.355234671464;43041.56958430415;46741.29888051673;47378.76310618442;47448.762756186254;47459;47495.287570686465;8184.345252379795;8262.339012878985;8313.043999940024;9143.679971201009;9339.626414943394;10953.726156846104;11175.720606984809;11184.049355804766;11235.325880447199;11248.100151987821;10947.069499092548;11029.172812039089;11036.11711063117;11040.779184416308;11044.613438529654;10909.127269818435;10945.343279403181;10997.890021099807;11020.283681560673;11036.392998385085;10749.83875241872;10855.511501982352;10866.185036122322;10876.891231087668;10881.510332035077;44026;44232.778836105615;44349.33475997863;44352.11295774099;44375.33436998448;3641.7814931103953;3719.1445967427644;3745.943810842812;3876.8643097491454;4182.498100227962;31467.52798708021;31489;31581.210469738442;32186.83906580452;32346.6765332346;3731.9626803731885;3755.9812200939095;3762.1159027628487;3775.6601905828607;3872.186840763443;297552;300627;300951;303561;306027;60607.39392606106;62122.689386552775;68496;68985;75992.62003689946;67075.66462167756;67338.32661673418;67707.32292677108;67771;67813.66093169502;17212.1393930303;17318.913405432893;17331.220095095752;17401.390951316644;17412.738808917875;13192.802107968388;13266.800997985038;13341.533046343331;13365.398557064955;13370.799438008438;22945.770542294696;23109.653355199687;23122.19072332494;23235.3029409118;23395.53208935812;8453.365997550209;8475.576221188916;8658.78353041179;8698.565071746387;8802.471851688919;1062.8458873463305;1079.6707004363586;1149.3391300002531;1153.9596114135966;1196.9700757481132;30505.23736906595;30570.54144187839;31126.533102003486;31774.523382149287;34757.30485390278;843.026304618167;1062.2776511971933;1065.6217042949797;1141.2467771271088;1173.2784337632295;14886.478973235884;15232.619184520476;15757.527274181793;17998.91000545015;18045.458636240935;448159;453724;454836;455233;457291;60846.39153608496;61050;61442.38557614456;61476.38523614796;61477;1486.6060493969107;1493.201137391512;1494.066208619591;1502.909825410457;1515.7650564162573;31631.683683163796;34777.47833782445;35022;35118.648813511536;35207.647923521465;55223;55874;55968.72015639896;55981.16028259661;56008.43991560032;312766;312796;314035;314150;314290;35259.47110793289;35792.64207357997;35864.641353587176;36247.818760906026;36265.27469450543;46934;47600.761996189794;47897;48086.759566201945;48180;39300.803495982334;39780.60219397769;40045;40676.59323406728;41203.7939810299;31964;36682.8165859169;40009.59990400175;40394;41282;35336.46995295124;35370.46944295786;45257.547424525335;46016.7699161502;46857;34874.47688284627;35504;35820.10449738777;35998;41921.37117943294;27106.864465677547;27421.862890685417;27570.5864412038;27623;27659 08:50:29 INFO - 2016-02-01 08:50:29,550 DEBUG : BROWSER_OUTPUT: |2;jquery.html;379.7303914220903;382.87939299120774;383.9270538597666;383.9865604703838;387.6685433953969;15573.143477108755;16965.3213871445;18059.097045147755;18460.16929238184;18508.629827403445;11597.30416175028;11847.289162650248;11855.881441185586;18902.62194756104;19128.42614721557;4932;5039.21892106724;5042.445331013586;5050.166722490803;5055.9747201263945;3997.560268370477;4087.8569250076253;5064.746762661871;5531.778728850862;5663.1505274208785;5606.943930560683;5627.521660658849;5641.351244606882;5652.378238393773;5654.802081927133;5481.287432633752;5568.1647752837;5573;5575.944240557604;5588.524975377098;5387.164989426645;5395.082835917864;5400.567954563626;5405.37838148614;5409.864753381157;19875.90062049695;22016.009279582457;22439.663405048937;22455.10179592814;22472.32583022512;2191.2659259148204;2209.5028618560855;2227.2204728345114;2237.093976939342;2297.9885100574556;10072.647457339031;10175.694729158136;10320.483975801255;12616.432260548298;13845.861541384558;1882.5293676580854;1944.980550194494;1946.6690662587393;1947.1529884500146;1960.5196726802026;62415;64003.3599664002;64188;65403;65628.67185664088;10152.441615711059;10165.44090075053;10232.897671023195;10297.227707921937;10382.273240873066;13236.205827650372;13310.866891330963;13321.800172997413;13322.400491977884;13367.131136476099;7780.805479863048;7929.920700793034;7954.125046244909;7961.243681850215;8011.118776934649;7963.4027447941635;7967;7994.800129996796;8046.396520261003;8063.072746634212;4404.933925991113;4425.291953287523;4427.889302767392;4430.667699922518;4452.888677783017;1706.2032808751526;1899.9620007600047;1920.9135588898532;1985.9801401985794;1987.5590197107272;941.9623215071457;978.7895602445508;1004.1264100232698;1093.9507722152523;1106.1372129738816;17270.309187632607;17606.2077206526;17867.642647146986;18262.086895655164;18286.908565457354;935.3873213045466;1021.97445063874;1049.0139269087033;1094.441834664327;1133.727905302738;9412.19996300322;9708.6116555337;9829.950850245703;9833.852492212622;9882.308238423386;379723;380591;573428;577284;617710;84019;86429;86827.56586217029;88222;88745.11254887498;1162.110985096402;1169.0764296206073;1184.2775906696909;1195.54569263681;1201.7356181640025;15913.283902224197;16175.67648647044;16199.75700364472;16267.674646507241;16721.832781672514;36198.63801361953;36817.26365472729;37217;37525.6247437533;38009.23981520409;50444.24333634925;50660.493395065576;51184;54314.18528721993;56013.71993140008;9784;10034.749131271778;10091.596336146471;10117.19062475015;10190.388576685422;11828.23116497425;17104.743428848826;17931.282748690428;17991;18562.628747424707;14534.563963081124;14842.628934276729;15340.616484587974;15433.151176685391;15675.294611742727;14938.77591836145;14939.402423902922;15008.774868376764;15358.232088395313;15582.22088895528;14451.132932024113;14530.85469145295;15645.452409165624;15657.37370505167;15950.840491594936;9949.303548751517;9984.301098923006;10045.095941365313;10060.547275372479;10063.49682515886;10721.517531711248;12198.634040978784;14602.780958285422;14767.409303627735;15654 08:50:29 INFO - 2016-02-01 08:50:29,552 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1529.8699610533106;1597.744360902256;1618.1342981504897;1770.3361239535175;1875.2030387085485;7444.739434119807;7864.8427031459405;7866.842663146734;7876.606169691507;7877;6834.487413443999;7034.577925324485;7268.164161121473;7448.515846469975;7674.347680447155;2327.6392159215306;2343.6836027136374;2368.7275963264274;2370.632551954441;2371.762823717632;2069.782672819355;2322.756110608387;2329.8136149108113;2392.3899405651537;2393.5811233034237;2058.6397380458434;2251.122062395667;2303.0672577606097;2304.873231972241;2305.942351441219;2272.4773302140516;2279.5554866801062;2281.441046943501;2350.330155905576;2355.9646605300936;2260.886955652211;2261.0729600863733;2291.32405940248;2291.9312420627407;2337.8597284163006;7723.7682869514;7748.457607967444;7775.805604859867;7807.414443916729;7824.765257042298;4719.221328480831;4806.399200099988;4810.39870016248;4841.975790121062;4846.297286893416;3108.207407111172;3128.2335827722127;3154.6056742907135;3222.548843161959;3229.1927018245437;1119.127080876909;1813.2656274208966;1899.8480121590378;1923.7883832778384;1927.6241132979076;10580.894191058067;10680.412577308245;10680.626178083807;10689.412082335391;10693.144548436107;4209.831606735757;4215.515215750228;4243.490781106225;4284.207421626947;4321.632661223768;8818.382713210014;8847.823043539222;8849.867251991225;8852.336074794417;8856.070112638208;4778.5699287064335;4784.067106914118;4785.712857228577;4821.614270858323;4823.589994850407;4409.9559004410185;4466.419365482531;4467.687261891637;4469;4469.709468884512;3328.484084966834;3364.8654053838063;3390.2541440882965;3392.9151771205916;3421.8289085545625;994.0506815990856;1014.0974532665933;1037.5953378182514;1046.4767616191905;1051.4900273367412;1233.2600439736086;1246.632243488155;1257.0823298991604;1491.7464031114737;1492.0898252066231;7437.256274372628;7496.137944136385;7639.274268944441;7720.459567830198;7721.884171737428;1204.476052916984;1220.6521141474636;1224.2165014390796;1224.8101544260476;1234.2665876364244;6028.4272994065495;6078.483328917003;6135.723892424851;6138.324784273726;6143.232095988002;9142.542872856331;9488.810223795486;9515.524223788783;9521.28590355726;9533;9323.627054917728;9348.4858332791;9381.202597779265;9392.812143757086;9408.247340212904;6975.128108986377;7168.211496735355;7170.784876453715;7172.0676312079095;7176.461765367619;7324.304191101837;7426.702931882664;7492.587907665131;7507.924920750723;7509.7371591994015;8359.205875441823;8406.873896891673;8407.327413807003;8506.489610623383;8587.270082042847;8558.186972237627;8593.398462107592;8670.089640587648;8679.218870301704;8694.391392602458;7530.8493830122;7621.657025433758;7635.961820190863;7638;7642.5032372895585;7644.312011918954;7702.653380597774;7725.111612164673;7769.7669069928;7771.844563108593;5934.228550288435;6988.196357418962;7282.526635768658;7364.594947277951;7381.8154546136775;7034.929650703632;7109;7142.107236595426;7192.676329565287;7271.272872712686;7174.67713952863;7182.712691492282;7194.388476979515;7274.054372931485;7282.599457029914;6727.327267273429;6741.325867413219;6743.696533656095;6773.085633439423;6778.627175505395;6835.965820170867;6840.179178498612;6848.623325717133;6862.828429289108;6865.210500792473 08:50:29 INFO - 2016-02-01 08:50:29,553 DEBUG : BROWSER_OUTPUT: |4;prototype.html;345.80289235135984;346.52526039326125;350.8719317449132;353.6216248613984;354.3497681753982;5896.793612223573;6077.665728384937;6097.664628445434;6148.446639802417;6159;5894.646321220719;5959.88080238395;6038.245219347582;6065.302490213627;6070.484008859252;4666.206744853366;4680.836170734008;4682.133805246023;4686.297055441694;4689.531046895318;4483.5964763171305;4566.223741963858;4704.952950470486;4709.270063140219;4709.552592503706;4562.224421848294;4608.792604332813;4611.469680986679;4638.744869032202;4650.162970665279;4656;4660.860174194746;4666.416697912761;4668.393108895856;4669.112868554981;4108.77401742904;4109.958900411018;4128.587141285878;4128.772917489537;4132.359484279941;5695.088785794253;5779.2775903012125;5788.88422231553;5795.565332600072;5847.970760146214;5344.706041167734;5345.545628621572;5354;5358.3569971603265;5360.249565060855;6408.295087540367;6425.807225783234;6427.132337134473;6434.742610295582;6443.903341449882;4308.67684923632;4364.672649551265;4397.450318710161;4447.933281000787;4466.798994045275;6567.310432404624;6614.437772789271;6622.86754264912;6623.602583844985;6629.303923088103;4566.429196350457;4566.680332376768;4566.70316429431;4567.474740404896;4571.9314210286875;4554.612857907049;4611.123886461562;4623.9075218495445;4627.814887404534;4644.558766917137;5354.732263386815;5363.088274993261;5364.195370694427;5367.141257398797;5375.247465354853;6448.871022579522;6451.419372256426;6458.61248325102;6466.159399278158;6466.64433456155;1620.8622267107191;1621.7891674082293;1622.7971503562055;1624.4639269041188;1624.8050233971999;1065.7229120428744;1066.925315227942;1084.1164450972433;1095.638439315024;1132.1452303510962;6300.495960323163;6311.368863113744;6315.36846315374;6327.209098862642;6327.905081423783;6630.535862489676;6637.1703537057865;6637.767678131242;6645.734170633121;6676.165479315086;6167.414095660905;6323.494120470351;6325.96837015812;6328.145700330489;6328.303886572473;3583.5341405617623;3608.8736894208578;3610.2779444111284;3612.6387361264187;3615.6565126312958;6851;6856.451483881277;6859.142607174103;6860.451163906975;6860.965695171592;6630.602163870183;6772.627505487147;6780.694868730919;6790.185177778698;6808.2170550386345;6725.495587830932;6727.966360168266;6741.426978706865;6746.392824645805;6757.662116894234;6475.417212450714;6605.372489613479;6606.8017959461295;6616.437602803623;6646.60120392778;6509.967450162719;6546.378094081055;6632.270450250565;6642.4021838034805;6644.302348253364;6876.896846547406;6914.101166848479;6914.8617027660175;6915.101036865175;6920.861582768417;4493.236149854467;4554.20301447252;4556.088782243498;4561.908761824679;4563.406757121553;6663.300353463011;6712.832179195364;6715.731370745311;6718.798436046926;6721.529492935448;6512.120863683342;6538.084668146558;6542.934570654233;6548.214214294315;6551.21385433751;6306.936930630635;6526.641034742945;6529.183852018497;6534.215894092739;6536.084948107364;6404.871902562016;6434.678266086583;6447.161868957006;6447.774327898501;6448.484121270193;6555.180602424697;6566.737330506918;6573.3098024706715;6575.868482630416;6581.3747693969;6238.220222472191;6401.967990160019;6406.935930640634;6411.166548348871;6412.35876412355 08:50:29 INFO - 2016-02-01 08:50:29,553 DEBUG : BROWSER_OUTPUT: |5;yui.html;784.1766145547174;854.5983387807729;859.6217664227739;864.7751584588008;917.5504003038513;13164.27596482193;13218.53735119271;13465.124766890145;13466.259355735443;14028.368723407446;13131;13171.934140329286;13263.535776247809;13269.336533173351;13270.867291327084;765.1583258415753;778.4589710151447;903.1871315815753;927.9814403711922;928.7167413938746;913.8811954445946;914.2457472585111;920.6271460058687;921.0099143420849;921.230772305126;962.9807403851919;972.65470757881;972.8978457261992;972.9902700972971;973.1679414100912;955.498363359235;959.8848138223458;961.3366776923936;962.1100482054072;963.1812958984876;712.5653351455608;714.2286330762764;714.5355518912696;715.6850985566334;716.2586722741937;12808.167469114478;12850.550230741972;12861.485540578366;12883.162594431333;12930.612081637471;12713.745725085446;13163.86836131636;13242.536511222157;13341.86658133416;13379.26414047227;9933.850992235122;10168.949155254177;10234.590616375335;10339.224558158168;10367.32612380193;3698.537682789651;3787.450819631138;3838.1747924196434;3839.865604703822;3844.1158533537164;49723;49920.500794991945;49924.25113623298;50224.24663630085;50232.49767502315;9696.321257511907;9701.805963880683;9850.901490985141;9879.555420006118;9882.160016398686;20134.19463221484;20165.09257083435;20186.899065504873;20215.191392344143;20218.887961162276;7522.887156692654;7593.620318984029;7721.227877212234;7745.612719364122;8016.679332826621;4094.4267802507675;4267.274563324242;4277.615014648634;4300.591443812833;4302.333138363559;3138.6076740407448;3173.76196785242;3179.0621766578915;3179.8410079496393;3183.8567264473154;534.292063016497;546.4644648244742;552.8120439050742;556.0824639345033;564.5793883556777;596.832886791699;711.7331000874672;720.8954701568243;730.2223132364015;733.6991833348302;8353.164683531599;8613.569321533898;8652.610632521551;8715;8876.20114189713;594.8066878264494;698.5040621158932;703.9084918960605;729.4201110117469;731.6561216228364;3900.8829735107424;4152.958470415258;4219.6202341789385;4276.786160691953;4449.888752781207;58184;60891.39108608946;62076;62122.689386552775;62195.06707399392;53676;53771.46228537743;53840.19239711407;54687.45312546903;54922;49733.50266497289;49739.50260497421;49760.25359619609;50471.24293135606;51185.23222151671;8533.74398768038;8823.823523529365;8852.734417967471;8987;8990.955045224731;36722.26555468929;37276.440853387765;37808.054798630255;38222.235555288185;38446;44605;45133;48617.5138248613;48750;53320;11229.27009744364;11548.48031838586;11817.054635629302;11945.104117191071;12091.758164836478;12567.18313309632;12740.171888827197;12895.742085158057;12913.09608327408;13783.37974791157;11874.465649045986;11895.702607434538;12054.879451205376;12385.133040687066;12435.813462798245;10863.456827158769;11036.613718520135;11039.280968661846;11848.185475839597;12262.26426414418;11858.16992810495;11936.58221962227;12149.878501215228;12265.509379625071;12295.9385203077;9985.301028928196;10266.691999240034;10397.272190946562;10469.528871200966;10600.681979540626;9828.31201815866;9892.505374731378;9937.155341796028;9988.200943924614;10045.447500387256 08:50:29 INFO - 2016-02-01 08:50:29,553 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:50:29 INFO - 2016-02-01 08:50:29,553 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:50:29 INFO - 2016-02-01 08:50:29,553 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,985 08:50:29 INFO - 2016-02-01 08:50:29,553 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:50:29 INFO - 2016-02-01 08:50:29,553 DEBUG : BROWSER_OUTPUT: __startTimestamp1454345429544__endTimestamp 08:50:29 INFO - 2016-02-01 08:50:29,553 DEBUG : BROWSER_OUTPUT: 08:50:29 INFO - 2016-02-01 08:50:29,554 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:50:29 INFO - 2016-02-01 08:50:29,554 DEBUG : BROWSER_OUTPUT: Number of tests: 6 08:50:29 INFO - 2016-02-01 08:50:29,554 DEBUG : BROWSER_OUTPUT: 08:50:29 INFO - 2016-02-01 08:50:29,554 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5606.69 Median:5787.45 stddev:618.79 (10.7%) stddev-sans-first:594.77 08:50:29 INFO - 2016-02-01 08:50:29,555 DEBUG : BROWSER_OUTPUT: Values: 3347.8 3410.2 3440.7 3442.8 3461.3 5519.1 5557.0 5575.2 5581.0 5606.3 5608.4 5618.1 5630.0 5645.0 5655.6 4947.5 5100.2 5142.9 5201.1 5247.8 5039.4 5050.1 5052.0 5054.8 5056.3 5159.1 5204.6 5218.9 5219.7 5223.5 5040.7 5051.8 5125.1 5139.5 5174.2 5145.9 5192.4 5255.3 5256.3 5294.8 5342.8 5427.6 5533.7 5543.4 5571.4 6060.2 6097.9 6118.1 6124.8 6125.9 6101.6 6109.6 6110.8 6114.9 6115.5 5443.7 5491.1 5534.1 5578.4 5641.4 6055.1 6101.2 6106.4 6106.6 6135.6 5941.5 5942.7 5943.4 5947.5 5953.0 5951.7 5982.2 6014.0 6036.5 6114.7 5969.7 6001.4 6007.1 6016.7 6045.4 6002.4 6008.6 6008.9 6009.0 6010.6 4483.8 4558.4 4582.2 4591.6 4608.6 4138.5 4164.4 4165.7 4166.5 4192.1 5760.4 5773.3 5775.1 5782.2 5810.9 5732.4 5836.3 5958.1 6106.3 6110.6 5730.3 5781.4 5784.3 5787.5 5790.6 5075.4 5295.3 5301.6 5315.1 5318.3 6040.5 6100.0 6205.3 6210.3 6210.4 6008.2 6182.1 6183.9 6193.2 6193.9 6085.0 6201.2 6284.2 6311.4 6343.3 5777.5 5864.5 5867.4 5875.3 5904.5 6112.8 6120.8 6162.7 6163.4 6174.1 5741.6 5788.2 5852.4 6008.7 6020.8 5798.9 6007.9 6012.5 6024.0 6027.3 5879.7 5902.0 5908.8 5914.7 5915.2 5566.0 5709.8 5711.0 5926.5 6002.9 5743.5 5754.7 5769.9 5778.4 5794.2 08:50:29 INFO - 2016-02-01 08:50:29,555 DEBUG : BROWSER_OUTPUT: 08:50:29 INFO - 2016-02-01 08:50:29,555 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:54523.13 Median:35648.32 stddev:95416.71 (267.7%) stddev-sans-first:95598.44 08:50:29 INFO - 2016-02-01 08:50:29,555 DEBUG : BROWSER_OUTPUT: Values: 586.7 630.8 633.7 637.9 644.8 33602.3 42324.4 42802.4 42984.4 43041.6 46741.3 47378.8 47448.8 47459.0 47495.3 8184.3 8262.3 8313.0 9143.7 9339.6 10953.7 11175.7 11184.0 11235.3 11248.1 10947.1 11029.2 11036.1 11040.8 11044.6 10909.1 10945.3 10997.9 11020.3 11036.4 10749.8 10855.5 10866.2 10876.9 10881.5 44026.0 44232.8 44349.3 44352.1 44375.3 3641.8 3719.1 3745.9 3876.9 4182.5 31467.5 31489.0 31581.2 32186.8 32346.7 3732.0 3756.0 3762.1 3775.7 3872.2 297552.0 300627.0 300951.0 303561.0 306027.0 60607.4 62122.7 68496.0 68985.0 75992.6 67075.7 67338.3 67707.3 67771.0 67813.7 17212.1 17318.9 17331.2 17401.4 17412.7 13192.8 13266.8 13341.5 13365.4 13370.8 22945.8 23109.7 23122.2 23235.3 23395.5 8453.4 8475.6 8658.8 8698.6 8802.5 1062.8 1079.7 1149.3 1154.0 1197.0 30505.2 30570.5 31126.5 31774.5 34757.3 843.0 1062.3 1065.6 1141.2 1173.3 14886.5 15232.6 15757.5 17998.9 18045.5 448159.0 453724.0 454836.0 455233.0 457291.0 60846.4 61050.0 61442.4 61476.4 61477.0 1486.6 1493.2 1494.1 1502.9 1515.8 31631.7 34777.5 35022.0 35118.6 35207.6 55223.0 55874.0 55968.7 55981.2 56008.4 312766.0 312796.0 314035.0 314150.0 314290.0 35259.5 35792.6 35864.6 36247.8 36265.3 46934.0 47600.8 47897.0 48086.8 48180.0 39300.8 39780.6 40045.0 40676.6 41203.8 31964.0 36682.8 40009.6 40394.0 41282.0 35336.5 35370.5 45257.5 46016.8 46857.0 34874.5 35504.0 35820.1 35998.0 41921.4 27106.9 27421.9 27570.6 27623.0 27659.0 08:50:29 INFO - 2016-02-01 08:50:29,556 DEBUG : BROWSER_OUTPUT: 08:50:29 INFO - 2016-02-01 08:50:29,556 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28664.28 Median:12103.64 stddev:84655.76 (699.4%) stddev-sans-first:84866.60 08:50:29 INFO - 2016-02-01 08:50:29,556 DEBUG : BROWSER_OUTPUT: Values: 379.7 382.9 383.9 384.0 387.7 15573.1 16965.3 18059.1 18460.2 18508.6 11597.3 11847.3 11855.9 18902.6 19128.4 4932.0 5039.2 5042.4 5050.2 5056.0 3997.6 4087.9 5064.7 5531.8 5663.2 5606.9 5627.5 5641.4 5652.4 5654.8 5481.3 5568.2 5573.0 5575.9 5588.5 5387.2 5395.1 5400.6 5405.4 5409.9 19875.9 22016.0 22439.7 22455.1 22472.3 2191.3 2209.5 2227.2 2237.1 2298.0 10072.6 10175.7 10320.5 12616.4 13845.9 1882.5 1945.0 1946.7 1947.2 1960.5 62415.0 64003.4 64188.0 65403.0 65628.7 10152.4 10165.4 10232.9 10297.2 10382.3 13236.2 13310.9 13321.8 13322.4 13367.1 7780.8 7929.9 7954.1 7961.2 8011.1 7963.4 7967.0 7994.8 8046.4 8063.1 4404.9 4425.3 4427.9 4430.7 4452.9 1706.2 1900.0 1920.9 1986.0 1987.6 942.0 978.8 1004.1 1094.0 1106.1 17270.3 17606.2 17867.6 18262.1 18286.9 935.4 1022.0 1049.0 1094.4 1133.7 9412.2 9708.6 9830.0 9833.9 9882.3 379723.0 380591.0 573428.0 577284.0 617710.0 84019.0 86429.0 86827.6 88222.0 88745.1 1162.1 1169.1 1184.3 1195.5 1201.7 15913.3 16175.7 16199.8 16267.7 16721.8 36198.6 36817.3 37217.0 37525.6 38009.2 50444.2 50660.5 51184.0 54314.2 56013.7 9784.0 10034.7 10091.6 10117.2 10190.4 11828.2 17104.7 17931.3 17991.0 18562.6 14534.6 14842.6 15340.6 15433.2 15675.3 14938.8 14939.4 15008.8 15358.2 15582.2 14451.1 14530.9 15645.5 15657.4 15950.8 9949.3 9984.3 10045.1 10060.5 10063.5 10721.5 12198.6 14602.8 14767.4 15654.0 08:50:29 INFO - 2016-02-01 08:50:29,556 DEBUG : BROWSER_OUTPUT: 08:50:29 INFO - 2016-02-01 08:50:29,556 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5560.95 Median:6734.33 stddev:2806.67 (41.7%) stddev-sans-first:2798.19 08:50:29 INFO - 2016-02-01 08:50:29,557 DEBUG : BROWSER_OUTPUT: Values: 1529.9 1597.7 1618.1 1770.3 1875.2 7444.7 7864.8 7866.8 7876.6 7877.0 6834.5 7034.6 7268.2 7448.5 7674.3 2327.6 2343.7 2368.7 2370.6 2371.8 2069.8 2322.8 2329.8 2392.4 2393.6 2058.6 2251.1 2303.1 2304.9 2305.9 2272.5 2279.6 2281.4 2350.3 2356.0 2260.9 2261.1 2291.3 2291.9 2337.9 7723.8 7748.5 7775.8 7807.4 7824.8 4719.2 4806.4 4810.4 4842.0 4846.3 3108.2 3128.2 3154.6 3222.5 3229.2 1119.1 1813.3 1899.8 1923.8 1927.6 10580.9 10680.4 10680.6 10689.4 10693.1 4209.8 4215.5 4243.5 4284.2 4321.6 8818.4 8847.8 8849.9 8852.3 8856.1 4778.6 4784.1 4785.7 4821.6 4823.6 4410.0 4466.4 4467.7 4469.0 4469.7 3328.5 3364.9 3390.3 3392.9 3421.8 994.1 1014.1 1037.6 1046.5 1051.5 1233.3 1246.6 1257.1 1491.7 1492.1 7437.3 7496.1 7639.3 7720.5 7721.9 1204.5 1220.7 1224.2 1224.8 1234.3 6028.4 6078.5 6135.7 6138.3 6143.2 9142.5 9488.8 9515.5 9521.3 9533.0 9323.6 9348.5 9381.2 9392.8 9408.2 6975.1 7168.2 7170.8 7172.1 7176.5 7324.3 7426.7 7492.6 7507.9 7509.7 8359.2 8406.9 8407.3 8506.5 8587.3 8558.2 8593.4 8670.1 8679.2 8694.4 7530.8 7621.7 7636.0 7638.0 7642.5 7644.3 7702.7 7725.1 7769.8 7771.8 5934.2 6988.2 7282.5 7364.6 7381.8 7034.9 7109.0 7142.1 7192.7 7271.3 7174.7 7182.7 7194.4 7274.1 7282.6 6727.3 6741.3 6743.7 6773.1 6778.6 6836.0 6840.2 6848.6 6862.8 6865.2 08:50:29 INFO - 2016-02-01 08:50:29,557 DEBUG : BROWSER_OUTPUT: 08:50:29 INFO - 2016-02-01 08:50:29,557 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5400.06 Median:6202.82 stddev:1627.63 (26.2%) stddev-sans-first:1587.37 08:50:29 INFO - 2016-02-01 08:50:29,557 DEBUG : BROWSER_OUTPUT: Values: 345.8 346.5 350.9 353.6 354.3 5896.8 6077.7 6097.7 6148.4 6159.0 5894.6 5959.9 6038.2 6065.3 6070.5 4666.2 4680.8 4682.1 4686.3 4689.5 4483.6 4566.2 4705.0 4709.3 4709.6 4562.2 4608.8 4611.5 4638.7 4650.2 4656.0 4660.9 4666.4 4668.4 4669.1 4108.8 4110.0 4128.6 4128.8 4132.4 5695.1 5779.3 5788.9 5795.6 5848.0 5344.7 5345.5 5354.0 5358.4 5360.2 6408.3 6425.8 6427.1 6434.7 6443.9 4308.7 4364.7 4397.5 4447.9 4466.8 6567.3 6614.4 6622.9 6623.6 6629.3 4566.4 4566.7 4566.7 4567.5 4571.9 4554.6 4611.1 4623.9 4627.8 4644.6 5354.7 5363.1 5364.2 5367.1 5375.2 6448.9 6451.4 6458.6 6466.2 6466.6 1620.9 1621.8 1622.8 1624.5 1624.8 1065.7 1066.9 1084.1 1095.6 1132.1 6300.5 6311.4 6315.4 6327.2 6327.9 6630.5 6637.2 6637.8 6645.7 6676.2 6167.4 6323.5 6326.0 6328.1 6328.3 3583.5 3608.9 3610.3 3612.6 3615.7 6851.0 6856.5 6859.1 6860.5 6861.0 6630.6 6772.6 6780.7 6790.2 6808.2 6725.5 6728.0 6741.4 6746.4 6757.7 6475.4 6605.4 6606.8 6616.4 6646.6 6510.0 6546.4 6632.3 6642.4 6644.3 6876.9 6914.1 6914.9 6915.1 6920.9 4493.2 4554.2 4556.1 4561.9 4563.4 6663.3 6712.8 6715.7 6718.8 6721.5 6512.1 6538.1 6542.9 6548.2 6551.2 6306.9 6526.6 6529.2 6534.2 6536.1 6404.9 6434.7 6447.2 6447.8 6448.5 6555.2 6566.7 6573.3 6575.9 6581.4 6238.2 6402.0 6406.9 6411.2 6412.4 08:50:29 INFO - 2016-02-01 08:50:29,557 DEBUG : BROWSER_OUTPUT: 08:50:29 INFO - 2016-02-01 08:50:29,557 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14517.45 Median:49749.88 stddev:17013.93 (34.2%) stddev-sans-first:17030.40 08:50:29 INFO - 2016-02-01 08:50:29,557 DEBUG : BROWSER_OUTPUT: Values: 784.2 854.6 859.6 864.8 917.6 13164.3 13218.5 13465.1 13466.3 14028.4 13131.0 13171.9 13263.5 13269.3 13270.9 765.2 778.5 903.2 928.0 928.7 913.9 914.2 920.6 921.0 921.2 963.0 972.7 972.9 973.0 973.2 955.5 959.9 961.3 962.1 963.2 712.6 714.2 714.5 715.7 716.3 12808.2 12850.6 12861.5 12883.2 12930.6 12713.7 13163.9 13242.5 13341.9 13379.3 9933.9 10168.9 10234.6 10339.2 10367.3 3698.5 3787.5 3838.2 3839.9 3844.1 49723.0 49920.5 49924.3 50224.2 50232.5 9696.3 9701.8 9850.9 9879.6 9882.2 20134.2 20165.1 20186.9 20215.2 20218.9 7522.9 7593.6 7721.2 7745.6 8016.7 4094.4 4267.3 4277.6 4300.6 4302.3 3138.6 3173.8 3179.1 3179.8 3183.9 534.3 546.5 552.8 556.1 564.6 596.8 711.7 720.9 730.2 733.7 8353.2 8613.6 8652.6 8715.0 8876.2 594.8 698.5 703.9 729.4 731.7 3900.9 4153.0 4219.6 4276.8 4449.9 58184.0 60891.4 62076.0 62122.7 62195.1 53676.0 53771.5 53840.2 54687.5 54922.0 49733.5 49739.5 49760.3 50471.2 51185.2 8533.7 8823.8 8852.7 8987.0 8991.0 36722.3 37276.4 37808.1 38222.2 38446.0 44605.0 45133.0 48617.5 48750.0 53320.0 11229.3 11548.5 11817.1 11945.1 12091.8 12567.2 12740.2 12895.7 12913.1 13783.4 11874.5 11895.7 12054.9 12385.1 12435.8 10863.5 11036.6 11039.3 11848.2 12262.3 11858.2 11936.6 12149.9 12265.5 12295.9 9985.3 10266.7 10397.3 10469.5 10600.7 9828.3 9892.5 9937.2 9988.2 10045.4 08:50:29 INFO - 2016-02-01 08:50:29,557 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:50:29 INFO - 2016-02-01 08:50:29,558 DEBUG : BROWSER_OUTPUT: 08:50:30 INFO - 2016-02-01 08:50:30,094 INFO : Browser exited with error code: 0 08:50:30 INFO - 2016-02-01 08:50:30,109 INFO : Completed test dromaeo_css (00:18:30) 08:50:30 INFO - 2016-02-01 08:50:30,109 INFO : Starting test kraken 08:50:30 INFO - 2016-02-01 08:50:30,110 DEBUG : operating with platform_type : linux_ 08:50:30 INFO - 2016-02-01 08:50:30,110 INFO : Initialising browser for kraken test... 08:50:30 INFO - 2016-02-01 08:50:30,116 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfdSu_q/profile http://localhost:38214/getInfo.html 08:50:34 INFO - 2016-02-01 08:50:34,024 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:50:34 INFO - 2016-02-01 08:50:34,024 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:50:34 INFO - 2016-02-01 08:50:34,024 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:50:34 INFO - 2016-02-01 08:50:34,024 DEBUG : BROWSER_OUTPUT: __metrics 08:50:34 INFO - 2016-02-01 08:50:34,193 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:50:35 INFO - 2016-02-01 08:50:35,124 INFO : Browser initialized. 08:50:35 INFO - 2016-02-01 08:50:35,125 INFO : Running cycle 1/1 for kraken test... 08:50:35 INFO - 2016-02-01 08:50:35,125 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfdSu_q/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:50:46 INFO - 2016-02-01 08:50:46,861 DEBUG : BROWSER_OUTPUT: RSS: Main: 134193152 08:50:46 INFO - 2016-02-01 08:50:46,861 DEBUG : BROWSER_OUTPUT: 08:50:49 INFO - 2016-02-01 08:50:49,276 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38214/tests/kraken/driver.html?testName=audio-beat-detection) 08:50:49 INFO - 2016-02-01 08:50:49,850 DEBUG : BROWSER_OUTPUT: RSS: Main: 164970496 08:50:49 INFO - 2016-02-01 08:50:49,850 DEBUG : BROWSER_OUTPUT: 08:50:52 INFO - 2016-02-01 08:50:52,433 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38214/tests/kraken/driver.html?testName=audio-dft) 08:50:52 INFO - 2016-02-01 08:50:52,998 DEBUG : BROWSER_OUTPUT: RSS: Main: 162779136 08:50:52 INFO - 2016-02-01 08:50:52,999 DEBUG : BROWSER_OUTPUT: 08:50:55 INFO - 2016-02-01 08:50:55,818 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38214/tests/kraken/driver.html?testName=audio-fft) 08:50:56 INFO - 2016-02-01 08:50:56,387 DEBUG : BROWSER_OUTPUT: RSS: Main: 161062912 08:50:56 INFO - 2016-02-01 08:50:56,388 DEBUG : BROWSER_OUTPUT: 08:50:58 INFO - 2016-02-01 08:50:58,352 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38214/tests/kraken/driver.html?testName=audio-oscillator) 08:50:58 INFO - 2016-02-01 08:50:58,918 DEBUG : BROWSER_OUTPUT: RSS: Main: 159236096 08:50:58 INFO - 2016-02-01 08:50:58,918 DEBUG : BROWSER_OUTPUT: 08:51:01 INFO - 2016-02-01 08:51:01,091 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38214/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:51:01 INFO - 2016-02-01 08:51:01,644 DEBUG : BROWSER_OUTPUT: RSS: Main: 159797248 08:51:01 INFO - 2016-02-01 08:51:01,644 DEBUG : BROWSER_OUTPUT: 08:51:04 INFO - 2016-02-01 08:51:04,512 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38214/tests/kraken/driver.html?testName=imaging-darkroom) 08:51:05 INFO - 2016-02-01 08:51:05,078 DEBUG : BROWSER_OUTPUT: RSS: Main: 159367168 08:51:05 INFO - 2016-02-01 08:51:05,078 DEBUG : BROWSER_OUTPUT: 08:51:08 INFO - 2016-02-01 08:51:08,402 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38214/tests/kraken/driver.html?testName=imaging-desaturate) 08:51:08 INFO - 2016-02-01 08:51:08,970 DEBUG : BROWSER_OUTPUT: RSS: Main: 158539776 08:51:08 INFO - 2016-02-01 08:51:08,970 DEBUG : BROWSER_OUTPUT: 08:51:11 INFO - 2016-02-01 08:51:11,708 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38214/tests/kraken/driver.html?testName=json-parse-financial) 08:51:12 INFO - 2016-02-01 08:51:12,282 DEBUG : BROWSER_OUTPUT: RSS: Main: 159301632 08:51:12 INFO - 2016-02-01 08:51:12,282 DEBUG : BROWSER_OUTPUT: 08:51:13 INFO - 2016-02-01 08:51:13,803 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38214/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:51:14 INFO - 2016-02-01 08:51:14,357 DEBUG : BROWSER_OUTPUT: RSS: Main: 166445056 08:51:14 INFO - 2016-02-01 08:51:14,357 DEBUG : BROWSER_OUTPUT: 08:51:16 INFO - 2016-02-01 08:51:16,101 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38214/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:51:16 INFO - 2016-02-01 08:51:16,695 DEBUG : BROWSER_OUTPUT: RSS: Main: 164012032 08:51:16 INFO - 2016-02-01 08:51:16,695 DEBUG : BROWSER_OUTPUT: 08:51:18 INFO - 2016-02-01 08:51:18,646 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38214/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:51:19 INFO - 2016-02-01 08:51:19,213 DEBUG : BROWSER_OUTPUT: RSS: Main: 170094592 08:51:19 INFO - 2016-02-01 08:51:19,213 DEBUG : BROWSER_OUTPUT: 08:51:21 INFO - 2016-02-01 08:51:21,546 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38214/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:51:22 INFO - 2016-02-01 08:51:22,128 DEBUG : BROWSER_OUTPUT: RSS: Main: 175521792 08:51:22 INFO - 2016-02-01 08:51:22,128 DEBUG : BROWSER_OUTPUT: 08:51:24 INFO - 2016-02-01 08:51:24,583 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38214/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:51:25 INFO - 2016-02-01 08:51:25,150 DEBUG : BROWSER_OUTPUT: RSS: Main: 173735936 08:51:25 INFO - 2016-02-01 08:51:25,150 DEBUG : BROWSER_OUTPUT: 08:51:26 INFO - 2016-02-01 08:51:26,800 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38214/tests/kraken/driver.html?testName=ai-astar) 08:51:27 INFO - 2016-02-01 08:51:27,046 DEBUG : BROWSER_OUTPUT: RSS: Main: 232259584 08:51:27 INFO - 2016-02-01 08:51:27,046 DEBUG : BROWSER_OUTPUT: 08:51:27 INFO - 2016-02-01 08:51:27,048 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:51:27 INFO - 2016-02-01 08:51:27,048 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:51:27 INFO - 2016-02-01 08:51:27,048 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:51:27 INFO - 2016-02-01 08:51:27,049 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:51:27 INFO - 2016-02-01 08:51:27,049 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;110;105;108;112;110;99;109;109;99 08:51:27 INFO - 2016-02-01 08:51:27,049 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;148;127;167;161;129;142;147;122;173;123 08:51:27 INFO - 2016-02-01 08:51:27,049 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;162;160;160;162;162;161;160;161;160 08:51:27 INFO - 2016-02-01 08:51:27,049 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;71;73;156;71;72;72;71;152;71 08:51:27 INFO - 2016-02-01 08:51:27,050 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;93;138;92;92;92;139;92;93;134 08:51:27 INFO - 2016-02-01 08:51:27,050 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;109;109;109;110 08:51:27 INFO - 2016-02-01 08:51:27,050 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;145;145;145;145;146;146;147;146;146 08:51:27 INFO - 2016-02-01 08:51:27,050 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;95;96;95;96;95 08:51:27 INFO - 2016-02-01 08:51:27,050 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;57;57;56;59;57;56;56;53;53 08:51:27 INFO - 2016-02-01 08:51:27,050 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;62;63;64;63;63;63;63;64;63 08:51:27 INFO - 2016-02-01 08:51:27,051 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;75;76;79;78;78;77;77;78;77 08:51:27 INFO - 2016-02-01 08:51:27,051 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;110;119;114;114;115;118;111;110;112;118 08:51:27 INFO - 2016-02-01 08:51:27,051 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;144;151;141;141;144;138;133;141;130 08:51:27 INFO - 2016-02-01 08:51:27,051 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;63;65;64;67;63;69;64;64;70 08:51:27 INFO - 2016-02-01 08:51:27,051 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:51:27 INFO - 2016-02-01 08:51:27,051 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:51:27 INFO - 2016-02-01 08:51:27,051 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,892 08:51:27 INFO - 2016-02-01 08:51:27,051 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:51:27 INFO - 2016-02-01 08:51:27,052 DEBUG : BROWSER_OUTPUT: __startTimestamp1454345487047__endTimestamp 08:51:27 INFO - 2016-02-01 08:51:27,052 DEBUG : BROWSER_OUTPUT: 08:51:27 INFO - 2016-02-01 08:51:27,055 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:51:27 INFO - 2016-02-01 08:51:27,055 DEBUG : BROWSER_OUTPUT: Number of tests: 14 08:51:27 INFO - 2016-02-01 08:51:27,055 DEBUG : BROWSER_OUTPUT: 08:51:27 INFO - 2016-02-01 08:51:27,055 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.00 Median:110.00 stddev:4.57 (4.2%) stddev-sans-first:4.79 08:51:27 INFO - 2016-02-01 08:51:27,055 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 105.0 108.0 112.0 110.0 99.0 109.0 109.0 99.0 08:51:27 INFO - 2016-02-01 08:51:27,055 DEBUG : BROWSER_OUTPUT: 08:51:27 INFO - 2016-02-01 08:51:27,055 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.90 Median:147.50 stddev:18.64 (12.6%) stddev-sans-first:19.71 08:51:27 INFO - 2016-02-01 08:51:27,056 DEBUG : BROWSER_OUTPUT: Values: 148.0 127.0 167.0 161.0 129.0 142.0 147.0 122.0 173.0 123.0 08:51:27 INFO - 2016-02-01 08:51:27,056 DEBUG : BROWSER_OUTPUT: 08:51:27 INFO - 2016-02-01 08:51:27,056 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:0.93 08:51:27 INFO - 2016-02-01 08:51:27,056 DEBUG : BROWSER_OUTPUT: Values: 159.0 162.0 160.0 160.0 162.0 162.0 161.0 160.0 161.0 160.0 08:51:27 INFO - 2016-02-01 08:51:27,056 DEBUG : BROWSER_OUTPUT: 08:51:27 INFO - 2016-02-01 08:51:27,056 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.90 Median:71.00 stddev:34.86 (49.1%) stddev-sans-first:36.37 08:51:27 INFO - 2016-02-01 08:51:27,056 DEBUG : BROWSER_OUTPUT: Values: 70.0 71.0 73.0 156.0 71.0 72.0 72.0 71.0 152.0 71.0 08:51:27 INFO - 2016-02-01 08:51:27,056 DEBUG : BROWSER_OUTPUT: 08:51:27 INFO - 2016-02-01 08:51:27,056 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.70 Median:92.00 stddev:21.64 (23.5%) stddev-sans-first:22.38 08:51:27 INFO - 2016-02-01 08:51:27,056 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 138.0 92.0 92.0 92.0 139.0 92.0 93.0 134.0 08:51:27 INFO - 2016-02-01 08:51:27,056 DEBUG : BROWSER_OUTPUT: 08:51:27 INFO - 2016-02-01 08:51:27,056 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 08:51:27 INFO - 2016-02-01 08:51:27,056 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 110.0 08:51:27 INFO - 2016-02-01 08:51:27,056 DEBUG : BROWSER_OUTPUT: 08:51:27 INFO - 2016-02-01 08:51:27,056 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.79 (0.5%) stddev-sans-first:0.71 08:51:27 INFO - 2016-02-01 08:51:27,056 DEBUG : BROWSER_OUTPUT: Values: 147.0 145.0 145.0 145.0 145.0 146.0 146.0 147.0 146.0 146.0 08:51:27 INFO - 2016-02-01 08:51:27,057 DEBUG : BROWSER_OUTPUT: 08:51:27 INFO - 2016-02-01 08:51:27,057 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 08:51:27 INFO - 2016-02-01 08:51:27,057 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 95.0 08:51:27 INFO - 2016-02-01 08:51:27,057 DEBUG : BROWSER_OUTPUT: 08:51:27 INFO - 2016-02-01 08:51:27,057 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.10 Median:57.00 stddev:1.85 (3.3%) stddev-sans-first:1.94 08:51:27 INFO - 2016-02-01 08:51:27,057 DEBUG : BROWSER_OUTPUT: Values: 57.0 57.0 57.0 56.0 59.0 57.0 56.0 56.0 53.0 53.0 08:51:27 INFO - 2016-02-01 08:51:27,057 DEBUG : BROWSER_OUTPUT: 08:51:27 INFO - 2016-02-01 08:51:27,057 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.10 Median:63.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 08:51:27 INFO - 2016-02-01 08:51:27,057 DEBUG : BROWSER_OUTPUT: Values: 63.0 62.0 63.0 64.0 63.0 63.0 63.0 63.0 64.0 63.0 08:51:27 INFO - 2016-02-01 08:51:27,057 DEBUG : BROWSER_OUTPUT: 08:51:27 INFO - 2016-02-01 08:51:27,057 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:78.00 stddev:2.71 (3.5%) stddev-sans-first:1.20 08:51:27 INFO - 2016-02-01 08:51:27,057 DEBUG : BROWSER_OUTPUT: Values: 85.0 75.0 76.0 79.0 78.0 78.0 77.0 77.0 78.0 77.0 08:51:27 INFO - 2016-02-01 08:51:27,057 DEBUG : BROWSER_OUTPUT: 08:51:27 INFO - 2016-02-01 08:51:27,057 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:114.10 Median:114.50 stddev:3.38 (3.0%) stddev-sans-first:3.24 08:51:27 INFO - 2016-02-01 08:51:27,057 DEBUG : BROWSER_OUTPUT: Values: 110.0 119.0 114.0 114.0 115.0 118.0 111.0 110.0 112.0 118.0 08:51:27 INFO - 2016-02-01 08:51:27,058 DEBUG : BROWSER_OUTPUT: 08:51:27 INFO - 2016-02-01 08:51:27,058 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:139.80 Median:141.00 stddev:6.09 (4.3%) stddev-sans-first:6.20 08:51:27 INFO - 2016-02-01 08:51:27,058 DEBUG : BROWSER_OUTPUT: Values: 135.0 144.0 151.0 141.0 141.0 144.0 138.0 133.0 141.0 130.0 08:51:27 INFO - 2016-02-01 08:51:27,058 DEBUG : BROWSER_OUTPUT: 08:51:27 INFO - 2016-02-01 08:51:27,058 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:64.50 stddev:2.50 (3.9%) stddev-sans-first:2.60 08:51:27 INFO - 2016-02-01 08:51:27,058 DEBUG : BROWSER_OUTPUT: Values: 64.0 63.0 65.0 64.0 67.0 63.0 69.0 64.0 64.0 70.0 08:51:27 INFO - 2016-02-01 08:51:27,058 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:51:27 INFO - 2016-02-01 08:51:27,058 DEBUG : BROWSER_OUTPUT: 08:51:27 INFO - 2016-02-01 08:51:27,685 INFO : Browser exited with error code: 0 08:51:27 INFO - 2016-02-01 08:51:27,748 INFO : Completed test kraken (00:00:57) 08:51:27 INFO - 2016-02-01 08:51:27,748 INFO : Starting test v8_7 08:51:27 INFO - 2016-02-01 08:51:27,748 DEBUG : operating with platform_type : linux_ 08:51:27 INFO - 2016-02-01 08:51:27,748 INFO : Initialising browser for v8_7 test... 08:51:27 INFO - 2016-02-01 08:51:27,755 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp_bh6gA/profile http://localhost:38214/getInfo.html 08:51:31 INFO - 2016-02-01 08:51:31,634 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:51:31 INFO - 2016-02-01 08:51:31,634 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:51:31 INFO - 2016-02-01 08:51:31,634 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:51:31 INFO - 2016-02-01 08:51:31,634 DEBUG : BROWSER_OUTPUT: __metrics 08:51:31 INFO - 2016-02-01 08:51:31,791 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:51:32 INFO - 2016-02-01 08:51:32,725 INFO : Browser initialized. 08:51:32 INFO - 2016-02-01 08:51:32,726 INFO : Running cycle 1/1 for v8_7 test... 08:51:32 INFO - 2016-02-01 08:51:32,726 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp_bh6gA/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:51:44 INFO - 2016-02-01 08:51:44,491 DEBUG : BROWSER_OUTPUT: RSS: Main: 134311936 08:51:44 INFO - 2016-02-01 08:51:44,491 DEBUG : BROWSER_OUTPUT: 08:52:05 INFO - 2016-02-01 08:52:05,818 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38214/tests/v8_7/run.html (next: http://localhost:38214/tests/v8_7/run.html) 08:52:06 INFO - 2016-02-01 08:52:06,077 DEBUG : BROWSER_OUTPUT: RSS: Main: 422060032 08:52:06 INFO - 2016-02-01 08:52:06,077 DEBUG : BROWSER_OUTPUT: 08:52:06 INFO - 2016-02-01 08:52:06,077 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:52:06 INFO - 2016-02-01 08:52:06,077 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:52:06 INFO - 2016-02-01 08:52:06,077 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:52:06 INFO - 2016-02-01 08:52:06,077 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:52:06 INFO - 2016-02-01 08:52:06,078 DEBUG : BROWSER_OUTPUT: |0;Richards;171.7032967032967 08:52:06 INFO - 2016-02-01 08:52:06,078 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.6090534979424 08:52:06 INFO - 2016-02-01 08:52:06,078 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.4857462269424 08:52:06 INFO - 2016-02-01 08:52:06,078 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5175.257731958763 08:52:06 INFO - 2016-02-01 08:52:06,078 DEBUG : BROWSER_OUTPUT: |4;RayTrace;941.6195856873823 08:52:06 INFO - 2016-02-01 08:52:06,078 DEBUG : BROWSER_OUTPUT: |5;Earley;539.9568034557235 08:52:06 INFO - 2016-02-01 08:52:06,078 DEBUG : BROWSER_OUTPUT: |6;Boyer;10956.521739130434 08:52:06 INFO - 2016-02-01 08:52:06,079 DEBUG : BROWSER_OUTPUT: |7;RegExp;24047.619047619046 08:52:06 INFO - 2016-02-01 08:52:06,079 DEBUG : BROWSER_OUTPUT: |8;Splay;518.595041322314 08:52:06 INFO - 2016-02-01 08:52:06,079 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 08:52:06 INFO - 2016-02-01 08:52:06,079 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:52:06 INFO - 2016-02-01 08:52:06,079 DEBUG : BROWSER_OUTPUT: __startTimestamp1454345526070__endTimestamp 08:52:06 INFO - 2016-02-01 08:52:06,079 DEBUG : BROWSER_OUTPUT: 08:52:06 INFO - 2016-02-01 08:52:06,079 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:52:06 INFO - 2016-02-01 08:52:06,079 DEBUG : BROWSER_OUTPUT: Number of tests: 10 08:52:06 INFO - 2016-02-01 08:52:06,079 DEBUG : BROWSER_OUTPUT: 08:52:06 INFO - 2016-02-01 08:52:06,079 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:171.70 Median:171.70 stddev:0.00 (0.0%) 08:52:06 INFO - 2016-02-01 08:52:06,079 DEBUG : BROWSER_OUTPUT: Values: 171.7 08:52:06 INFO - 2016-02-01 08:52:06,079 DEBUG : BROWSER_OUTPUT: 08:52:06 INFO - 2016-02-01 08:52:06,079 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.61 Median:164.61 stddev:0.00 (0.0%) 08:52:06 INFO - 2016-02-01 08:52:06,079 DEBUG : BROWSER_OUTPUT: Values: 164.6 08:52:06 INFO - 2016-02-01 08:52:06,079 DEBUG : BROWSER_OUTPUT: 08:52:06 INFO - 2016-02-01 08:52:06,079 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.49 Median:279.49 stddev:0.00 (0.0%) 08:52:06 INFO - 2016-02-01 08:52:06,080 DEBUG : BROWSER_OUTPUT: Values: 279.5 08:52:06 INFO - 2016-02-01 08:52:06,080 DEBUG : BROWSER_OUTPUT: 08:52:06 INFO - 2016-02-01 08:52:06,080 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5175.26 Median:5175.26 stddev:0.00 (0.0%) 08:52:06 INFO - 2016-02-01 08:52:06,080 DEBUG : BROWSER_OUTPUT: Values: 5175.3 08:52:06 INFO - 2016-02-01 08:52:06,080 DEBUG : BROWSER_OUTPUT: 08:52:06 INFO - 2016-02-01 08:52:06,080 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:941.62 Median:941.62 stddev:0.00 (0.0%) 08:52:06 INFO - 2016-02-01 08:52:06,080 DEBUG : BROWSER_OUTPUT: Values: 941.6 08:52:06 INFO - 2016-02-01 08:52:06,080 DEBUG : BROWSER_OUTPUT: 08:52:06 INFO - 2016-02-01 08:52:06,080 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:539.96 Median:539.96 stddev:0.00 (0.0%) 08:52:06 INFO - 2016-02-01 08:52:06,080 DEBUG : BROWSER_OUTPUT: Values: 540.0 08:52:06 INFO - 2016-02-01 08:52:06,080 DEBUG : BROWSER_OUTPUT: 08:52:06 INFO - 2016-02-01 08:52:06,080 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10956.52 Median:10956.52 stddev:0.00 (0.0%) 08:52:06 INFO - 2016-02-01 08:52:06,080 DEBUG : BROWSER_OUTPUT: Values: 10956.5 08:52:06 INFO - 2016-02-01 08:52:06,080 DEBUG : BROWSER_OUTPUT: 08:52:06 INFO - 2016-02-01 08:52:06,080 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24047.62 Median:24047.62 stddev:0.00 (0.0%) 08:52:06 INFO - 2016-02-01 08:52:06,080 DEBUG : BROWSER_OUTPUT: Values: 24047.6 08:52:06 INFO - 2016-02-01 08:52:06,081 DEBUG : BROWSER_OUTPUT: 08:52:06 INFO - 2016-02-01 08:52:06,081 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:518.60 Median:518.60 stddev:0.00 (0.0%) 08:52:06 INFO - 2016-02-01 08:52:06,081 DEBUG : BROWSER_OUTPUT: Values: 518.6 08:52:06 INFO - 2016-02-01 08:52:06,081 DEBUG : BROWSER_OUTPUT: 08:52:06 INFO - 2016-02-01 08:52:06,081 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 08:52:06 INFO - 2016-02-01 08:52:06,081 DEBUG : BROWSER_OUTPUT: Values: 4822.1 08:52:06 INFO - 2016-02-01 08:52:06,081 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:52:06 INFO - 2016-02-01 08:52:06,081 DEBUG : BROWSER_OUTPUT: 08:52:06 INFO - 2016-02-01 08:52:06,781 INFO : Browser exited with error code: 0 08:52:06 INFO - 2016-02-01 08:52:06,798 INFO : Completed test v8_7 (00:00:39) 08:52:07 INFO - 2016-02-01 08:52:07,111 INFO : Completed test suite (00:20:07) 08:52:07 INFO - 2016-02-01 08:52:07,111 DEBUG : Working with test: dromaeo_css 08:52:07 INFO - 2016-02-01 08:52:07,111 DEBUG : Generating results file: dromaeo_css 08:52:07 INFO - 2016-02-01 08:52:07,112 DEBUG : Working with test: kraken 08:52:07 INFO - 2016-02-01 08:52:07,113 DEBUG : Generating results file: kraken 08:52:07 INFO - 2016-02-01 08:52:07,113 INFO : javascript benchmark 08:52:07 INFO - 2016-02-01 08:52:07,113 DEBUG : Working with test: v8_7 08:52:07 INFO - 2016-02-01 08:52:07,113 DEBUG : Generating results file: v8_7 08:52:07 INFO - 2016-02-01 08:52:07,114 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:52:07 INFO - 2016-02-01 08:52:07,174 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 08:52:07 INFO - 2016-02-01 08:52:07,174 DEBUG : process_Request line: dromaeo_css 7371.70 graph.html#tests=[[72,53,35]] 08:52:07 INFO - 2016-02-01 08:52:07,175 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:52:07 INFO - 2016-02-01 08:52:07,209 DEBUG : process_Request line: kraken 1471.70 graph.html#tests=[[232,53,35]] 08:52:07 INFO - 2016-02-01 08:52:07,209 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:52:07 INFO - 2016-02-01 08:52:07,254 DEBUG : process_Request line: v8_7 23214.21 graph.html#tests=[[230,53,35]] 08:52:07 INFO - 2016-02-01 08:52:07,255 INFO : javascript benchmark 08:52:07 INFO - 2016-02-01 08:52:07,259 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3347.8326083695815, 3410.249745056087, 3440.7419443541726, 3442.7762195457294, 3461.325041616885, 5519.061759500884, 5556.972215138929, 5575.191597218406, 5581.0, 5606.327240731118, 5608.43915608439, 5618.129189975561, 5629.971850140743, 5645.0, 5655.632383895044, 4947.480514545975, 5100.1584738518195, 5142.871428214287, 5201.141811601081, 5247.816326428576, 5039.370078740158, 5050.090983622947, 5052.0, 5054.772535235905, 5056.266841308008, 5159.07136715393, 5204.609654275944, 5218.895622087575, 5219.71291578963, 5223.503767142115, 5040.697558146505, 5051.797928082854, 5125.077486052509, 5139.460356662534, 5174.172132458826, 5145.897082058375, 5192.428832828386, 5255.264263003182, 5256.26412302278, 5294.788208471657, 5342.759575819097, 5427.565794736412, 5533.723313834292, 5543.362513310981, 5571.415001424833, 6060.2424696912885, 6097.939020609781, 6118.1128736332985, 6124.785632502885, 6125.938740612582, 6101.603395779304, 6109.602875813058, 6110.7861224856915, 6114.877702445926, 6115.48018418435, 5443.727813609344, 5491.148871924818, 5534.08687566551, 5578.386377498472, 5641.379448260688, 6055.091736239599, 6101.206843110367, 6106.419890110433, 6106.572539922252, 6135.601185922901, 5941.465268125889, 5942.732577034044, 5943.375945525744, 5947.464728174485, 5953.047512397996, 5951.702414879239, 5982.162497250389, 6014.037753959433, 6036.5170786336985, 6114.724837382329, 5969.701514924236, 6001.399860013964, 6007.098935159674, 6016.669083200466, 6045.395460453919, 6002.429769171922, 6008.609440386361, 6008.939910600838, 6008.969955150196, 6010.579259451883, 4483.820647174141, 4558.407407037064, 4582.24392975162, 4591.632669386441, 4608.631309495232, 4138.461999940048, 4164.43780089684, 4165.687573432005, 4166.479190101238, 4192.119654872511, 5760.36635970043, 5773.336066352339, 5775.075987841925, 5782.161586570008, 5810.912836307459, 5732.398098199712, 5836.270466191726, 5958.136070269787, 6106.297775755756, 6110.602810817282, 5730.3410107838135, 5781.421857814269, 5784.334801497882, 5787.450192231731, 5790.565707571949, 5075.365579302587, 5295.285136506599, 5301.628885978022, 5315.149576067811, 5318.282031925718, 6040.456358927718, 6100.0, 6205.2553693557065, 6210.285817131088, 6210.378962103844, 6008.188894499362, 6182.1345011699295, 6183.907241391473, 6193.163922870355, 6193.876122477435, 6085.0, 6201.162843016135, 6284.214473190851, 6311.400416960381, 6343.270523889627, 5777.480026797609, 5864.472197502245, 5867.413258674099, 5875.3243377010485, 5904.52763778888, 6112.78605248832, 6120.785772497941, 6162.722677479614, 6163.3528479509005, 6174.135621012972, 5741.569382296428, 5788.160716696139, 5852.4440178183, 6008.669523176092, 6020.759169633166, 5798.913016304843, 6007.879842403215, 6012.549058820693, 6023.969880150747, 6027.336992930862, 5879.7060146993335, 5902.0, 5908.822735317947, 5914.7338369774325, 5915.231019967377, 5565.972170139124, 5709.8287051388525, 5711.029125048669, 5926.525877929669, 6002.879942401215, 5743.540516758566, 5754.741036653444, 5769.8557536061935, 5778.364379918122, 5794.217780599565], "unit": "ms", "name": "dojo.html", "value": 5567.122590542148}, {"lowerIsBetter": false, "replicates": [586.662668965345, 630.8044506203076, 633.6863252689914, 637.9011253255748, 644.7678835619173, 33602.327953440945, 42324.36513452297, 42802.35796463048, 42984.355234671464, 43041.56958430415, 46741.29888051673, 47378.76310618442, 47448.762756186254, 47459.0, 47495.287570686465, 8184.345252379795, 8262.339012878985, 8313.043999940024, 9143.679971201009, 9339.626414943394, 10953.726156846104, 11175.720606984809, 11184.049355804766, 11235.325880447199, 11248.100151987821, 10947.069499092548, 11029.172812039089, 11036.11711063117, 11040.779184416308, 11044.613438529654, 10909.127269818435, 10945.343279403181, 10997.890021099807, 11020.283681560673, 11036.392998385085, 10749.83875241872, 10855.511501982352, 10866.185036122322, 10876.891231087668, 10881.510332035077, 44026.0, 44232.778836105615, 44349.33475997863, 44352.11295774099, 44375.33436998448, 3641.7814931103953, 3719.1445967427644, 3745.943810842812, 3876.8643097491454, 4182.498100227962, 31467.52798708021, 31489.0, 31581.210469738442, 32186.83906580452, 32346.6765332346, 3731.9626803731885, 3755.9812200939095, 3762.1159027628487, 3775.6601905828607, 3872.186840763443, 297552.0, 300627.0, 300951.0, 303561.0, 306027.0, 60607.39392606106, 62122.689386552775, 68496.0, 68985.0, 75992.62003689946, 67075.66462167756, 67338.32661673418, 67707.32292677108, 67771.0, 67813.66093169502, 17212.1393930303, 17318.913405432893, 17331.220095095752, 17401.390951316644, 17412.738808917875, 13192.802107968388, 13266.800997985038, 13341.533046343331, 13365.398557064955, 13370.799438008438, 22945.770542294696, 23109.653355199687, 23122.19072332494, 23235.3029409118, 23395.53208935812, 8453.365997550209, 8475.576221188916, 8658.78353041179, 8698.565071746387, 8802.471851688919, 1062.8458873463305, 1079.6707004363586, 1149.3391300002531, 1153.9596114135966, 1196.9700757481132, 30505.23736906595, 30570.54144187839, 31126.533102003486, 31774.523382149287, 34757.30485390278, 843.026304618167, 1062.2776511971933, 1065.6217042949797, 1141.2467771271088, 1173.2784337632295, 14886.478973235884, 15232.619184520476, 15757.527274181793, 17998.91000545015, 18045.458636240935, 448159.0, 453724.0, 454836.0, 455233.0, 457291.0, 60846.39153608496, 61050.0, 61442.38557614456, 61476.38523614796, 61477.0, 1486.6060493969107, 1493.201137391512, 1494.066208619591, 1502.909825410457, 1515.7650564162573, 31631.683683163796, 34777.47833782445, 35022.0, 35118.648813511536, 35207.647923521465, 55223.0, 55874.0, 55968.72015639896, 55981.16028259661, 56008.43991560032, 312766.0, 312796.0, 314035.0, 314150.0, 314290.0, 35259.47110793289, 35792.64207357997, 35864.641353587176, 36247.818760906026, 36265.27469450543, 46934.0, 47600.761996189794, 47897.0, 48086.759566201945, 48180.0, 39300.803495982334, 39780.60219397769, 40045.0, 40676.59323406728, 41203.7939810299, 31964.0, 36682.8165859169, 40009.59990400175, 40394.0, 41282.0, 35336.46995295124, 35370.46944295786, 45257.547424525335, 46016.7699161502, 46857.0, 34874.47688284627, 35504.0, 35820.10449738777, 35998.0, 41921.37117943294, 27106.864465677547, 27421.862890685417, 27570.5864412038, 27623.0, 27659.0], "unit": "ms", "name": "ext.html", "value": 20912.443622766215}, {"lowerIsBetter": false, "replicates": [379.7303914220903, 382.87939299120774, 383.9270538597666, 383.9865604703838, 387.6685433953969, 15573.143477108755, 16965.3213871445, 18059.097045147755, 18460.16929238184, 18508.629827403445, 11597.30416175028, 11847.289162650248, 11855.881441185586, 18902.62194756104, 19128.42614721557, 4932.0, 5039.21892106724, 5042.445331013586, 5050.166722490803, 5055.9747201263945, 3997.560268370477, 4087.8569250076253, 5064.746762661871, 5531.778728850862, 5663.1505274208785, 5606.943930560683, 5627.521660658849, 5641.351244606882, 5652.378238393773, 5654.802081927133, 5481.287432633752, 5568.1647752837, 5573.0, 5575.944240557604, 5588.524975377098, 5387.164989426645, 5395.082835917864, 5400.567954563626, 5405.37838148614, 5409.864753381157, 19875.90062049695, 22016.009279582457, 22439.663405048937, 22455.10179592814, 22472.32583022512, 2191.2659259148204, 2209.5028618560855, 2227.2204728345114, 2237.093976939342, 2297.9885100574556, 10072.647457339031, 10175.694729158136, 10320.483975801255, 12616.432260548298, 13845.861541384558, 1882.5293676580854, 1944.980550194494, 1946.6690662587393, 1947.1529884500146, 1960.5196726802026, 62415.0, 64003.3599664002, 64188.0, 65403.0, 65628.67185664088, 10152.441615711059, 10165.44090075053, 10232.897671023195, 10297.227707921937, 10382.273240873066, 13236.205827650372, 13310.866891330963, 13321.800172997413, 13322.400491977884, 13367.131136476099, 7780.805479863048, 7929.920700793034, 7954.125046244909, 7961.243681850215, 8011.118776934649, 7963.4027447941635, 7967.0, 7994.800129996796, 8046.396520261003, 8063.072746634212, 4404.933925991113, 4425.291953287523, 4427.889302767392, 4430.667699922518, 4452.888677783017, 1706.2032808751526, 1899.9620007600047, 1920.9135588898532, 1985.9801401985794, 1987.5590197107272, 941.9623215071457, 978.7895602445508, 1004.1264100232698, 1093.9507722152523, 1106.1372129738816, 17270.309187632607, 17606.2077206526, 17867.642647146986, 18262.086895655164, 18286.908565457354, 935.3873213045466, 1021.97445063874, 1049.0139269087033, 1094.441834664327, 1133.727905302738, 9412.19996300322, 9708.6116555337, 9829.950850245703, 9833.852492212622, 9882.308238423386, 379723.0, 380591.0, 573428.0, 577284.0, 617710.0, 84019.0, 86429.0, 86827.56586217029, 88222.0, 88745.11254887498, 1162.110985096402, 1169.0764296206073, 1184.2775906696909, 1195.54569263681, 1201.7356181640025, 15913.283902224197, 16175.67648647044, 16199.75700364472, 16267.674646507241, 16721.832781672514, 36198.63801361953, 36817.26365472729, 37217.0, 37525.6247437533, 38009.23981520409, 50444.24333634925, 50660.493395065576, 51184.0, 54314.18528721993, 56013.71993140008, 9784.0, 10034.749131271778, 10091.596336146471, 10117.19062475015, 10190.388576685422, 11828.23116497425, 17104.743428848826, 17931.282748690428, 17991.0, 18562.628747424707, 14534.563963081124, 14842.628934276729, 15340.616484587974, 15433.151176685391, 15675.294611742727, 14938.77591836145, 14939.402423902922, 15008.774868376764, 15358.232088395313, 15582.22088895528, 14451.132932024113, 14530.85469145295, 15645.452409165624, 15657.37370505167, 15950.840491594936, 9949.303548751517, 9984.301098923006, 10045.095941365313, 10060.547275372479, 10063.49682515886, 10721.517531711248, 12198.634040978784, 14602.780958285422, 14767.409303627735, 15654.0], "unit": "ms", "name": "jquery.html", "value": 9206.14286147947}, {"lowerIsBetter": false, "replicates": [1529.8699610533106, 1597.744360902256, 1618.1342981504897, 1770.3361239535175, 1875.2030387085485, 7444.739434119807, 7864.8427031459405, 7866.842663146734, 7876.606169691507, 7877.0, 6834.487413443999, 7034.577925324485, 7268.164161121473, 7448.515846469975, 7674.347680447155, 2327.6392159215306, 2343.6836027136374, 2368.7275963264274, 2370.632551954441, 2371.762823717632, 2069.782672819355, 2322.756110608387, 2329.8136149108113, 2392.3899405651537, 2393.5811233034237, 2058.6397380458434, 2251.122062395667, 2303.0672577606097, 2304.873231972241, 2305.942351441219, 2272.4773302140516, 2279.5554866801062, 2281.441046943501, 2350.330155905576, 2355.9646605300936, 2260.886955652211, 2261.0729600863733, 2291.32405940248, 2291.9312420627407, 2337.8597284163006, 7723.7682869514, 7748.457607967444, 7775.805604859867, 7807.414443916729, 7824.765257042298, 4719.221328480831, 4806.399200099988, 4810.39870016248, 4841.975790121062, 4846.297286893416, 3108.207407111172, 3128.2335827722127, 3154.6056742907135, 3222.548843161959, 3229.1927018245437, 1119.127080876909, 1813.2656274208966, 1899.8480121590378, 1923.7883832778384, 1927.6241132979076, 10580.894191058067, 10680.412577308245, 10680.626178083807, 10689.412082335391, 10693.144548436107, 4209.831606735757, 4215.515215750228, 4243.490781106225, 4284.207421626947, 4321.632661223768, 8818.382713210014, 8847.823043539222, 8849.867251991225, 8852.336074794417, 8856.070112638208, 4778.5699287064335, 4784.067106914118, 4785.712857228577, 4821.614270858323, 4823.589994850407, 4409.9559004410185, 4466.419365482531, 4467.687261891637, 4469.0, 4469.709468884512, 3328.484084966834, 3364.8654053838063, 3390.2541440882965, 3392.9151771205916, 3421.8289085545625, 994.0506815990856, 1014.0974532665933, 1037.5953378182514, 1046.4767616191905, 1051.4900273367412, 1233.2600439736086, 1246.632243488155, 1257.0823298991604, 1491.7464031114737, 1492.0898252066231, 7437.256274372628, 7496.137944136385, 7639.274268944441, 7720.459567830198, 7721.884171737428, 1204.476052916984, 1220.6521141474636, 1224.2165014390796, 1224.8101544260476, 1234.2665876364244, 6028.4272994065495, 6078.483328917003, 6135.723892424851, 6138.324784273726, 6143.232095988002, 9142.542872856331, 9488.810223795486, 9515.524223788783, 9521.28590355726, 9533.0, 9323.627054917728, 9348.4858332791, 9381.202597779265, 9392.812143757086, 9408.247340212904, 6975.128108986377, 7168.211496735355, 7170.784876453715, 7172.0676312079095, 7176.461765367619, 7324.304191101837, 7426.702931882664, 7492.587907665131, 7507.924920750723, 7509.7371591994015, 8359.205875441823, 8406.873896891673, 8407.327413807003, 8506.489610623383, 8587.270082042847, 8558.186972237627, 8593.398462107592, 8670.089640587648, 8679.218870301704, 8694.391392602458, 7530.8493830122, 7621.657025433758, 7635.961820190863, 7638.0, 7642.5032372895585, 7644.312011918954, 7702.653380597774, 7725.111612164673, 7769.7669069928, 7771.844563108593, 5934.228550288435, 6988.196357418962, 7282.526635768658, 7364.594947277951, 7381.8154546136775, 7034.929650703632, 7109.0, 7142.107236595426, 7192.676329565287, 7271.272872712686, 7174.67713952863, 7182.712691492282, 7194.388476979515, 7274.054372931485, 7282.599457029914, 6727.327267273429, 6741.325867413219, 6743.696533656095, 6773.085633439423, 6778.627175505395, 6835.965820170867, 6840.179178498612, 6848.623325717133, 6862.828429289108, 6865.210500792473], "unit": "ms", "name": "mootools.html", "value": 4636.729451816416}, {"lowerIsBetter": false, "replicates": [345.80289235135984, 346.52526039326125, 350.8719317449132, 353.6216248613984, 354.3497681753982, 5896.793612223573, 6077.665728384937, 6097.664628445434, 6148.446639802417, 6159.0, 5894.646321220719, 5959.88080238395, 6038.245219347582, 6065.302490213627, 6070.484008859252, 4666.206744853366, 4680.836170734008, 4682.133805246023, 4686.297055441694, 4689.531046895318, 4483.5964763171305, 4566.223741963858, 4704.952950470486, 4709.270063140219, 4709.552592503706, 4562.224421848294, 4608.792604332813, 4611.469680986679, 4638.744869032202, 4650.162970665279, 4656.0, 4660.860174194746, 4666.416697912761, 4668.393108895856, 4669.112868554981, 4108.77401742904, 4109.958900411018, 4128.587141285878, 4128.772917489537, 4132.359484279941, 5695.088785794253, 5779.2775903012125, 5788.88422231553, 5795.565332600072, 5847.970760146214, 5344.706041167734, 5345.545628621572, 5354.0, 5358.3569971603265, 5360.249565060855, 6408.295087540367, 6425.807225783234, 6427.132337134473, 6434.742610295582, 6443.903341449882, 4308.67684923632, 4364.672649551265, 4397.450318710161, 4447.933281000787, 4466.798994045275, 6567.310432404624, 6614.437772789271, 6622.86754264912, 6623.602583844985, 6629.303923088103, 4566.429196350457, 4566.680332376768, 4566.70316429431, 4567.474740404896, 4571.9314210286875, 4554.612857907049, 4611.123886461562, 4623.9075218495445, 4627.814887404534, 4644.558766917137, 5354.732263386815, 5363.088274993261, 5364.195370694427, 5367.141257398797, 5375.247465354853, 6448.871022579522, 6451.419372256426, 6458.61248325102, 6466.159399278158, 6466.64433456155, 1620.8622267107191, 1621.7891674082293, 1622.7971503562055, 1624.4639269041188, 1624.8050233971999, 1065.7229120428744, 1066.925315227942, 1084.1164450972433, 1095.638439315024, 1132.1452303510962, 6300.495960323163, 6311.368863113744, 6315.36846315374, 6327.209098862642, 6327.905081423783, 6630.535862489676, 6637.1703537057865, 6637.767678131242, 6645.734170633121, 6676.165479315086, 6167.414095660905, 6323.494120470351, 6325.96837015812, 6328.145700330489, 6328.303886572473, 3583.5341405617623, 3608.8736894208578, 3610.2779444111284, 3612.6387361264187, 3615.6565126312958, 6851.0, 6856.451483881277, 6859.142607174103, 6860.451163906975, 6860.965695171592, 6630.602163870183, 6772.627505487147, 6780.694868730919, 6790.185177778698, 6808.2170550386345, 6725.495587830932, 6727.966360168266, 6741.426978706865, 6746.392824645805, 6757.662116894234, 6475.417212450714, 6605.372489613479, 6606.8017959461295, 6616.437602803623, 6646.60120392778, 6509.967450162719, 6546.378094081055, 6632.270450250565, 6642.4021838034805, 6644.302348253364, 6876.896846547406, 6914.101166848479, 6914.8617027660175, 6915.101036865175, 6920.861582768417, 4493.236149854467, 4554.20301447252, 4556.088782243498, 4561.908761824679, 4563.406757121553, 6663.300353463011, 6712.832179195364, 6715.731370745311, 6718.798436046926, 6721.529492935448, 6512.120863683342, 6538.084668146558, 6542.934570654233, 6548.214214294315, 6551.21385433751, 6306.936930630635, 6526.641034742945, 6529.183852018497, 6534.215894092739, 6536.084948107364, 6404.871902562016, 6434.678266086583, 6447.161868957006, 6447.774327898501, 6448.484121270193, 6555.180602424697, 6566.737330506918, 6573.3098024706715, 6575.868482630416, 6581.3747693969, 6238.220222472191, 6401.967990160019, 6406.935930640634, 6411.166548348871, 6412.35876412355], "unit": "ms", "name": "prototype.html", "value": 4872.219847040789}, {"lowerIsBetter": false, "replicates": [784.1766145547174, 854.5983387807729, 859.6217664227739, 864.7751584588008, 917.5504003038513, 13164.27596482193, 13218.53735119271, 13465.124766890145, 13466.259355735443, 14028.368723407446, 13131.0, 13171.934140329286, 13263.535776247809, 13269.336533173351, 13270.867291327084, 765.1583258415753, 778.4589710151447, 903.1871315815753, 927.9814403711922, 928.7167413938746, 913.8811954445946, 914.2457472585111, 920.6271460058687, 921.0099143420849, 921.230772305126, 962.9807403851919, 972.65470757881, 972.8978457261992, 972.9902700972971, 973.1679414100912, 955.498363359235, 959.8848138223458, 961.3366776923936, 962.1100482054072, 963.1812958984876, 712.5653351455608, 714.2286330762764, 714.5355518912696, 715.6850985566334, 716.2586722741937, 12808.167469114478, 12850.550230741972, 12861.485540578366, 12883.162594431333, 12930.612081637471, 12713.745725085446, 13163.86836131636, 13242.536511222157, 13341.86658133416, 13379.26414047227, 9933.850992235122, 10168.949155254177, 10234.590616375335, 10339.224558158168, 10367.32612380193, 3698.537682789651, 3787.450819631138, 3838.1747924196434, 3839.865604703822, 3844.1158533537164, 49723.0, 49920.500794991945, 49924.25113623298, 50224.24663630085, 50232.49767502315, 9696.321257511907, 9701.805963880683, 9850.901490985141, 9879.555420006118, 9882.160016398686, 20134.19463221484, 20165.09257083435, 20186.899065504873, 20215.191392344143, 20218.887961162276, 7522.887156692654, 7593.620318984029, 7721.227877212234, 7745.612719364122, 8016.679332826621, 4094.4267802507675, 4267.274563324242, 4277.615014648634, 4300.591443812833, 4302.333138363559, 3138.6076740407448, 3173.76196785242, 3179.0621766578915, 3179.8410079496393, 3183.8567264473154, 534.292063016497, 546.4644648244742, 552.8120439050742, 556.0824639345033, 564.5793883556777, 596.832886791699, 711.7331000874672, 720.8954701568243, 730.2223132364015, 733.6991833348302, 8353.164683531599, 8613.569321533898, 8652.610632521551, 8715.0, 8876.20114189713, 594.8066878264494, 698.5040621158932, 703.9084918960605, 729.4201110117469, 731.6561216228364, 3900.8829735107424, 4152.958470415258, 4219.6202341789385, 4276.786160691953, 4449.888752781207, 58184.0, 60891.39108608946, 62076.0, 62122.689386552775, 62195.06707399392, 53676.0, 53771.46228537743, 53840.19239711407, 54687.45312546903, 54922.0, 49733.50266497289, 49739.50260497421, 49760.25359619609, 50471.24293135606, 51185.23222151671, 8533.74398768038, 8823.823523529365, 8852.734417967471, 8987.0, 8990.955045224731, 36722.26555468929, 37276.440853387765, 37808.054798630255, 38222.235555288185, 38446.0, 44605.0, 45133.0, 48617.5138248613, 48750.0, 53320.0, 11229.27009744364, 11548.48031838586, 11817.054635629302, 11945.104117191071, 12091.758164836478, 12567.18313309632, 12740.171888827197, 12895.742085158057, 12913.09608327408, 13783.37974791157, 11874.465649045986, 11895.702607434538, 12054.879451205376, 12385.133040687066, 12435.813462798245, 10863.456827158769, 11036.613718520135, 11039.280968661846, 11848.185475839597, 12262.26426414418, 11858.16992810495, 11936.58221962227, 12149.878501215228, 12265.509379625071, 12295.9385203077, 9985.301028928196, 10266.691999240034, 10397.272190946562, 10469.528871200966, 10600.681979540626, 9828.31201815866, 9892.505374731378, 9937.155341796028, 9988.200943924614, 10045.447500387256], "unit": "ms", "name": "yui.html", "value": 6626.9386729053185}], "name": "dromaeo_css", "value": 7371.699265654664}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 105.0, 108.0, 112.0, 110.0, 99.0, 109.0, 109.0, 99.0], "unit": "ms", "name": "ai-astar", "value": 107.0}, {"lowerIsBetter": true, "replicates": [148.0, 127.0, 167.0, 161.0, 129.0, 142.0, 147.0, 122.0, 173.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.9}, {"lowerIsBetter": true, "replicates": [159.0, 162.0, 160.0, 160.0, 162.0, 162.0, 161.0, 160.0, 161.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.7}, {"lowerIsBetter": true, "replicates": [70.0, 71.0, 73.0, 156.0, 71.0, 72.0, 72.0, 71.0, 152.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 87.9}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 138.0, 92.0, 92.0, 92.0, 139.0, 92.0, 93.0, 134.0], "unit": "ms", "name": "audio-oscillator", "value": 105.7}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [147.0, 145.0, 145.0, 145.0, 145.0, 146.0, 146.0, 147.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [57.0, 57.0, 57.0, 56.0, 59.0, 57.0, 56.0, 56.0, 53.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 56.1}, {"lowerIsBetter": true, "replicates": [63.0, 62.0, 63.0, 64.0, 63.0, 63.0, 63.0, 63.0, 64.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.1}, {"lowerIsBetter": true, "replicates": [85.0, 75.0, 76.0, 79.0, 78.0, 78.0, 77.0, 77.0, 78.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.0}, {"lowerIsBetter": true, "replicates": [110.0, 119.0, 114.0, 114.0, 115.0, 118.0, 111.0, 110.0, 112.0, 118.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 114.1}, {"lowerIsBetter": true, "replicates": [135.0, 144.0, 151.0, 141.0, 141.0, 144.0, 138.0, 133.0, 141.0, 130.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 139.8}, {"lowerIsBetter": true, "replicates": [64.0, 63.0, 65.0, 64.0, 67.0, 63.0, 69.0, 64.0, 64.0, 70.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.3}], "name": "kraken", "value": 1471.7}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [171.7032967032967], "unit": "score", "name": "Richards", "value": 205.598848}, {"lowerIsBetter": false, "replicates": [164.6090534979424], "unit": "score", "name": "DeltaBlue", "value": 401.66685000000007}, {"lowerIsBetter": false, "replicates": [279.4857462269424], "unit": "score", "name": "Encrypt", "value": 952.3956179999999}, {"lowerIsBetter": false, "replicates": [5175.257731958763], "unit": "score", "name": "Decrypt", "value": 51.433380478087685}, {"lowerIsBetter": false, "replicates": [941.6195856873823], "unit": "score", "name": "RayTrace", "value": 785.8683179999997}, {"lowerIsBetter": false, "replicates": [539.9568034557235], "unit": "score", "name": "Earley", "value": 1234.2894759999995}, {"lowerIsBetter": false, "replicates": [10956.521739130434], "unit": "score", "name": "Boyer", "value": 60.82797222222217}, {"lowerIsBetter": false, "replicates": [24047.619047619046], "unit": "score", "name": "RegExp", "value": 37.88254455445545}, {"lowerIsBetter": false, "replicates": [518.595041322314], "unit": "score", "name": "Splay", "value": 157.13802390438244}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23214.208635696996}]} 08:52:07 INFO - RETURN: dromaeo_css: 7371.70 08:52:07 INFO - RETURN: kraken: 1471.70 08:52:07 INFO - RETURN: v8_7: 23214.21 08:52:07 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23214.21"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1471.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7371.70"}}} 08:52:07 INFO - Return code: 0 08:52:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 08:52:07 INFO - # TBPL SUCCESS # 08:52:07 INFO - Running post-action listener: _resource_record_post_action 08:52:07 INFO - Running post-run listener: _resource_record_post_run 08:52:08 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 0; Write bytes: 499638272; Read time: 0; Write time: 391932 08:52:08 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 235536384; Read time: 0; Write time: 246552 08:52:08 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 0; Write bytes: 264101888; Read time: 0; Write time: 145380 08:52:08 INFO - Running post-run listener: _upload_blobber_files 08:52:08 INFO - Blob upload gear active. 08:52:08 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 08:52:08 INFO - Copying logs to upload dir... 08:52:08 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1265.842132 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-02-01 08:52:07.624991) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-01 08:52:07.628690) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009986 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454335467/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-01 08:52:07.678830) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:52:07.679111) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1454344253.810711-1142473865 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004546 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 08:52:07.728877) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-01 08:52:07.729161) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-01 08:52:07.729457) ========= ========= Total master_lag: 0.29 =========