builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-024 starttime: 1456767036.8 results: success (0) buildid: 20160229070520 builduid: 998bbf553b3f4bae9fd2ce8257cb62ad revision: 48e0d09b052c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:36.803506) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:36.803934) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:36.804216) ========= 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=4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006232 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:36.826211) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:36.826473) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:36.840014) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:36.840267) ========= 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=4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004613 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:36.889435) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:36.889744) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:36.890107) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:36.890378) ========= 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=4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-29 09:30:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 9.38M=0.001s 2016-02-29 09:30:37 (9.38 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109878 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:37.012330) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:37.012647) ========= 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=4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020595 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:37.062151) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-29 09:30:37.062461) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 48e0d09b052c --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 48e0d09b052c --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=4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-29 09:30:37,224 Setting DEBUG logging. 2016-02-29 09:30:37,224 attempt 1/10 2016-02-29 09:30:37,224 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/48e0d09b052c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-29 09:30:38,058 unpacking tar archive at: mozilla-beta-48e0d09b052c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.150943 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-29 09:30:38.225839) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:38.226184) ========= echo 48e0d09b052c in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'48e0d09b052c'] 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=4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 48e0d09b052c program finished with exit code 0 elapsedTime=0.004088 script_repo_revision: '48e0d09b052c' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:38.275183) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:38.275556) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-29 09:30:38.285958) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-02-29 09:30:38.286328) ========= /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=4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:30:38 INFO - MultiFileLogger online at 20160229 09:30:38 in /builds/slave/test-pgo 09:30:38 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 09:30:38 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:30:38 INFO - {'append_to_log': False, 09:30:38 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:30:38 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:30:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:30:38 INFO - 'branch': 'Mozilla-Beta', 09:30:38 INFO - 'buildbot_json_path': 'buildprops.json', 09:30:38 INFO - 'config_files': ('talos/linux_config.py',), 09:30:38 INFO - 'default_actions': ('clobber', 09:30:38 INFO - 'read-buildbot-config', 09:30:38 INFO - 'download-and-extract', 09:30:38 INFO - 'populate-webroot', 09:30:38 INFO - 'create-virtualenv', 09:30:38 INFO - 'install', 09:30:38 INFO - 'run-tests'), 09:30:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:30:38 INFO - 'download_minidump_stackwalk': True, 09:30:38 INFO - 'download_symbols': 'ondemand', 09:30:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:30:38 INFO - 'tooltool.py': '/tools/tooltool.py', 09:30:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:30:38 INFO - '/tools/misc-python/virtualenv.py')}, 09:30:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:30:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:30:38 INFO - 'installer_path': 'installer.exe', 09:30:38 INFO - 'log_level': 'info', 09:30:38 INFO - 'log_name': 'talos', 09:30:38 INFO - 'log_to_console': True, 09:30:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:30:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:30:38 INFO - 'opt_config_files': (), 09:30:38 INFO - 'pip_index': False, 09:30:38 INFO - 'sps_profile': False, 09:30:38 INFO - 'sps_profile_interval': 0, 09:30:38 INFO - 'suite': 'dromaeojs', 09:30:38 INFO - 'system_bits': '32', 09:30:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:30:38 INFO - 'title': 'talos-linux64-ix-024', 09:30:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:30:38 INFO - 'use_talos_json': True, 09:30:38 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:30:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:30:38 INFO - 'work_dir': 'build'} 09:30:38 INFO - ##### 09:30:38 INFO - ##### Running clobber step. 09:30:38 INFO - ##### 09:30:38 INFO - Running pre-action listener: _resource_record_pre_action 09:30:38 INFO - Running main action method: clobber 09:30:38 INFO - rmtree: /builds/slave/test-pgo/build 09:30:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:30:39 INFO - Running post-action listener: _resource_record_post_action 09:30:39 INFO - ##### 09:30:39 INFO - ##### Running read-buildbot-config step. 09:30:39 INFO - ##### 09:30:39 INFO - Running pre-action listener: _resource_record_pre_action 09:30:39 INFO - Running main action method: read_buildbot_config 09:30:39 INFO - Using buildbot properties: 09:30:39 INFO - { 09:30:39 INFO - "properties": { 09:30:39 INFO - "buildnumber": 138, 09:30:39 INFO - "product": "firefox", 09:30:39 INFO - "script_repo_revision": "production", 09:30:39 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:30:39 INFO - "repository": "", 09:30:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:30:39 INFO - "buildid": "20160229070520", 09:30:39 INFO - "slavename": "talos-linux64-ix-024", 09:30:39 INFO - "pgo_build": "True", 09:30:39 INFO - "basedir": "/builds/slave/test-pgo", 09:30:39 INFO - "project": "", 09:30:39 INFO - "platform": "ubuntu64_hw", 09:30:39 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:30:39 INFO - "slavebuilddir": "test", 09:30:39 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:30:39 INFO - "branch": "mozilla-beta", 09:30:39 INFO - "repo_path": "releases/mozilla-beta", 09:30:39 INFO - "stage_platform": "linux64-pgo", 09:30:39 INFO - "builduid": "998bbf553b3f4bae9fd2ce8257cb62ad", 09:30:39 INFO - "revision": "48e0d09b052c" 09:30:39 INFO - }, 09:30:39 INFO - "sourcestamp": { 09:30:39 INFO - "repository": "", 09:30:39 INFO - "hasPatch": false, 09:30:39 INFO - "project": "", 09:30:39 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:30:39 INFO - "changes": [ 09:30:39 INFO - { 09:30:39 INFO - "category": null, 09:30:39 INFO - "files": [ 09:30:39 INFO - { 09:30:39 INFO - "url": null, 09:30:39 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/firefox-45.0.en-US.linux-x86_64.tar.bz2" 09:30:39 INFO - } 09:30:39 INFO - ], 09:30:39 INFO - "repository": "", 09:30:39 INFO - "rev": "48e0d09b052c", 09:30:39 INFO - "who": "sendchange", 09:30:39 INFO - "when": 1456766748, 09:30:39 INFO - "number": 7669445, 09:30:39 INFO - "comments": "Bug 1249659 - Disable accelerated Canvas on OS X versions _ 10.8. r=milan a=sylvestre", 09:30:39 INFO - "project": "", 09:30:39 INFO - "at": "Mon 29 Feb 2016 09:25:48", 09:30:39 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:30:39 INFO - "revlink": "", 09:30:39 INFO - "properties": [ 09:30:39 INFO - [ 09:30:39 INFO - "buildid", 09:30:39 INFO - "20160229070520", 09:30:39 INFO - "Change" 09:30:39 INFO - ], 09:30:39 INFO - [ 09:30:39 INFO - "builduid", 09:30:39 INFO - "998bbf553b3f4bae9fd2ce8257cb62ad", 09:30:39 INFO - "Change" 09:30:39 INFO - ], 09:30:39 INFO - [ 09:30:39 INFO - "pgo_build", 09:30:39 INFO - "True", 09:30:39 INFO - "Change" 09:30:39 INFO - ] 09:30:39 INFO - ], 09:30:39 INFO - "revision": "48e0d09b052c" 09:30:39 INFO - } 09:30:39 INFO - ], 09:30:39 INFO - "revision": "48e0d09b052c" 09:30:39 INFO - } 09:30:39 INFO - } 09:30:39 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/firefox-45.0.en-US.linux-x86_64.tar.bz2. 09:30:39 INFO - Running post-action listener: _resource_record_post_action 09:30:39 INFO - ##### 09:30:39 INFO - ##### Running download-and-extract step. 09:30:39 INFO - ##### 09:30:39 INFO - Running pre-action listener: _resource_record_pre_action 09:30:39 INFO - Running main action method: download_and_extract 09:30:39 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:30:39 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')]} 09:30:39 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/test_packages.json 09:30:39 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:30:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:30:39 INFO - Downloaded 1270 bytes. 09:30:39 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:30:39 INFO - Using the following test package requirements: 09:30:39 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:30:39 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:30:39 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:30:39 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:30:39 INFO - u'jsshell-linux-x86_64.zip'], 09:30:39 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:30:39 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:30:39 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:30:39 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:30:39 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 09:30:39 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:30:39 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 09:30:39 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:30:39 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:30:39 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:30:39 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:30:39 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:30:39 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:30:39 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')]} 09:30:39 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:30:39 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/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 09:30:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/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 09:30:40 INFO - Downloaded 21584465 bytes. 09:30:40 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 09:30:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:30:40 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 09:30:41 INFO - Return code: 0 09:30:41 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 09:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:41 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:30:41 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/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 09:30:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/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 09:30:41 INFO - Downloaded 21584465 bytes. 09:30:41 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 09:30:41 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:30:41 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 09:30:43 INFO - Return code: 0 09:30:43 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')]} 09:30:43 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:30:43 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/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 09:30:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/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 09:30:43 INFO - Downloaded 11132038 bytes. 09:30:43 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 09:30:43 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:30:43 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 09:30:43 INFO - Return code: 0 09:30:43 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')]} 09:30:43 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:30:43 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/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 09:30:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/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 09:30:44 INFO - Downloaded 57942031 bytes. 09:30:44 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:30:44 INFO - mkdir: /builds/slave/test-pgo/properties 09:30:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:30:44 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:30:44 INFO - Contents: 09:30:44 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:30:44 INFO - Running post-action listener: _resource_record_post_action 09:30:44 INFO - Running post-action listener: set_extra_try_arguments 09:30:44 INFO - ##### 09:30:44 INFO - ##### Running populate-webroot step. 09:30:44 INFO - ##### 09:30:44 INFO - Running pre-action listener: _resource_record_pre_action 09:30:44 INFO - Running main action method: populate_webroot 09:30:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:30:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:30:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:30:44 INFO - '--tppagecycles', 09:30:44 INFO - '7'], 09:30:44 INFO - 'tests': ['tsvgm']}}, 09:30:44 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:30:44 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:30:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:30:44 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:30:44 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:30:44 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:30:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:30:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:44 INFO - 'talos_options': ['--e10s'], 09:30:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:30:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:44 INFO - 'tests': ['damp', 'tps']}, 09:30:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:44 INFO - 'talos_options': ['--e10s'], 09:30:44 INFO - 'tests': ['damp', 'tps']}, 09:30:44 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:30:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:30:44 INFO - 'other': {'tests': ['a11yr', 09:30:44 INFO - 'ts_paint', 09:30:44 INFO - 'tpaint', 09:30:44 INFO - 'sessionrestore', 09:30:44 INFO - 'sessionrestore_no_auto_restore']}, 09:30:44 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:30:44 INFO - 'tests': ['a11yr', 09:30:44 INFO - 'ts_paint', 09:30:44 INFO - 'tpaint', 09:30:44 INFO - 'sessionrestore', 09:30:44 INFO - 'sessionrestore_no_auto_restore']}, 09:30:44 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:30:44 INFO - 'tests': ['a11yr', 09:30:44 INFO - 'ts_paint', 09:30:44 INFO - 'tpaint', 09:30:44 INFO - 'sessionrestore', 09:30:44 INFO - 'sessionrestore_no_auto_restore']}, 09:30:44 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:30:44 INFO - 'tests': ['a11yr', 09:30:44 INFO - 'ts_paint', 09:30:44 INFO - 'tpaint', 09:30:44 INFO - 'sessionrestore', 09:30:44 INFO - 'sessionrestore_no_auto_restore']}, 09:30:44 INFO - 'other_l64': {'tests': ['a11yr', 09:30:44 INFO - 'ts_paint', 09:30:44 INFO - 'tpaint', 09:30:44 INFO - 'sessionrestore', 09:30:44 INFO - 'sessionrestore_no_auto_restore']}, 09:30:44 INFO - 'other_nol64': {'tests': ['a11yr', 09:30:44 INFO - 'ts_paint', 09:30:44 INFO - 'tpaint', 09:30:44 INFO - 'sessionrestore', 09:30:44 INFO - 'sessionrestore_no_auto_restore']}, 09:30:44 INFO - 'svgr': {'tests': ['tsvgx', 09:30:44 INFO - 'tsvgr_opacity', 09:30:44 INFO - 'tart', 09:30:44 INFO - 'tscrollx', 09:30:44 INFO - 'cart']}, 09:30:44 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:30:44 INFO - 'tests': ['tsvgx', 09:30:44 INFO - 'tsvgr_opacity', 09:30:44 INFO - 'tart', 09:30:44 INFO - 'tscrollx', 09:30:44 INFO - 'cart']}, 09:30:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:44 INFO - 'tests': ['tp5o']}, 09:30:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:44 INFO - 'talos_options': ['--e10s'], 09:30:44 INFO - 'tests': ['tp5o']}, 09:30:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:44 INFO - 'talos_options': ['--xperf_path', 09:30:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:30:44 INFO - 'tests': ['tp5n']}, 09:30:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:44 INFO - 'talos_options': ['--e10s', 09:30:44 INFO - '--xperf_path', 09:30:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:30:44 INFO - 'tests': ['tp5n']}}, 09:30:44 INFO - 'talos.zip': {'path': '', 09:30:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:30:44 INFO - Running post-action listener: _resource_record_post_action 09:30:44 INFO - ##### 09:30:44 INFO - ##### Running create-virtualenv step. 09:30:44 INFO - ##### 09:30:44 INFO - Running pre-action listener: _resource_record_pre_action 09:30:44 INFO - Running main action method: create_virtualenv 09:30:44 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:30:44 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 09:30:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:30:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:30:44 INFO - Using real prefix '/usr' 09:30:44 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:30:45 INFO - Installing distribute.............................................................................................................................................................................................done. 09:30:47 INFO - Installing pip.................done. 09:30:47 INFO - Return code: 0 09:30:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:47 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 0x7f48918d4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc350>, '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 0x19cd250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19cd6e0>, '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': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', '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 09:30:47 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 09:30:47 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 09:30:47 INFO - Using env: {'DISPLAY': ':0', 09:30:47 INFO - 'HOME': '/home/cltbld', 09:30:47 INFO - 'LANG': 'en_US.UTF-8', 09:30:47 INFO - 'LANGUAGE': 'en_US:en', 09:30:47 INFO - 'LOGNAME': 'cltbld', 09:30:47 INFO - 'MAIL': '/var/mail/cltbld', 09:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:47 INFO - 'MOZ_NO_REMOTE': '1', 09:30:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:47 INFO - 'NO_EM_RESTART': '1', 09:30:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:30:47 INFO - 'PWD': '/builds/slave/test-pgo', 09:30:47 INFO - 'SHELL': '/bin/bash', 09:30:47 INFO - 'SHLVL': '1', 09:30:47 INFO - 'TERM': 'linux', 09:30:47 INFO - 'TMOUT': '86400', 09:30:47 INFO - 'USER': 'cltbld', 09:30:47 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', 09:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:47 INFO - '_': '/tools/buildbot/bin/python'} 09:30:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:47 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 09:30:47 INFO - Cleaning up... 09:30:47 INFO - Return code: 0 09:30:47 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:47 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 0x7f48918d4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc350>, '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 0x19cd250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19cd6e0>, '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': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', '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 09:30:47 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 09:30:47 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 09:30:47 INFO - Using env: {'DISPLAY': ':0', 09:30:47 INFO - 'HOME': '/home/cltbld', 09:30:47 INFO - 'LANG': 'en_US.UTF-8', 09:30:47 INFO - 'LANGUAGE': 'en_US:en', 09:30:47 INFO - 'LOGNAME': 'cltbld', 09:30:47 INFO - 'MAIL': '/var/mail/cltbld', 09:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:47 INFO - 'MOZ_NO_REMOTE': '1', 09:30:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:47 INFO - 'NO_EM_RESTART': '1', 09:30:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:30:47 INFO - 'PWD': '/builds/slave/test-pgo', 09:30:47 INFO - 'SHELL': '/bin/bash', 09:30:47 INFO - 'SHLVL': '1', 09:30:47 INFO - 'TERM': 'linux', 09:30:47 INFO - 'TMOUT': '86400', 09:30:47 INFO - 'USER': 'cltbld', 09:30:47 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', 09:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:47 INFO - '_': '/tools/buildbot/bin/python'} 09:30:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:47 INFO - Downloading/unpacking psutil>=0.7.1 09:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:30:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:49 INFO - Installing collected packages: psutil 09:30:49 INFO - Running setup.py install for psutil 09:30:49 INFO - building 'psutil._psutil_linux' extension 09:30:49 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 09:30:49 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 09:30:49 INFO - building 'psutil._psutil_posix' extension 09:30:49 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 09:30:50 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 09:30:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:50 INFO - Successfully installed psutil 09:30:50 INFO - Cleaning up... 09:30:50 INFO - Return code: 0 09:30:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:30:50 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')]} 09:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:50 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')]} 09:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:50 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 0x7f48918d4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc350>, '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 0x19cd250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19cd6e0>, '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': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', '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 09:30:50 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 09:30:50 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 09:30:50 INFO - Using env: {'DISPLAY': ':0', 09:30:50 INFO - 'HOME': '/home/cltbld', 09:30:50 INFO - 'LANG': 'en_US.UTF-8', 09:30:50 INFO - 'LANGUAGE': 'en_US:en', 09:30:50 INFO - 'LOGNAME': 'cltbld', 09:30:50 INFO - 'MAIL': '/var/mail/cltbld', 09:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:50 INFO - 'MOZ_NO_REMOTE': '1', 09:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:50 INFO - 'NO_EM_RESTART': '1', 09:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:30:50 INFO - 'PWD': '/builds/slave/test-pgo', 09:30:50 INFO - 'SHELL': '/bin/bash', 09:30:50 INFO - 'SHLVL': '1', 09:30:50 INFO - 'TERM': 'linux', 09:30:50 INFO - 'TMOUT': '86400', 09:30:50 INFO - 'USER': 'cltbld', 09:30:50 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', 09:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:50 INFO - '_': '/tools/buildbot/bin/python'} 09:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:30:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:30:52 INFO - Installing collected packages: mozsystemmonitor 09:30:52 INFO - Running setup.py install for mozsystemmonitor 09:30:52 INFO - Successfully installed mozsystemmonitor 09:30:52 INFO - Cleaning up... 09:30:52 INFO - Return code: 0 09:30:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:30:52 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')]} 09:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:52 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')]} 09:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:52 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 0x7f48918d4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc350>, '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 0x19cd250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19cd6e0>, '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': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', '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 09:30:52 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 09:30:52 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 09:30:52 INFO - Using env: {'DISPLAY': ':0', 09:30:52 INFO - 'HOME': '/home/cltbld', 09:30:52 INFO - 'LANG': 'en_US.UTF-8', 09:30:52 INFO - 'LANGUAGE': 'en_US:en', 09:30:52 INFO - 'LOGNAME': 'cltbld', 09:30:52 INFO - 'MAIL': '/var/mail/cltbld', 09:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:52 INFO - 'MOZ_NO_REMOTE': '1', 09:30:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:52 INFO - 'NO_EM_RESTART': '1', 09:30:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:30:52 INFO - 'PWD': '/builds/slave/test-pgo', 09:30:52 INFO - 'SHELL': '/bin/bash', 09:30:52 INFO - 'SHLVL': '1', 09:30:52 INFO - 'TERM': 'linux', 09:30:52 INFO - 'TMOUT': '86400', 09:30:52 INFO - 'USER': 'cltbld', 09:30:52 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', 09:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:52 INFO - '_': '/tools/buildbot/bin/python'} 09:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:52 INFO - Downloading/unpacking blobuploader==1.2.4 09:30:52 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 09:30:52 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 09:30:52 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 09:30:52 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 09:30:52 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 09:30:52 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 09:30:54 INFO - Downloading blobuploader-1.2.4.tar.gz 09:30:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:30:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:30:54 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 09:30:54 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 09:30:54 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 09:30:54 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 09:30:54 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 09:30:54 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 09:30:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:30:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:30:54 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 09:30:54 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 09:30:54 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 09:30:54 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 09:30:54 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 09:30:54 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 09:30:55 INFO - Downloading docopt-0.6.1.tar.gz 09:30:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:30:55 INFO - Installing collected packages: blobuploader, requests, docopt 09:30:55 INFO - Running setup.py install for blobuploader 09:30:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:30:55 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:30:55 INFO - Running setup.py install for requests 09:30:55 INFO - Running setup.py install for docopt 09:30:55 INFO - Successfully installed blobuploader requests docopt 09:30:55 INFO - Cleaning up... 09:30:55 INFO - Return code: 0 09:30:55 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:55 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 0x7f48918d4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc350>, '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 0x19cd250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19cd6e0>, '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': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', '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 09:30:55 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 09:30:55 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 09:30:55 INFO - Using env: {'DISPLAY': ':0', 09:30:55 INFO - 'HOME': '/home/cltbld', 09:30:55 INFO - 'LANG': 'en_US.UTF-8', 09:30:55 INFO - 'LANGUAGE': 'en_US:en', 09:30:55 INFO - 'LOGNAME': 'cltbld', 09:30:55 INFO - 'MAIL': '/var/mail/cltbld', 09:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:55 INFO - 'MOZ_NO_REMOTE': '1', 09:30:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:55 INFO - 'NO_EM_RESTART': '1', 09:30:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:30:55 INFO - 'PWD': '/builds/slave/test-pgo', 09:30:55 INFO - 'SHELL': '/bin/bash', 09:30:55 INFO - 'SHLVL': '1', 09:30:55 INFO - 'TERM': 'linux', 09:30:55 INFO - 'TMOUT': '86400', 09:30:55 INFO - 'USER': 'cltbld', 09:30:55 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', 09:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:55 INFO - '_': '/tools/buildbot/bin/python'} 09:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:30:56 INFO - Running setup.py (path:/tmp/pip-mDgcln-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:30:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:30:56 INFO - Running setup.py (path:/tmp/pip-h4rVFm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:30:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:30:56 INFO - Running setup.py (path:/tmp/pip-uIWNNf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:30:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:30:56 INFO - Running setup.py (path:/tmp/pip-DWzTuV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:30:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:30:56 INFO - Running setup.py (path:/tmp/pip-WJSkVM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:30:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:30:56 INFO - Running setup.py (path:/tmp/pip-G06TTi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:30:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:30:56 INFO - Running setup.py (path:/tmp/pip-hdGd8a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:30:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:30:56 INFO - Running setup.py (path:/tmp/pip-hHXDwz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:30:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:30:56 INFO - Running setup.py (path:/tmp/pip-MArJga-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:30:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:30:56 INFO - Running setup.py (path:/tmp/pip-VFVKjm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:30:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:30:56 INFO - Running setup.py (path:/tmp/pip-SnSkAz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:30:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:30:56 INFO - Running setup.py (path:/tmp/pip-xBmjN6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:30:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:30:56 INFO - Running setup.py (path:/tmp/pip-TlARXd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:30:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:30:56 INFO - Running setup.py (path:/tmp/pip-oJMygL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:30:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:30:57 INFO - Running setup.py (path:/tmp/pip-ImOa7v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:30:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:30:57 INFO - Running setup.py (path:/tmp/pip-6QN5qx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:30:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:30:57 INFO - Running setup.py (path:/tmp/pip-R1zNbZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:30:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:30:57 INFO - Running setup.py install for manifestparser 09:30:57 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:30:57 INFO - Running setup.py install for mozcrash 09:30:57 INFO - Running setup.py install for mozdebug 09:30:57 INFO - Running setup.py install for mozdevice 09:30:57 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:30:57 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:30:57 INFO - Running setup.py install for mozfile 09:30:57 INFO - Running setup.py install for mozhttpd 09:30:57 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:30:57 INFO - Running setup.py install for mozinfo 09:30:57 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:30:57 INFO - Running setup.py install for mozInstall 09:30:58 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:30:58 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:30:58 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:30:58 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:30:58 INFO - Running setup.py install for mozleak 09:30:58 INFO - Running setup.py install for mozlog 09:30:58 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:30:58 INFO - Running setup.py install for moznetwork 09:30:58 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:30:58 INFO - Running setup.py install for mozprocess 09:30:58 INFO - Running setup.py install for mozprofile 09:30:58 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:30:58 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:30:58 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:30:58 INFO - Running setup.py install for mozrunner 09:30:58 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:30:58 INFO - Running setup.py install for mozscreenshot 09:30:58 INFO - Running setup.py install for moztest 09:30:58 INFO - Running setup.py install for mozversion 09:30:58 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:30:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:30:59 INFO - Cleaning up... 09:30:59 INFO - Return code: 0 09:30:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:59 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 0x7f48918d4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc350>, '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 0x19cd250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19cd6e0>, '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': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', '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 09:30:59 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 09:30:59 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 09:30:59 INFO - Using env: {'DISPLAY': ':0', 09:30:59 INFO - 'HOME': '/home/cltbld', 09:30:59 INFO - 'LANG': 'en_US.UTF-8', 09:30:59 INFO - 'LANGUAGE': 'en_US:en', 09:30:59 INFO - 'LOGNAME': 'cltbld', 09:30:59 INFO - 'MAIL': '/var/mail/cltbld', 09:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:59 INFO - 'MOZ_NO_REMOTE': '1', 09:30:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:59 INFO - 'NO_EM_RESTART': '1', 09:30:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:30:59 INFO - 'PWD': '/builds/slave/test-pgo', 09:30:59 INFO - 'SHELL': '/bin/bash', 09:30:59 INFO - 'SHLVL': '1', 09:30:59 INFO - 'TERM': 'linux', 09:30:59 INFO - 'TMOUT': '86400', 09:30:59 INFO - 'USER': 'cltbld', 09:30:59 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', 09:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:59 INFO - '_': '/tools/buildbot/bin/python'} 09:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:30:59 INFO - Running setup.py (path:/tmp/pip-0tP_9_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:30:59 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)) 09:30:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:30:59 INFO - Running setup.py (path:/tmp/pip-hSgHye-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:30:59 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)) 09:30:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:30:59 INFO - Running setup.py (path:/tmp/pip-mqysO_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:30:59 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)) 09:30:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:30:59 INFO - Running setup.py (path:/tmp/pip-Urz17v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:30:59 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)) 09:30:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:30:59 INFO - Running setup.py (path:/tmp/pip-iJpRKR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:30:59 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)) 09:30:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:30:59 INFO - Running setup.py (path:/tmp/pip-5WAXxq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:30:59 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)) 09:30:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:30:59 INFO - Running setup.py (path:/tmp/pip-CWwjOH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:30:59 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)) 09:30:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:30:59 INFO - Running setup.py (path:/tmp/pip-E2eXkQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:30:59 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)) 09:30:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:30:59 INFO - Running setup.py (path:/tmp/pip-td_OTQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:30:59 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)) 09:30:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:30:59 INFO - Running setup.py (path:/tmp/pip-zxFoi6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:30:59 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)) 09:30:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:30:59 INFO - Running setup.py (path:/tmp/pip-0_X2fU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:31:00 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)) 09:31:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:31:00 INFO - Running setup.py (path:/tmp/pip-71Ou0p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:31:00 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)) 09:31:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:31:00 INFO - Running setup.py (path:/tmp/pip-8CfYwr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:31:00 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)) 09:31:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:31:00 INFO - Running setup.py (path:/tmp/pip-wq8Y0f-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:31:00 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)) 09:31:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:31:00 INFO - Running setup.py (path:/tmp/pip-dlekcl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:31:00 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)) 09:31:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:31:00 INFO - Running setup.py (path:/tmp/pip-sKAMSr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:31:00 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)) 09:31:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:31:00 INFO - Running setup.py (path:/tmp/pip-npBD0k-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:31:00 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)) 09:31:00 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)) 09:31:00 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)) 09:31:00 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)) 09:31:00 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)) 09:31:00 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)) 09:31:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:31:00 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 09:31:00 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 09:31:00 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 09:31:00 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 09:31:00 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 09:31:00 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 09:31:02 INFO - Downloading blessings-1.6.tar.gz 09:31:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:31:02 INFO - Installing collected packages: blessings 09:31:02 INFO - Running setup.py install for blessings 09:31:02 INFO - Successfully installed blessings 09:31:02 INFO - Cleaning up... 09:31:02 INFO - Return code: 0 09:31:02 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:31:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:31:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:31:02 INFO - Reading from file tmpfile_stdout 09:31:02 INFO - Current package versions: 09:31:02 INFO - argparse == 1.2.1 09:31:02 INFO - blessings == 1.6 09:31:02 INFO - blobuploader == 1.2.4 09:31:02 INFO - docopt == 0.6.1 09:31:02 INFO - manifestparser == 1.1 09:31:02 INFO - mozInstall == 1.12 09:31:02 INFO - mozcrash == 0.16 09:31:02 INFO - mozdebug == 0.1 09:31:02 INFO - mozdevice == 0.47 09:31:02 INFO - mozfile == 1.2 09:31:02 INFO - mozhttpd == 0.7 09:31:02 INFO - mozinfo == 0.9 09:31:02 INFO - mozleak == 0.1 09:31:02 INFO - mozlog == 3.1 09:31:02 INFO - moznetwork == 0.27 09:31:02 INFO - mozprocess == 0.22 09:31:02 INFO - mozprofile == 0.27 09:31:02 INFO - mozrunner == 6.11 09:31:02 INFO - mozscreenshot == 0.1 09:31:02 INFO - mozsystemmonitor == 0.0 09:31:02 INFO - moztest == 0.7 09:31:02 INFO - mozversion == 1.4 09:31:02 INFO - psutil == 3.1.1 09:31:02 INFO - requests == 1.2.3 09:31:02 INFO - wsgiref == 0.1.2 09:31:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:31:02 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')]} 09:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:02 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')]} 09:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:02 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 0x7f48918d4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc350>, '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 0x19cd250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19cd6e0>, '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': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', '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 09:31:02 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 09:31:02 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 09:31:02 INFO - Using env: {'DISPLAY': ':0', 09:31:02 INFO - 'HOME': '/home/cltbld', 09:31:02 INFO - 'LANG': 'en_US.UTF-8', 09:31:02 INFO - 'LANGUAGE': 'en_US:en', 09:31:02 INFO - 'LOGNAME': 'cltbld', 09:31:02 INFO - 'MAIL': '/var/mail/cltbld', 09:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:02 INFO - 'MOZ_NO_REMOTE': '1', 09:31:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:02 INFO - 'NO_EM_RESTART': '1', 09:31:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:31:02 INFO - 'PWD': '/builds/slave/test-pgo', 09:31:02 INFO - 'SHELL': '/bin/bash', 09:31:02 INFO - 'SHLVL': '1', 09:31:02 INFO - 'TERM': 'linux', 09:31:02 INFO - 'TMOUT': '86400', 09:31:02 INFO - 'USER': 'cltbld', 09:31:02 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', 09:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:02 INFO - '_': '/tools/buildbot/bin/python'} 09:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:02 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)) 09:31:02 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)) 09:31:02 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)) 09:31:02 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)) 09:31:02 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)) 09:31:02 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)) 09:31:02 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)) 09:31:02 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)) 09:31:02 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)) 09:31:02 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)) 09:31:02 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)) 09:31:02 INFO - Cleaning up... 09:31:02 INFO - Return code: 0 09:31:02 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:31:02 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')]} 09:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:02 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')]} 09:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:02 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 0x7f48918d4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc350>, '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 0x19cd250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19cd6e0>, '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': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', '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 09:31:02 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 09:31:02 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 09:31:02 INFO - Using env: {'DISPLAY': ':0', 09:31:02 INFO - 'HOME': '/home/cltbld', 09:31:02 INFO - 'LANG': 'en_US.UTF-8', 09:31:02 INFO - 'LANGUAGE': 'en_US:en', 09:31:02 INFO - 'LOGNAME': 'cltbld', 09:31:02 INFO - 'MAIL': '/var/mail/cltbld', 09:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:02 INFO - 'MOZ_NO_REMOTE': '1', 09:31:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:02 INFO - 'NO_EM_RESTART': '1', 09:31:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:31:02 INFO - 'PWD': '/builds/slave/test-pgo', 09:31:02 INFO - 'SHELL': '/bin/bash', 09:31:02 INFO - 'SHLVL': '1', 09:31:02 INFO - 'TERM': 'linux', 09:31:02 INFO - 'TMOUT': '86400', 09:31:02 INFO - 'USER': 'cltbld', 09:31:02 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', 09:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:02 INFO - '_': '/tools/buildbot/bin/python'} 09:31:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:03 INFO - Downloading/unpacking jsonschema 09:31:03 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 09:31:03 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 09:31:03 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 09:31:03 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 09:31:03 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 09:31:03 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 09:31:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:31:04 INFO - Downloading/unpacking functools32 (from jsonschema) 09:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:05 INFO - Downloading functools32-3.2.3-2.tar.gz 09:31:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:31:05 INFO - warning: no files found matching '*.txt' 09:31:05 INFO - no previously-included directories found matching 'build' 09:31:05 INFO - no previously-included directories found matching 'dist' 09:31:05 INFO - no previously-included directories found matching '.git*' 09:31:05 INFO - Installing collected packages: jsonschema, functools32 09:31:05 INFO - Running setup.py install for functools32 09:31:05 INFO - warning: no files found matching '*.txt' 09:31:05 INFO - no previously-included directories found matching 'build' 09:31:05 INFO - no previously-included directories found matching 'dist' 09:31:05 INFO - no previously-included directories found matching '.git*' 09:31:05 INFO - Successfully installed jsonschema functools32 09:31:05 INFO - Cleaning up... 09:31:05 INFO - Return code: 0 09:31:05 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:31:05 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')]} 09:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:05 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')]} 09:31:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:05 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 0x7f48918d4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc350>, '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 0x19cd250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19cd6e0>, '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': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', '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 09:31:05 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 09:31:05 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 09:31:05 INFO - Using env: {'DISPLAY': ':0', 09:31:05 INFO - 'HOME': '/home/cltbld', 09:31:05 INFO - 'LANG': 'en_US.UTF-8', 09:31:05 INFO - 'LANGUAGE': 'en_US:en', 09:31:05 INFO - 'LOGNAME': 'cltbld', 09:31:05 INFO - 'MAIL': '/var/mail/cltbld', 09:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:05 INFO - 'MOZ_NO_REMOTE': '1', 09:31:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:05 INFO - 'NO_EM_RESTART': '1', 09:31:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:31:05 INFO - 'PWD': '/builds/slave/test-pgo', 09:31:05 INFO - 'SHELL': '/bin/bash', 09:31:05 INFO - 'SHLVL': '1', 09:31:05 INFO - 'TERM': 'linux', 09:31:05 INFO - 'TMOUT': '86400', 09:31:05 INFO - 'USER': 'cltbld', 09:31:05 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', 09:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:05 INFO - '_': '/tools/buildbot/bin/python'} 09:31:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:05 INFO - Downloading/unpacking flake8 09:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:31:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:31:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:31:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:31:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:31:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:31:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:31:08 INFO - no previously-included directories found matching 'docs/_build' 09:31:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:31:08 INFO - Running setup.py install for pep8 09:31:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:31:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:31:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:31:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:31:08 INFO - no previously-included directories found matching 'docs/_build' 09:31:08 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:31:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:31:08 INFO - Cleaning up... 09:31:08 INFO - Return code: 0 09:31:08 INFO - Running post-action listener: _resource_record_post_action 09:31:08 INFO - Running post-action listener: _start_resource_monitoring 09:31:08 INFO - Starting resource monitoring. 09:31:08 INFO - ##### 09:31:08 INFO - ##### Running install step. 09:31:08 INFO - ##### 09:31:08 INFO - Running pre-action listener: _resource_record_pre_action 09:31:08 INFO - Running main action method: install 09:31:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:31:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:31:08 INFO - Reading from file tmpfile_stdout 09:31:08 INFO - Detecting whether we're running mozinstall >=1.0... 09:31:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:31:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:31:08 INFO - Reading from file tmpfile_stdout 09:31:08 INFO - Output received: 09:31:08 INFO - Usage: mozinstall [options] installer 09:31:08 INFO - Options: 09:31:08 INFO - -h, --help show this help message and exit 09:31:08 INFO - -d DEST, --destination=DEST 09:31:08 INFO - Directory to install application into. [default: 09:31:08 INFO - "/builds/slave/test-pgo"] 09:31:08 INFO - --app=APP Application being installed. [default: firefox] 09:31:08 INFO - mkdir: /builds/slave/test-pgo/build/application 09:31:08 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'] 09:31:08 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 09:31:23 INFO - Reading from file tmpfile_stdout 09:31:23 INFO - Output received: 09:31:23 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:31:23 INFO - Running post-action listener: _resource_record_post_action 09:31:23 INFO - ##### 09:31:23 INFO - ##### Running run-tests step. 09:31:23 INFO - ##### 09:31:23 INFO - Running pre-action listener: _resource_record_pre_action 09:31:23 INFO - Running main action method: run_tests 09:31:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:31:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:31:23 INFO - Python 2.7.3 09:31:23 INFO - Return code: 0 09:31:23 INFO - grabbing minidump binary from tooltool 09:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:23 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 0x19cc350>, '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 0x19cd250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19cd6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:31:23 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 09:31:23 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 09:31:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:31:23 INFO - Return code: 0 09:31:23 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:31:23 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:31:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:31:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:31:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:31:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:31:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:31:24 INFO - Return code: 0 09:31:24 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/1456758320/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:31:24 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/1456758320/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-024 --webServer localhost 09:31:24 INFO - Using env: {'DISPLAY': ':0', 09:31:24 INFO - 'HOME': '/home/cltbld', 09:31:24 INFO - 'LANG': 'en_US.UTF-8', 09:31:24 INFO - 'LANGUAGE': 'en_US:en', 09:31:24 INFO - 'LOGNAME': 'cltbld', 09:31:24 INFO - 'MAIL': '/var/mail/cltbld', 09:31:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:31:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:31:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:24 INFO - 'MOZ_NO_REMOTE': '1', 09:31:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:31:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:24 INFO - 'NO_EM_RESTART': '1', 09:31:24 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:31:24 INFO - 'PWD': '/builds/slave/test-pgo', 09:31:24 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:31:24 INFO - 'SHELL': '/bin/bash', 09:31:24 INFO - 'SHLVL': '1', 09:31:24 INFO - 'TERM': 'linux', 09:31:24 INFO - 'TMOUT': '86400', 09:31:24 INFO - 'USER': 'cltbld', 09:31:24 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092', 09:31:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:24 INFO - '_': '/tools/buildbot/bin/python'} 09:31:24 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/1456758320/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost'] with output_timeout 3600 09:31:24 INFO - mozversion INFO | application_buildid: 20160229070520 09:31:24 INFO - mozversion INFO | application_changeset: 48e0d09b052cb157df2659be7ebaac1f0dff58d8 09:31:24 INFO - mozversion INFO | application_display_name: Nightly 09:31:24 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:31:24 INFO - mozversion INFO | application_name: Firefox 09:31:24 INFO - mozversion INFO | application_remotingname: firefox 09:31:24 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:31:24 INFO - mozversion INFO | application_vendor: Mozilla 09:31:24 INFO - mozversion INFO | application_version: 45.0 09:31:24 INFO - mozversion INFO | platform_buildid: 20160229070520 09:31:24 INFO - mozversion INFO | platform_changeset: 48e0d09b052cb157df2659be7ebaac1f0dff58d8 09:31:24 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:31:24 INFO - mozversion INFO | platform_version: 45.0 09:31:24 INFO - 2016-02-29 09:31:24,616 DEBUG : using testdate: 1456767084 09:31:24 INFO - 2016-02-29 09:31:24,616 DEBUG : actual date: 1456767084 09:31:24 INFO - 2016-02-29 09:31:24,625 INFO : starting webserver on 'localhost:43682' 09:31:24 INFO - 2016-02-29 09:31:24,626 INFO : Starting test suite talos-linux64-ix-024 09:31:24 INFO - 2016-02-29 09:31:24,626 INFO : Starting test dromaeo_css 09:31:24 INFO - 2016-02-29 09:31:24,626 DEBUG : operating with platform_type : linux_ 09:31:24 INFO - 2016-02-29 09:31:24,627 INFO : Initialising browser for dromaeo_css test... 09:31:24 INFO - 2016-02-29 09:31:24,642 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpl7pVqM/profile http://localhost:43682/getInfo.html 09:31:27 INFO - 2016-02-29 09:31:27,907 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:31:27 INFO - 2016-02-29 09:31:27,907 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:31:27 INFO - 2016-02-29 09:31:27,907 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:31:27 INFO - 2016-02-29 09:31:27,907 DEBUG : BROWSER_OUTPUT: __metrics 09:31:28 INFO - 2016-02-29 09:31:28,110 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] 09:31:29 INFO - 2016-02-29 09:31:29,566 INFO : Browser initialized. 09:31:29 INFO - 2016-02-29 09:31:29,566 INFO : Running cycle 1/1 for dromaeo_css test... 09:31:29 INFO - 2016-02-29 09:31:29,566 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpl7pVqM/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:31:41 INFO - 2016-02-29 09:31:41,250 DEBUG : BROWSER_OUTPUT: RSS: Main: 132468736 09:31:41 INFO - 2016-02-29 09:31:41,250 DEBUG : BROWSER_OUTPUT: 09:32:32 INFO - 2016-02-29 09:32:32,013 DEBUG : BROWSER_OUTPUT: 1456767152012 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] 09:34:40 INFO - 2016-02-29 09:34:40,272 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43682/tests/dromaeo/cssquery-ext.html) 09:34:41 INFO - 2016-02-29 09:34:41,308 DEBUG : BROWSER_OUTPUT: RSS: Main: 242831360 09:34:41 INFO - 2016-02-29 09:34:41,308 DEBUG : BROWSER_OUTPUT: 09:37:43 INFO - 2016-02-29 09:37:43,390 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/dromaeo/cssquery-ext.html (next: http://localhost:43682/tests/dromaeo/cssquery-jquery.html) 09:37:43 INFO - 2016-02-29 09:37:43,956 DEBUG : BROWSER_OUTPUT: RSS: Main: 210399232 09:37:43 INFO - 2016-02-29 09:37:43,956 DEBUG : BROWSER_OUTPUT: 09:40:45 INFO - 2016-02-29 09:40:45,767 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43682/tests/dromaeo/cssquery-mootools.html) 09:40:46 INFO - 2016-02-29 09:40:46,333 DEBUG : BROWSER_OUTPUT: RSS: Main: 205107200 09:40:46 INFO - 2016-02-29 09:40:46,333 DEBUG : BROWSER_OUTPUT: 09:43:48 INFO - 2016-02-29 09:43:48,003 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43682/tests/dromaeo/cssquery-prototype.html) 09:43:48 INFO - 2016-02-29 09:43:48,577 DEBUG : BROWSER_OUTPUT: RSS: Main: 195252224 09:43:48 INFO - 2016-02-29 09:43:48,577 DEBUG : BROWSER_OUTPUT: 09:46:51 INFO - 2016-02-29 09:46:51,024 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43682/tests/dromaeo/cssquery-yui.html) 09:46:51 INFO - 2016-02-29 09:46:51,590 DEBUG : BROWSER_OUTPUT: RSS: Main: 194985984 09:46:51 INFO - 2016-02-29 09:46:51,590 DEBUG : BROWSER_OUTPUT: 09:49:53 INFO - 2016-02-29 09:49:53,767 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/dromaeo/cssquery-yui.html (next: http://localhost:43682/tests/dromaeo/cssquery-dojo.html) 09:49:54 INFO - 2016-02-29 09:49:54,017 DEBUG : BROWSER_OUTPUT: RSS: Main: 239616000 09:49:54 INFO - 2016-02-29 09:49:54,018 DEBUG : BROWSER_OUTPUT: 09:49:54 INFO - 2016-02-29 09:49:54,019 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:49:54 INFO - 2016-02-29 09:49:54,019 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:49:54 INFO - 2016-02-29 09:49:54,019 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:49:54 INFO - 2016-02-29 09:49:54,019 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:49:54 INFO - 2016-02-29 09:49:54,021 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3331.16720819795;3407.6421975692565;3438.5873695156583;3445.2592692571093;3484.3031393721244;5606.411326810688;5607.495325420711;5609.691466969321;5610.382857885629;5616.129499927518;5563.833085007446;5592.5805564582615;5596.720163991794;5623.128415095667;5636.436356364362;4961.702297862122;5028.2709007193935;5105.106606343884;5106.7701953412;5168.069747445458;5015.623828212881;5023.849284521452;5026.773795179208;5073.746312684351;5112;5088.389393272794;5144.22836574515;5159.097157997354;5166.354205724285;5169.457206993268;5013.147764879979;5074.847754567369;5076.390833100052;5130.666506677091;5150.021495915764;4928.964917367357;4960.776765045545;4992.650514463989;5032.622553308516;5103.821366252163;5333.866653333659;5341.7062061586985;5349.277847490578;5350.3312085989255;5357.4106848246665;5879.088741245071;5942.851428714317;5943.732532036069;5943.881122377571;5956.851078723023;5787.305523337184;5833.533317334645;5860.882782344372;5905;5935;5614.382417934024;5716.085426331767;5723.914141287884;5725.341585717612;5726.570507211975;5980.970095149583;6170.074488826712;6185.752569897244;6191.628502289877;6199.039148931923;5804.709764511757;5806.738696758656;5810.88378232433;5845.678487683133;5897.587168898136;5967.31375891778;6018.458338749533;6024.909626355608;6034.9396506035255;6038.577299589075;5740.1102829061565;5849.385814489502;5952.345242023374;5953.613015154001;5969.04495280753;5907.911381329283;5991.4907232884825;5992.58051936369;5996.700164991733;6006.729697163638;4586.747728874877;4621.075784842978;4626.306054091913;4630.3748993886065;4658;4047.838086476508;4076.9592304077173;4118.588141185917;4128.02991297045;4176.749395036308;5644.491995720405;5662.773489060474;5668.829935101953;5717.799877004368;5721.256236689203;6037.849053773691;6050.334463209044;6055.000924847351;6057.606255593372;6059.8485037873525;5433.728313584305;5470;5608.719564021782;5649.06790379591;5671.716414179357;5234.136367499396;5285.365756109215;5292.788288468418;5306.310179676618;5306.708131052752;6297.244330680256;6297.401746834135;6313.053042043638;6315.810525684236;6316.494680425647;5920.496757775637;5948.791792287249;6037.728302226323;6061.454469097803;6073.635581865102;6119.8776024478375;6199.721012554536;6229.937700622936;6233.438990490697;6242.157308763259;5677.460641239076;6003.0695242237525;6016.4885984690045;6022.728977196124;6070.908936366046;5962.97018514922;5975.521958243243;5983.760649573968;6024.427679370453;6096.969515152396;5810.738516766671;5899.587028907935;5906.114082887601;5906.763729450774;5909.025010873158;5649.943500564942;5797.710114494174;5887.352391236875;5888.499477544457;5896.469317761422;5709.314882214161;5810.244668193108;5811.738471768866;5812.854678632899;5841.853953651193;5847.883042339215;5854.590178687451;5881.353051164283;5884.764609415575;5885;5551.472610102034;5676.035074037506;5720.513756330592;5749.453801888814;5755.021646320052 09:49:54 INFO - 2016-02-29 09:49:54,024 DEBUG : BROWSER_OUTPUT: |1;ext.html;585.7071464267864;611.4191518057846;613.9109829074785;630.1492984470965;638.5402510192661;38546;39046.60953390465;39052.60947390525;39112.413313800316;39114;43874.561254387445;44402;44508.55491445085;44511.33233001507;44573.77713111438;8140.796480087986;8258.174182581724;9117.908820911789;9276.721698349027;10320.948395258014;10535.841962370532;10659.78680426391;10660.946695266513;10667.626633067845;10671.413072281022;10793.244472886901;10897.237193396466;10898.128149748041;10904.781904361947;10910.672679819618;10741.087007604363;10800.783984320347;10815.134789216843;10830.67507974762;10839.620613278537;10514.631987880464;10622.893771062269;10624.256302058859;10643.893561064368;10644.414557199429;42694.78652606748;43124.56875431237;43149.56850431487;43158.56841431577;43161;3699.408094704834;3993.9201215975518;4224.070704445017;4380.649548036181;4386.232409328387;31733.20666983321;31849;31972;32193.83903080493;32258.193545161324;4070.959290407088;4120.835166593332;4153.552551382345;4155.168966206771;4183.853565125206;306115;306941;306946;307424;307862.46068769734;61369.38630613726;61661;61864.38135618586;62132.68933655303;70243;61872.381276186665;62257.06614400788;62313.376866231665;62346;62598.68700656467;18429.170687319103;18620;18620.44138675842;18643.44069677912;18702.251909923452;14204.431822726976;14412.567622971328;14429.783553246709;14435.783463248059;14451.638709032359;20734.896325518275;21047.15811367559;21051.263205787724;21077;21115.788842111688;8224;8438.831223375499;8515.104864346153;8561.1866872647;8613.44012639189;1022.9283950123569;1114.9275297105662;1131.1968502363413;1131.3551275772732;1131.801934661431;29892.85053574718;30331.204529907238;32510.674893250765;32898.50652240218;32913;1055.6410820320973;1119.2221406122667;1132.9376884271444;1203.1036877526124;1207.6618546806733;13371.41837007939;14592;14615.56153315381;15058.247087645574;15267.465638702592;272530;275391;275616;275958;276994;54577.727111364984;55174.44825551773;55195;55253.447465525634;55336;1378.427952399743;1414.610981979943;1438.697873446588;1503.503843731566;1528.0678785940568;28641.283967900137;28649.28376790597;29078.41843163167;29286.41427171487;29660.55509167321;44584.10831783281;44939.32591011072;44941.77529112333;45026.54973450224;45149.32276015928;255585;319792;329643;329645;331824;31087.68912310848;31368.529472058395;31617.52573711442;31686.207844804063;31709.524357135124;41672.37491437692;42375;42741;43782;43807.561924381625;37783.81108094442;37897.4315385275;37988.62011379851;39003;39306.2138757229;37964;40523.39214911838;41058.38412423876;42222;42915.785421072695;36777.63222367742;37248.255034899696;37564;38035;38046.23907521779;32235.032949011566;32325;33910;34499.82750086234;34657.30685386329;23989.640155398032;25239.36901577475;25792.323498972506;25864.094756683426;26858.73141268562 09:49:54 INFO - 2016-02-29 09:49:54,024 DEBUG : BROWSER_OUTPUT: |2;jquery.html;379.62575476774066;381.33457117330227;382.73887540553875;386.75054589789585;391.33277761416787;14602.853971460283;15574.688506229868;17152.399666011694;19009.42971710849;19018.239270429185;19001.714974275397;19182.52043698908;19336.226550937947;19521.804781952178;19571.217151313966;6145.569810113294;6238.376162383748;6270.435660790528;6284.5600807943465;6285.308616052231;4621;4751.667383283154;6187.783427580036;6695.096162018138;6927.7228910843505;5989.730462129215;6848.007038979345;6865.038894554767;6911.067005954182;6913.827154321157;5131.615128865331;6141.170941922828;6141.508679305645;6142.140100385905;6151.907721384206;5396.406395296514;5512.448755124496;5515.779368825242;5583.832485025456;5594.580406469531;12984.805227921588;13188.340582970908;13193.86806131936;16843.41048063312;20843.270485533085;2474.096954611554;2493.463905260378;2529.696436427622;2530.8481491110592;2552.8468291902545;9818.509074546315;9919.80160396788;9966.252531060127;10175.84736228957;10601.893981060168;1972.8421726261865;1983.5041239690077;1987.1951859496926;2001.1495114576392;2015.9899200503903;65655;67211.66394168047;67731.66134169347;68503.3149668507;68818.31181688169;11058.11535077192;11286.209965302514;11316.6039188628;11331;11402.771944561062;14767.40930362795;14890.255487225812;14921.179335136672;14926.626834329012;14964.176970266522;8222;8417;8434.114417986146;8533.402661813738;8791.780205494913;7719.696630164624;7758.262965018314;7807.921920780719;7886;7926.088499822594;4192.45498085247;4791;4845.563899249085;4847.80608775652;4877.951220487821;1702.1630308291844;1777.512157739722;1835.5392706697469;1836.4949638849423;1882.9905850470934;955.7753927826955;958.9856152157723;995.6117114325419;1041.5677493839971;1047.9004494573003;16418.589535261715;16686.916565417338;17018.574535636708;17252.396166134124;17496.68774841892;951.4909523404945;960.9423434593947;997.4962643864802;1023.0843395161289;1082.0423924826428;10045.899541004497;10125.898741012643;10254.436006019741;10259.333143345808;10336.741581460523;643198;653254;653918;679042;682523;82459;83374;85057.57471212605;85250;86398.56800715956;1153.7692461507731;1162.0587324267372;1164.062929341871;1201.375284851872;1210.9455074521668;16147;16256.105914174834;16336.836631633532;16337.182033191655;16546;38085.80957095197;38274;38510.42234366543;38896.6110338893;39634.603653964245;50203;50339.74830125972;50754.238686420475;52868.20697689461;54396.46422982494;10251.897481025095;10305.948470257601;10327.690169294932;10443.738906527398;10537.683869483928;14183.787243191568;18676.81323186751;18721.25114995385;18924.716129258348;19074.427767167006;11850.525978960744;14292.499762508261;14893.925530372278;15023.323950422477;15242.923785381003;14334.856651433352;14758.704825903207;14924.776128357866;14929.35854771531;15588.610284742519;15401.537953861402;15615.843841561438;15976.920115399349;16219.18904054769;16220.756688649444;9386.67146649864;9741.951290243787;9869.062439068277;10648.520816563117;10668.613080299512;15759.842401575837;15796.68406631884;15797.763033554276;16067;16128.677426451171 09:49:54 INFO - 2016-02-29 09:49:54,028 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1551.6198531359819;1609.903405795653;1609.935602575897;1769.380716749138;1858.4424672598225;7170.498065135443;7628.351590114833;7635.312821846032;7636.503627264224;7642.426817988652;6716.1940567132015;6849.075374824397;7393.8151546211375;7578.166401695809;7601.619919004055;2321.2223904991806;2336.2991102669216;2365;2367.7868991790733;2368.4670949036417;2048.6414877396396;2307.8038366738847;2372.9762702373014;2373.9881300593474;2374.6556749271344;2033.1460786469718;2161.3083813179787;2194.9122035118576;2198.967015494769;2200.4058904095964;2278.2481781012307;2288.199130304397;2298.10373954158;2366.3610825077135;2369.099742098009;2288.78256565626;2293.5756884976167;2294.7246330440453;2306.8039216666602;2314.8148148148275;7598.658060387296;7655.655495502716;7669;7672.961635191788;7676.232376762335;4704.270838020112;4800.423949126127;4803.351547541107;4805.543473370024;4807.855764327076;3122.531620256957;3140.795848269855;3149.118246890874;3168.8415579221178;3191.1702957231055;1101.0971003776906;1133.6429024857143;1135.2564070533813;2029.7767245602972;2095.4447071526056;10201;10287.89712102877;10303.227257955683;10318.948405258001;10331.690049298459;4121.052158003616;4165.437665915123;4249.490061192615;4250.596193361625;4255.829766809293;8792.736217913473;8797.692080777142;8799.692010779592;8808.427452215585;8821.911780882108;4685.5782979532005;4688.343631891538;4694.718316900997;4712.835050773207;4721;4327.242732521796;4333.479982402132;4380.342948557742;4385.079133382025;4387.780610969438;3480.3039392121173;3493.7729047611824;3495.4582039783872;3504.0363899927725;3540.309639620301;1027.5427434791445;1053.146950969717;1062.644014255233;1074.548689550375;1085.4138765066934;1199.3583432863377;1206.1014544164655;1242.5588915934893;1418.1065928465207;1435.8564143585559;7288.817779555554;7349.154847192534;7498.287662672037;7501.287377699219;7502.687029769769;642.6047980491963;649.574528683713;654.0842820051965;668.6222284409293;804.4811096842503;5807.245058142415;5845.269341332333;5853.2976042875125;5864.237649105674;5872.060470324727;8775.122487751174;8798.956005219932;9025.729228123168;9032.819343613091;9091.454512729257;8867.556622168866;8899.243564297107;8909.465432073966;8919;8927.24118449926;7214.531055481377;7229.566226026455;7355.5586664800285;7361.815954601285;7365.9631701841145;7415.555066696015;7419.109706835215;7469.215732348025;7475.177730449755;7485.925140748523;8106.229908158715;8463.873041904499;8465;8481.830363392573;8488.83022339562;8355.373346998878;8460.196281353261;8474.915250847413;8545.700900468453;8576;6924.58452492852;6970.093887794555;6992.1259842519685;7097.858042839011;7109.111361079865;7298.744543940937;7361.447891408059;7387.519811212254;7388.445866560137;7389.889151662622;6093.268807743099;6949.235584085847;7065.6820443081215;7174.748883789042;7312.415006799337;6494.415502604788;6542;6991.510594258557;6996.090508233897;7060.858782824417;6132.724027418866;7029.05107810439;7040.155181378267;7044.260352663102;7044.577325360495;6489.123968264224;6526.67366631676;6527.216733991761;6527.934720652733;6531.967340163459;6487.675616219264;6606.207255129415;6608.867822643617;6636.2368327642935;6676.0987266718375 09:49:54 INFO - 2016-02-29 09:49:54,028 DEBUG : BROWSER_OUTPUT: |4;prototype.html;355.0626346445384;359.9514065601143;360.2614639988024;361.2738395824392;363.06148605853883;6116.9388306116925;6128.172696685946;6143.293521245058;6162.938370616293;6205.81382558524;5872.471477567018;5925.962956482609;6027.246594175728;6120.081987701847;6129.908051379233;4770.666053376265;4778.976105119487;4782.234842425212;4786.282057691356;4792.466410748561;4524.9095018099615;4779.713217206961;4784.97607511962;4789.98820495391;4802.711837289774;4593.333966574844;4622.58396744293;4692.41344831896;4714.151452738506;4719.362886010396;4672.509386514401;4695.506971767966;4702.8589142325445;4704.882377940545;4713.835015774465;4185.560516145792;4187.016051227959;4187.20443115807;4192.874213773592;4197.160567886435;5705.77176912923;5814.302283725938;5828.970855145697;5829.562782791265;5837.970810145922;5560.082586373243;5571.498565129117;5572.108462645957;5575.414581468968;5579.38626751057;6468.902966455507;6469.93530064698;6478.902816457757;6485;6485.3514648534765;4695.1783437898575;4707.905841883177;4712.6229901607785;4713.104510143062;4719.740414277213;6608.207015158212;6739.090222819955;6784.76253331136;6790.898136527907;6800.52396332252;4703.95296047042;4733.053389322149;4734.976325118352;4736.550027747358;4738.905221895543;4771.928421073687;4786.138495070921;4787.066522028171;4792.496787837296;4805.6155507559315;5337.572994160458;5354.946450535523;5448.83653490396;5456.7544460499375;5458.235846981426;6510.023496475566;6524.575902566318;6548.27968923418;6551.967240163769;6555.967220163964;1674.087622245879;1676.1535424610493;1689.847913687774;1694.0344003917648;1694.4154266777941;1128.7291050147905;1137.7724455108846;1140.560884059643;1144.7023773818867;1165.7143999719954;6413.775517856852;6420.1974753155855;6422.293547709748;6423.164988551458;6423.646699431576;6579.76970806029;6743.055972163901;6794.0827988221945;6799.082123913307;6802.761903333359;6328.082428047907;6341.397567231105;6499.935000650027;6507.381798729113;6514.283428822826;3672.779633222015;3701.7414079213195;3714.9442758358646;3715.851365945332;3716.1638631307737;6846.110005699227;7053.047838541834;7087.397571206505;7090.716371345088;7095.361417472453;6997.090378250795;7051.788446346618;7054.682539285642;7063.540869843444;7065.964670176719;7103.644817759195;7105.75129870452;7109.004739336497;7113.608751518613;7116.110486189226;6777.648601182456;6848.691808868513;6893.414059804973;6899.655017249017;6901.689423975833;6729.596224226562;6736.023276624764;6740.662966851736;6741.797746067626;6742.123523942052;7074.71701131949;7083.574985500886;7086.326798954091;7091.2554181810165;7099.645017749195;4621.630269578359;4733.6213102951;4738.265568836836;4771.5705586496;4777.713337199779;6813.557118787263;6817.590944543145;6819.795406137824;6821.2155602106395;6850.691718872563;6650.534462587572;6653.334666533308;6663.433608143168;6666.6000039995815;6668.499862510428;6479.902801458076;6571.145751052332;6636.601803891782;6638.0374845648075;6642.335766423319;6474.643894585654;6499.935000649933;6500.869982600416;6501.577397469149;6507.316731743099;6633.900491492728;6636.4027237546925;6642.734290628321;6648.634325112165;6651.301613330531;6266.37336266388;6366.267879193767;6484.383983521558;6486.967565162144;6487.3836985486305 09:49:54 INFO - 2016-02-29 09:49:54,028 DEBUG : BROWSER_OUTPUT: |5;yui.html;785.5483097219101;806.475612296521;837.4807619276046;925.7361651929201;934.439336398161;14556.563303100898;14955.55133346;14959.551213463586;14983.175925324116;15112.319945602447;11878.94060529696;12590.929770969478;12759.17065390749;12849.421776020079;12899.16155449895;870.4864130163203;871.7166920750766;873.2926329672986;873.5588527793456;875.6672464463495;879.0111124984393;879.5953861223845;879.9472031678088;881.0748713850463;886.6586364249746;919.4437365393941;920.8296465153934;921.7511271956602;922.9538523073819;929.3401684803797;911.8951320598084;919.7792529792869;920.4523308631387;921.3596550397476;935.4153653966271;676.5737585321216;679.8538314262457;682.3551743602297;689.2969171445149;695.5513693667607;13463.124896881769;13592.320383980861;13614.727705445835;13646.727065458635;13649.181049136983;11419.314841109477;11501.827472587918;12044.518219271218;12417.130800843945;13525.120867143603;11047.668569942914;11068.944655276753;11159.944200279027;11170;11493.367864767435;3920.9803950980063;3959.78221197834;3983.5618082010956;3989.202159568098;4089.488813898263;44814.775926120485;45002.54997450049;45188.548114518766;51248.23127653051;51272.48727512715;10750.73123171927;10914.21567411195;10920.12638988879;11150.49822757978;11162.832557511643;19356.4193074208;19456.513587160433;19458.416247512316;19465.02674866251;19478.415647530597;8286.917130828735;8299.917000829915;8318.70884519039;8349.415540912078;8353.916460835435;4719.079779442975;4769.213079841857;4770.546798054179;4841.757912104381;4883.218685010453;3412.6587341265677;3433.0044287156943;3434.5019972103905;3440.3291358184915;3440.587129544421;624.341319907502;632.6713900808265;638.6200210874522;642.5534253693731;652.3802387731674;668.6623255256162;703.5040296590892;725.5972935021008;729.3800269770652;745.697992313114;9152.54237288133;9276.493098467863;9415.905840941503;9555.187809036172;10023.749406264902;689.8551304226169;708.5429897716048;711.6441779110445;754.2344520311888;770.6994272233833;4271.061541525605;4594.517575654508;4642.675012749075;4657.650676199299;4734.928976065362;60193;63636.36363636304;64331.35668643347;69830.30169698238;70825;52579;53558.73220633872;57583.42416575781;57957;58150.12774808381;42566.57433425688;47904.28143577849;48974.99999999929;50528.49471505238;54925.45074549283;9234.815303694022;9429.009953954735;9642.373245739005;9943.558184063415;10026.899731002888;36333;39564.40653390144;40502;40511.594884050784;41045.794771025954;47704.52295477001;50252.49747502478;50321;51113.48886511236;51207.48792512027;11259.380734059705;11327.603533876274;11849.170558060852;12163.939180304396;12369.752604948031;13051.673708157372;13062.020348473712;13151.27667978232;14314.785278220626;14465.132092074089;11822.11334149925;12184.390780460764;12378.442970066188;12645.24128552253;12664.556740514037;12199.390030498262;12438.129330946747;12500.499980001063;12768.936155319165;12882.35588220604;12469.376531173224;12966.092373533762;13231.867681323449;13249.668758281121;13315.733685326433;10434.321769084985;10504.369737815756;10558.260921735711;10699.786004280026;10961.83557246626;10458.947705261426;10533.4733263335;10603.946980265358;10628.415437150721;10641.201909856947 09:49:54 INFO - 2016-02-29 09:49:54,028 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:49:54 INFO - 2016-02-29 09:49:54,028 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:49:54 INFO - 2016-02-29 09:49:54,028 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,792 09:49:54 INFO - 2016-02-29 09:49:54,028 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:49:54 INFO - 2016-02-29 09:49:54,028 DEBUG : BROWSER_OUTPUT: __startTimestamp1456768194018__endTimestamp 09:49:54 INFO - 2016-02-29 09:49:54,029 DEBUG : BROWSER_OUTPUT: 09:49:54 INFO - 2016-02-29 09:49:54,029 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:49:54 INFO - 2016-02-29 09:49:54,029 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:49:54 INFO - 2016-02-29 09:49:54,029 DEBUG : BROWSER_OUTPUT: 09:49:54 INFO - 2016-02-29 09:49:54,029 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5563.26 Median:5749.45 stddev:611.90 (10.6%) stddev-sans-first:588.19 09:49:54 INFO - 2016-02-29 09:49:54,029 DEBUG : BROWSER_OUTPUT: Values: 3331.2 3407.6 3438.6 3445.3 3484.3 5606.4 5607.5 5609.7 5610.4 5616.1 5563.8 5592.6 5596.7 5623.1 5636.4 4961.7 5028.3 5105.1 5106.8 5168.1 5015.6 5023.8 5026.8 5073.7 5112.0 5088.4 5144.2 5159.1 5166.4 5169.5 5013.1 5074.8 5076.4 5130.7 5150.0 4929.0 4960.8 4992.7 5032.6 5103.8 5333.9 5341.7 5349.3 5350.3 5357.4 5879.1 5942.9 5943.7 5943.9 5956.9 5787.3 5833.5 5860.9 5905.0 5935.0 5614.4 5716.1 5723.9 5725.3 5726.6 5981.0 6170.1 6185.8 6191.6 6199.0 5804.7 5806.7 5810.9 5845.7 5897.6 5967.3 6018.5 6024.9 6034.9 6038.6 5740.1 5849.4 5952.3 5953.6 5969.0 5907.9 5991.5 5992.6 5996.7 6006.7 4586.7 4621.1 4626.3 4630.4 4658.0 4047.8 4077.0 4118.6 4128.0 4176.7 5644.5 5662.8 5668.8 5717.8 5721.3 6037.8 6050.3 6055.0 6057.6 6059.8 5433.7 5470.0 5608.7 5649.1 5671.7 5234.1 5285.4 5292.8 5306.3 5306.7 6297.2 6297.4 6313.1 6315.8 6316.5 5920.5 5948.8 6037.7 6061.5 6073.6 6119.9 6199.7 6229.9 6233.4 6242.2 5677.5 6003.1 6016.5 6022.7 6070.9 5963.0 5975.5 5983.8 6024.4 6097.0 5810.7 5899.6 5906.1 5906.8 5909.0 5649.9 5797.7 5887.4 5888.5 5896.5 5709.3 5810.2 5811.7 5812.9 5841.9 5847.9 5854.6 5881.4 5884.8 5885.0 5551.5 5676.0 5720.5 5749.5 5755.0 09:49:54 INFO - 2016-02-29 09:49:54,029 DEBUG : BROWSER_OUTPUT: 09:49:54 INFO - 2016-02-29 09:49:54,029 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48125.22 Median:32291.60 stddev:78021.61 (241.6%) stddev-sans-first:78158.83 09:49:54 INFO - 2016-02-29 09:49:54,030 DEBUG : BROWSER_OUTPUT: Values: 585.7 611.4 613.9 630.1 638.5 38546.0 39046.6 39052.6 39112.4 39114.0 43874.6 44402.0 44508.6 44511.3 44573.8 8140.8 8258.2 9117.9 9276.7 10320.9 10535.8 10659.8 10660.9 10667.6 10671.4 10793.2 10897.2 10898.1 10904.8 10910.7 10741.1 10800.8 10815.1 10830.7 10839.6 10514.6 10622.9 10624.3 10643.9 10644.4 42694.8 43124.6 43149.6 43158.6 43161.0 3699.4 3993.9 4224.1 4380.6 4386.2 31733.2 31849.0 31972.0 32193.8 32258.2 4071.0 4120.8 4153.6 4155.2 4183.9 306115.0 306941.0 306946.0 307424.0 307862.5 61369.4 61661.0 61864.4 62132.7 70243.0 61872.4 62257.1 62313.4 62346.0 62598.7 18429.2 18620.0 18620.4 18643.4 18702.3 14204.4 14412.6 14429.8 14435.8 14451.6 20734.9 21047.2 21051.3 21077.0 21115.8 8224.0 8438.8 8515.1 8561.2 8613.4 1022.9 1114.9 1131.2 1131.4 1131.8 29892.9 30331.2 32510.7 32898.5 32913.0 1055.6 1119.2 1132.9 1203.1 1207.7 13371.4 14592.0 14615.6 15058.2 15267.5 272530.0 275391.0 275616.0 275958.0 276994.0 54577.7 55174.4 55195.0 55253.4 55336.0 1378.4 1414.6 1438.7 1503.5 1528.1 28641.3 28649.3 29078.4 29286.4 29660.6 44584.1 44939.3 44941.8 45026.5 45149.3 255585.0 319792.0 329643.0 329645.0 331824.0 31087.7 31368.5 31617.5 31686.2 31709.5 41672.4 42375.0 42741.0 43782.0 43807.6 37783.8 37897.4 37988.6 39003.0 39306.2 37964.0 40523.4 41058.4 42222.0 42915.8 36777.6 37248.3 37564.0 38035.0 38046.2 32235.0 32325.0 33910.0 34499.8 34657.3 23989.6 25239.4 25792.3 25864.1 26858.7 09:49:54 INFO - 2016-02-29 09:49:54,030 DEBUG : BROWSER_OUTPUT: 09:49:54 INFO - 2016-02-29 09:49:54,031 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:33247.43 Median:2008.57 stddev:108176.68 (5385.8%) stddev-sans-first:108451.99 09:49:54 INFO - 2016-02-29 09:49:54,031 DEBUG : BROWSER_OUTPUT: Values: 379.6 381.3 382.7 386.8 391.3 14602.9 15574.7 17152.4 19009.4 19018.2 19001.7 19182.5 19336.2 19521.8 19571.2 6145.6 6238.4 6270.4 6284.6 6285.3 4621.0 4751.7 6187.8 6695.1 6927.7 5989.7 6848.0 6865.0 6911.1 6913.8 5131.6 6141.2 6141.5 6142.1 6151.9 5396.4 5512.4 5515.8 5583.8 5594.6 12984.8 13188.3 13193.9 16843.4 20843.3 2474.1 2493.5 2529.7 2530.8 2552.8 9818.5 9919.8 9966.3 10175.8 10601.9 1972.8 1983.5 1987.2 2001.1 2016.0 65655.0 67211.7 67731.7 68503.3 68818.3 11058.1 11286.2 11316.6 11331.0 11402.8 14767.4 14890.3 14921.2 14926.6 14964.2 8222.0 8417.0 8434.1 8533.4 8791.8 7719.7 7758.3 7807.9 7886.0 7926.1 4192.5 4791.0 4845.6 4847.8 4878.0 1702.2 1777.5 1835.5 1836.5 1883.0 955.8 959.0 995.6 1041.6 1047.9 16418.6 16686.9 17018.6 17252.4 17496.7 951.5 960.9 997.5 1023.1 1082.0 10045.9 10125.9 10254.4 10259.3 10336.7 643198.0 653254.0 653918.0 679042.0 682523.0 82459.0 83374.0 85057.6 85250.0 86398.6 1153.8 1162.1 1164.1 1201.4 1210.9 16147.0 16256.1 16336.8 16337.2 16546.0 38085.8 38274.0 38510.4 38896.6 39634.6 50203.0 50339.7 50754.2 52868.2 54396.5 10251.9 10305.9 10327.7 10443.7 10537.7 14183.8 18676.8 18721.3 18924.7 19074.4 11850.5 14292.5 14893.9 15023.3 15242.9 14334.9 14758.7 14924.8 14929.4 15588.6 15401.5 15615.8 15976.9 16219.2 16220.8 9386.7 9742.0 9869.1 10648.5 10668.6 15759.8 15796.7 15797.8 16067.0 16128.7 09:49:54 INFO - 2016-02-29 09:49:54,031 DEBUG : BROWSER_OUTPUT: 09:49:54 INFO - 2016-02-29 09:49:54,031 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5409.70 Median:3572.00 stddev:2736.13 (76.6%) stddev-sans-first:2728.43 09:49:54 INFO - 2016-02-29 09:49:54,032 DEBUG : BROWSER_OUTPUT: Values: 1551.6 1609.9 1609.9 1769.4 1858.4 7170.5 7628.4 7635.3 7636.5 7642.4 6716.2 6849.1 7393.8 7578.2 7601.6 2321.2 2336.3 2365.0 2367.8 2368.5 2048.6 2307.8 2373.0 2374.0 2374.7 2033.1 2161.3 2194.9 2199.0 2200.4 2278.2 2288.2 2298.1 2366.4 2369.1 2288.8 2293.6 2294.7 2306.8 2314.8 7598.7 7655.7 7669.0 7673.0 7676.2 4704.3 4800.4 4803.4 4805.5 4807.9 3122.5 3140.8 3149.1 3168.8 3191.2 1101.1 1133.6 1135.3 2029.8 2095.4 10201.0 10287.9 10303.2 10318.9 10331.7 4121.1 4165.4 4249.5 4250.6 4255.8 8792.7 8797.7 8799.7 8808.4 8821.9 4685.6 4688.3 4694.7 4712.8 4721.0 4327.2 4333.5 4380.3 4385.1 4387.8 3480.3 3493.8 3495.5 3504.0 3540.3 1027.5 1053.1 1062.6 1074.5 1085.4 1199.4 1206.1 1242.6 1418.1 1435.9 7288.8 7349.2 7498.3 7501.3 7502.7 642.6 649.6 654.1 668.6 804.5 5807.2 5845.3 5853.3 5864.2 5872.1 8775.1 8799.0 9025.7 9032.8 9091.5 8867.6 8899.2 8909.5 8919.0 8927.2 7214.5 7229.6 7355.6 7361.8 7366.0 7415.6 7419.1 7469.2 7475.2 7485.9 8106.2 8463.9 8465.0 8481.8 8488.8 8355.4 8460.2 8474.9 8545.7 8576.0 6924.6 6970.1 6992.1 7097.9 7109.1 7298.7 7361.4 7387.5 7388.4 7389.9 6093.3 6949.2 7065.7 7174.7 7312.4 6494.4 6542.0 6991.5 6996.1 7060.9 6132.7 7029.1 7040.2 7044.3 7044.6 6489.1 6526.7 6527.2 6527.9 6532.0 6487.7 6606.2 6608.9 6636.2 6676.1 09:49:54 INFO - 2016-02-29 09:49:54,033 DEBUG : BROWSER_OUTPUT: 09:49:54 INFO - 2016-02-29 09:49:54,033 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5521.03 Median:6297.23 stddev:1654.92 (26.3%) stddev-sans-first:1613.50 09:49:54 INFO - 2016-02-29 09:49:54,036 DEBUG : BROWSER_OUTPUT: Values: 355.1 360.0 360.3 361.3 363.1 6116.9 6128.2 6143.3 6162.9 6205.8 5872.5 5926.0 6027.2 6120.1 6129.9 4770.7 4779.0 4782.2 4786.3 4792.5 4524.9 4779.7 4785.0 4790.0 4802.7 4593.3 4622.6 4692.4 4714.2 4719.4 4672.5 4695.5 4702.9 4704.9 4713.8 4185.6 4187.0 4187.2 4192.9 4197.2 5705.8 5814.3 5829.0 5829.6 5838.0 5560.1 5571.5 5572.1 5575.4 5579.4 6468.9 6469.9 6478.9 6485.0 6485.4 4695.2 4707.9 4712.6 4713.1 4719.7 6608.2 6739.1 6784.8 6790.9 6800.5 4704.0 4733.1 4735.0 4736.6 4738.9 4771.9 4786.1 4787.1 4792.5 4805.6 5337.6 5354.9 5448.8 5456.8 5458.2 6510.0 6524.6 6548.3 6552.0 6556.0 1674.1 1676.2 1689.8 1694.0 1694.4 1128.7 1137.8 1140.6 1144.7 1165.7 6413.8 6420.2 6422.3 6423.2 6423.6 6579.8 6743.1 6794.1 6799.1 6802.8 6328.1 6341.4 6499.9 6507.4 6514.3 3672.8 3701.7 3714.9 3715.9 3716.2 6846.1 7053.0 7087.4 7090.7 7095.4 6997.1 7051.8 7054.7 7063.5 7066.0 7103.6 7105.8 7109.0 7113.6 7116.1 6777.6 6848.7 6893.4 6899.7 6901.7 6729.6 6736.0 6740.7 6741.8 6742.1 7074.7 7083.6 7086.3 7091.3 7099.6 4621.6 4733.6 4738.3 4771.6 4777.7 6813.6 6817.6 6819.8 6821.2 6850.7 6650.5 6653.3 6663.4 6666.6 6668.5 6479.9 6571.1 6636.6 6638.0 6642.3 6474.6 6499.9 6500.9 6501.6 6507.3 6633.9 6636.4 6642.7 6648.6 6651.3 6266.4 6366.3 6484.4 6487.0 6487.4 09:49:54 INFO - 2016-02-29 09:49:54,036 DEBUG : BROWSER_OUTPUT: 09:49:54 INFO - 2016-02-29 09:49:54,036 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14968.10 Median:45095.55 stddev:17430.97 (38.7%) stddev-sans-first:17447.33 09:49:54 INFO - 2016-02-29 09:49:54,037 DEBUG : BROWSER_OUTPUT: Values: 785.5 806.5 837.5 925.7 934.4 14556.6 14955.6 14959.6 14983.2 15112.3 11878.9 12590.9 12759.2 12849.4 12899.2 870.5 871.7 873.3 873.6 875.7 879.0 879.6 879.9 881.1 886.7 919.4 920.8 921.8 923.0 929.3 911.9 919.8 920.5 921.4 935.4 676.6 679.9 682.4 689.3 695.6 13463.1 13592.3 13614.7 13646.7 13649.2 11419.3 11501.8 12044.5 12417.1 13525.1 11047.7 11068.9 11159.9 11170.0 11493.4 3921.0 3959.8 3983.6 3989.2 4089.5 44814.8 45002.5 45188.5 51248.2 51272.5 10750.7 10914.2 10920.1 11150.5 11162.8 19356.4 19456.5 19458.4 19465.0 19478.4 8286.9 8299.9 8318.7 8349.4 8353.9 4719.1 4769.2 4770.5 4841.8 4883.2 3412.7 3433.0 3434.5 3440.3 3440.6 624.3 632.7 638.6 642.6 652.4 668.7 703.5 725.6 729.4 745.7 9152.5 9276.5 9415.9 9555.2 10023.7 689.9 708.5 711.6 754.2 770.7 4271.1 4594.5 4642.7 4657.7 4734.9 60193.0 63636.4 64331.4 69830.3 70825.0 52579.0 53558.7 57583.4 57957.0 58150.1 42566.6 47904.3 48975.0 50528.5 54925.5 9234.8 9429.0 9642.4 9943.6 10026.9 36333.0 39564.4 40502.0 40511.6 41045.8 47704.5 50252.5 50321.0 51113.5 51207.5 11259.4 11327.6 11849.2 12163.9 12369.8 13051.7 13062.0 13151.3 14314.8 14465.1 11822.1 12184.4 12378.4 12645.2 12664.6 12199.4 12438.1 12500.5 12768.9 12882.4 12469.4 12966.1 13231.9 13249.7 13315.7 10434.3 10504.4 10558.3 10699.8 10961.8 10458.9 10533.5 10603.9 10628.4 10641.2 09:49:54 INFO - 2016-02-29 09:49:54,037 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:49:54 INFO - 2016-02-29 09:49:54,037 DEBUG : BROWSER_OUTPUT: 09:49:54 INFO - 2016-02-29 09:49:54,570 INFO : Browser exited with error code: 0 09:49:54 INFO - 2016-02-29 09:49:54,593 INFO : Completed test dromaeo_css (00:18:29) 09:49:54 INFO - 2016-02-29 09:49:54,593 INFO : Starting test kraken 09:49:54 INFO - 2016-02-29 09:49:54,593 DEBUG : operating with platform_type : linux_ 09:49:54 INFO - 2016-02-29 09:49:54,593 INFO : Initialising browser for kraken test... 09:49:54 INFO - 2016-02-29 09:49:54,599 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDHWeEA/profile http://localhost:43682/getInfo.html 09:49:58 INFO - 2016-02-29 09:49:58,075 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:49:58 INFO - 2016-02-29 09:49:58,075 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:49:58 INFO - 2016-02-29 09:49:58,075 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:49:58 INFO - 2016-02-29 09:49:58,075 DEBUG : BROWSER_OUTPUT: __metrics 09:49:58 INFO - 2016-02-29 09:49:58,216 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] 09:49:58 INFO - 2016-02-29 09:49:58,914 INFO : Browser initialized. 09:49:58 INFO - 2016-02-29 09:49:58,914 INFO : Running cycle 1/1 for kraken test... 09:49:58 INFO - 2016-02-29 09:49:58,915 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDHWeEA/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:50:10 INFO - 2016-02-29 09:50:10,593 DEBUG : BROWSER_OUTPUT: RSS: Main: 134565888 09:50:10 INFO - 2016-02-29 09:50:10,593 DEBUG : BROWSER_OUTPUT: 09:50:13 INFO - 2016-02-29 09:50:13,013 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43682/tests/kraken/driver.html?testName=audio-beat-detection) 09:50:13 INFO - 2016-02-29 09:50:13,583 DEBUG : BROWSER_OUTPUT: RSS: Main: 164495360 09:50:13 INFO - 2016-02-29 09:50:13,583 DEBUG : BROWSER_OUTPUT: 09:50:16 INFO - 2016-02-29 09:50:16,152 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43682/tests/kraken/driver.html?testName=audio-dft) 09:50:16 INFO - 2016-02-29 09:50:16,725 DEBUG : BROWSER_OUTPUT: RSS: Main: 164216832 09:50:16 INFO - 2016-02-29 09:50:16,725 DEBUG : BROWSER_OUTPUT: 09:50:19 INFO - 2016-02-29 09:50:19,503 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43682/tests/kraken/driver.html?testName=audio-fft) 09:50:20 INFO - 2016-02-29 09:50:20,070 DEBUG : BROWSER_OUTPUT: RSS: Main: 162713600 09:50:20 INFO - 2016-02-29 09:50:20,070 DEBUG : BROWSER_OUTPUT: 09:50:22 INFO - 2016-02-29 09:50:22,062 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43682/tests/kraken/driver.html?testName=audio-oscillator) 09:50:22 INFO - 2016-02-29 09:50:22,631 DEBUG : BROWSER_OUTPUT: RSS: Main: 161378304 09:50:22 INFO - 2016-02-29 09:50:22,631 DEBUG : BROWSER_OUTPUT: 09:50:24 INFO - 2016-02-29 09:50:24,798 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43682/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:50:25 INFO - 2016-02-29 09:50:25,359 DEBUG : BROWSER_OUTPUT: RSS: Main: 160026624 09:50:25 INFO - 2016-02-29 09:50:25,359 DEBUG : BROWSER_OUTPUT: 09:50:28 INFO - 2016-02-29 09:50:28,181 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43682/tests/kraken/driver.html?testName=imaging-darkroom) 09:50:28 INFO - 2016-02-29 09:50:28,763 DEBUG : BROWSER_OUTPUT: RSS: Main: 160792576 09:50:28 INFO - 2016-02-29 09:50:28,763 DEBUG : BROWSER_OUTPUT: 09:50:32 INFO - 2016-02-29 09:50:32,092 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43682/tests/kraken/driver.html?testName=imaging-desaturate) 09:50:32 INFO - 2016-02-29 09:50:32,666 DEBUG : BROWSER_OUTPUT: RSS: Main: 160342016 09:50:32 INFO - 2016-02-29 09:50:32,666 DEBUG : BROWSER_OUTPUT: 09:50:35 INFO - 2016-02-29 09:50:35,368 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43682/tests/kraken/driver.html?testName=json-parse-financial) 09:50:35 INFO - 2016-02-29 09:50:35,937 DEBUG : BROWSER_OUTPUT: RSS: Main: 159637504 09:50:35 INFO - 2016-02-29 09:50:35,938 DEBUG : BROWSER_OUTPUT: 09:50:37 INFO - 2016-02-29 09:50:37,510 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43682/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:50:38 INFO - 2016-02-29 09:50:38,072 DEBUG : BROWSER_OUTPUT: RSS: Main: 169218048 09:50:38 INFO - 2016-02-29 09:50:38,072 DEBUG : BROWSER_OUTPUT: 09:50:39 INFO - 2016-02-29 09:50:39,845 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43682/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:50:40 INFO - 2016-02-29 09:50:40,438 DEBUG : BROWSER_OUTPUT: RSS: Main: 167223296 09:50:40 INFO - 2016-02-29 09:50:40,438 DEBUG : BROWSER_OUTPUT: 09:50:42 INFO - 2016-02-29 09:50:42,390 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43682/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:50:42 INFO - 2016-02-29 09:50:42,958 DEBUG : BROWSER_OUTPUT: RSS: Main: 168894464 09:50:42 INFO - 2016-02-29 09:50:42,958 DEBUG : BROWSER_OUTPUT: 09:50:45 INFO - 2016-02-29 09:50:45,324 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43682/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:50:45 INFO - 2016-02-29 09:50:45,905 DEBUG : BROWSER_OUTPUT: RSS: Main: 175620096 09:50:45 INFO - 2016-02-29 09:50:45,906 DEBUG : BROWSER_OUTPUT: 09:50:48 INFO - 2016-02-29 09:50:48,412 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43682/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:50:48 INFO - 2016-02-29 09:50:48,981 DEBUG : BROWSER_OUTPUT: RSS: Main: 175853568 09:50:48 INFO - 2016-02-29 09:50:48,981 DEBUG : BROWSER_OUTPUT: 09:50:50 INFO - 2016-02-29 09:50:50,634 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43682/tests/kraken/driver.html?testName=ai-astar) 09:50:50 INFO - 2016-02-29 09:50:50,885 DEBUG : BROWSER_OUTPUT: RSS: Main: 232792064 09:50:50 INFO - 2016-02-29 09:50:50,885 DEBUG : BROWSER_OUTPUT: 09:50:50 INFO - 2016-02-29 09:50:50,885 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:50:50 INFO - 2016-02-29 09:50:50,885 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:50:50 INFO - 2016-02-29 09:50:50,885 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:50:50 INFO - 2016-02-29 09:50:50,886 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:50:50 INFO - 2016-02-29 09:50:50,886 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;110;103;108;109;109;98;108;107;109 09:50:50 INFO - 2016-02-29 09:50:50,886 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;153;126;146;168;124;143;155;122;161;122 09:50:50 INFO - 2016-02-29 09:50:50,886 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;162;159;159;159;158;160;161;161;159 09:50:50 INFO - 2016-02-29 09:50:50,886 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;70;71;145;71;72;72;74;166;72 09:50:50 INFO - 2016-02-29 09:50:50,886 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;92;140;92;92;92;136;92;94;139 09:50:50 INFO - 2016-02-29 09:50:50,886 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;109;109;109;109;109;109;108 09:50:50 INFO - 2016-02-29 09:50:50,886 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;146;144;145;146;145;145;145;146 09:50:50 INFO - 2016-02-29 09:50:50,887 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;96;95;96;95;96;95;95;94 09:50:50 INFO - 2016-02-29 09:50:50,887 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;57;56;58;59;56;58;58;71;54 09:50:50 INFO - 2016-02-29 09:50:50,887 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;65;64;64;64;66;64;65;68;65 09:50:50 INFO - 2016-02-29 09:50:50,887 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;75;77;79;78;78;79;79;78;78 09:50:50 INFO - 2016-02-29 09:50:50,887 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;117;113;119;118;113;114;125;111;120;112 09:50:50 INFO - 2016-02-29 09:50:50,887 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;153;141;144;145;146;137;141;141;146;130 09:50:50 INFO - 2016-02-29 09:50:50,887 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;64;64;63;63;64;63;62;63;65 09:50:50 INFO - 2016-02-29 09:50:50,888 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:50:50 INFO - 2016-02-29 09:50:50,888 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:50:50 INFO - 2016-02-29 09:50:50,888 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,908 09:50:50 INFO - 2016-02-29 09:50:50,888 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:50:50 INFO - 2016-02-29 09:50:50,888 DEBUG : BROWSER_OUTPUT: __startTimestamp1456768250883__endTimestamp 09:50:50 INFO - 2016-02-29 09:50:50,888 DEBUG : BROWSER_OUTPUT: 09:50:50 INFO - 2016-02-29 09:50:50,896 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:50:50 INFO - 2016-02-29 09:50:50,896 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:50:50 INFO - 2016-02-29 09:50:50,896 DEBUG : BROWSER_OUTPUT: 09:50:50 INFO - 2016-02-29 09:50:50,896 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.80 Median:109.00 stddev:3.65 (3.3%) stddev-sans-first:3.87 09:50:50 INFO - 2016-02-29 09:50:50,896 DEBUG : BROWSER_OUTPUT: Values: 107.0 110.0 103.0 108.0 109.0 109.0 98.0 108.0 107.0 109.0 09:50:50 INFO - 2016-02-29 09:50:50,896 DEBUG : BROWSER_OUTPUT: 09:50:50 INFO - 2016-02-29 09:50:50,896 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.00 Median:149.50 stddev:17.40 (11.6%) stddev-sans-first:17.99 09:50:50 INFO - 2016-02-29 09:50:50,897 DEBUG : BROWSER_OUTPUT: Values: 153.0 126.0 146.0 168.0 124.0 143.0 155.0 122.0 161.0 122.0 09:50:50 INFO - 2016-02-29 09:50:50,897 DEBUG : BROWSER_OUTPUT: 09:50:50 INFO - 2016-02-29 09:50:50,897 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.90 Median:160.50 stddev:1.29 (0.8%) stddev-sans-first:1.30 09:50:50 INFO - 2016-02-29 09:50:50,897 DEBUG : BROWSER_OUTPUT: Values: 161.0 162.0 159.0 159.0 159.0 158.0 160.0 161.0 161.0 159.0 09:50:50 INFO - 2016-02-29 09:50:50,897 DEBUG : BROWSER_OUTPUT: 09:50:50 INFO - 2016-02-29 09:50:50,897 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.40 Median:71.50 stddev:35.72 (50.0%) stddev-sans-first:37.33 09:50:50 INFO - 2016-02-29 09:50:50,897 DEBUG : BROWSER_OUTPUT: Values: 71.0 70.0 71.0 145.0 71.0 72.0 72.0 74.0 166.0 72.0 09:50:50 INFO - 2016-02-29 09:50:50,897 DEBUG : BROWSER_OUTPUT: 09:50:50 INFO - 2016-02-29 09:50:50,897 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.10 Median:92.00 stddev:22.27 (24.2%) stddev-sans-first:23.03 09:50:50 INFO - 2016-02-29 09:50:50,897 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 140.0 92.0 92.0 92.0 136.0 92.0 94.0 139.0 09:50:50 INFO - 2016-02-29 09:50:50,897 DEBUG : BROWSER_OUTPUT: 09:50:50 INFO - 2016-02-29 09:50:50,897 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 09:50:50 INFO - 2016-02-29 09:50:50,897 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 108.0 09:50:50 INFO - 2016-02-29 09:50:50,897 DEBUG : BROWSER_OUTPUT: 09:50:50 INFO - 2016-02-29 09:50:50,897 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 09:50:50 INFO - 2016-02-29 09:50:50,898 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 146.0 144.0 145.0 146.0 145.0 145.0 145.0 146.0 09:50:50 INFO - 2016-02-29 09:50:50,898 DEBUG : BROWSER_OUTPUT: 09:50:50 INFO - 2016-02-29 09:50:50,898 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 09:50:50 INFO - 2016-02-29 09:50:50,898 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 96.0 95.0 96.0 95.0 96.0 95.0 95.0 94.0 09:50:50 INFO - 2016-02-29 09:50:50,898 DEBUG : BROWSER_OUTPUT: 09:50:50 INFO - 2016-02-29 09:50:50,898 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:58.40 Median:58.00 stddev:4.65 (8.0%) stddev-sans-first:4.90 09:50:50 INFO - 2016-02-29 09:50:50,898 DEBUG : BROWSER_OUTPUT: Values: 57.0 57.0 56.0 58.0 59.0 56.0 58.0 58.0 71.0 54.0 09:50:50 INFO - 2016-02-29 09:50:50,898 DEBUG : BROWSER_OUTPUT: 09:50:50 INFO - 2016-02-29 09:50:50,898 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.90 Median:65.00 stddev:1.29 (2.0%) stddev-sans-first:1.32 09:50:50 INFO - 2016-02-29 09:50:50,898 DEBUG : BROWSER_OUTPUT: Values: 64.0 65.0 64.0 64.0 64.0 66.0 64.0 65.0 68.0 65.0 09:50:50 INFO - 2016-02-29 09:50:50,898 DEBUG : BROWSER_OUTPUT: 09:50:50 INFO - 2016-02-29 09:50:50,898 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.70 Median:78.00 stddev:1.34 (1.7%) stddev-sans-first:1.27 09:50:50 INFO - 2016-02-29 09:50:50,898 DEBUG : BROWSER_OUTPUT: Values: 76.0 75.0 77.0 79.0 78.0 78.0 79.0 79.0 78.0 78.0 09:50:50 INFO - 2016-02-29 09:50:50,898 DEBUG : BROWSER_OUTPUT: 09:50:50 INFO - 2016-02-29 09:50:50,898 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:116.20 Median:117.50 stddev:4.39 (3.7%) stddev-sans-first:4.65 09:50:50 INFO - 2016-02-29 09:50:50,898 DEBUG : BROWSER_OUTPUT: Values: 117.0 113.0 119.0 118.0 113.0 114.0 125.0 111.0 120.0 112.0 09:50:50 INFO - 2016-02-29 09:50:50,899 DEBUG : BROWSER_OUTPUT: 09:50:50 INFO - 2016-02-29 09:50:50,899 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.40 Median:144.50 stddev:6.11 (4.2%) stddev-sans-first:5.14 09:50:50 INFO - 2016-02-29 09:50:50,899 DEBUG : BROWSER_OUTPUT: Values: 153.0 141.0 144.0 145.0 146.0 137.0 141.0 141.0 146.0 130.0 09:50:50 INFO - 2016-02-29 09:50:50,899 DEBUG : BROWSER_OUTPUT: 09:50:50 INFO - 2016-02-29 09:50:50,899 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.60 Median:64.00 stddev:0.97 (1.5%) stddev-sans-first:0.88 09:50:50 INFO - 2016-02-29 09:50:50,899 DEBUG : BROWSER_OUTPUT: Values: 65.0 64.0 64.0 63.0 63.0 64.0 63.0 62.0 63.0 65.0 09:50:50 INFO - 2016-02-29 09:50:50,899 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:50:50 INFO - 2016-02-29 09:50:50,899 DEBUG : BROWSER_OUTPUT: 09:50:51 INFO - 2016-02-29 09:50:51,554 INFO : Browser exited with error code: 0 09:50:51 INFO - 2016-02-29 09:50:51,604 INFO : Completed test kraken (00:00:57) 09:50:51 INFO - 2016-02-29 09:50:51,604 INFO : Starting test v8_7 09:50:51 INFO - 2016-02-29 09:50:51,604 DEBUG : operating with platform_type : linux_ 09:50:51 INFO - 2016-02-29 09:50:51,604 INFO : Initialising browser for v8_7 test... 09:50:51 INFO - 2016-02-29 09:50:51,611 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpvzuyKM/profile http://localhost:43682/getInfo.html 09:50:55 INFO - 2016-02-29 09:50:55,088 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:50:55 INFO - 2016-02-29 09:50:55,088 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:50:55 INFO - 2016-02-29 09:50:55,088 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:50:55 INFO - 2016-02-29 09:50:55,089 DEBUG : BROWSER_OUTPUT: __metrics 09:50:55 INFO - 2016-02-29 09:50:55,225 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] 09:50:56 INFO - 2016-02-29 09:50:56,094 INFO : Browser initialized. 09:50:56 INFO - 2016-02-29 09:50:56,094 INFO : Running cycle 1/1 for v8_7 test... 09:50:56 INFO - 2016-02-29 09:50:56,094 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpvzuyKM/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:51:07 INFO - 2016-02-29 09:51:07,777 DEBUG : BROWSER_OUTPUT: RSS: Main: 134803456 09:51:07 INFO - 2016-02-29 09:51:07,777 DEBUG : BROWSER_OUTPUT: 09:51:29 INFO - 2016-02-29 09:51:29,154 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43682/tests/v8_7/run.html (next: http://localhost:43682/tests/v8_7/run.html) 09:51:29 INFO - 2016-02-29 09:51:29,413 DEBUG : BROWSER_OUTPUT: RSS: Main: 424026112 09:51:29 INFO - 2016-02-29 09:51:29,413 DEBUG : BROWSER_OUTPUT: 09:51:29 INFO - 2016-02-29 09:51:29,413 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:51:29 INFO - 2016-02-29 09:51:29,413 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:51:29 INFO - 2016-02-29 09:51:29,414 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:51:29 INFO - 2016-02-29 09:51:29,414 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:51:29 INFO - 2016-02-29 09:51:29,414 DEBUG : BROWSER_OUTPUT: |0;Richards;167.9261125104954 09:51:29 INFO - 2016-02-29 09:51:29,414 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.9620587264929 09:51:29 INFO - 2016-02-29 09:51:29,414 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.3296089385475 09:51:29 INFO - 2016-02-29 09:51:29,414 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5170.103092783505 09:51:29 INFO - 2016-02-29 09:51:29,414 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1083.4236186348862 09:51:29 INFO - 2016-02-29 09:51:29,414 DEBUG : BROWSER_OUTPUT: |5;Earley;543.773790103317 09:51:29 INFO - 2016-02-29 09:51:29,415 DEBUG : BROWSER_OUTPUT: |6;Boyer;10610.526315789473 09:51:29 INFO - 2016-02-29 09:51:29,415 DEBUG : BROWSER_OUTPUT: |7;RegExp;24214.285714285714 09:51:29 INFO - 2016-02-29 09:51:29,415 DEBUG : BROWSER_OUTPUT: |8;Splay;533.0490405117271 09:51:29 INFO - 2016-02-29 09:51:29,415 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 09:51:29 INFO - 2016-02-29 09:51:29,415 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:51:29 INFO - 2016-02-29 09:51:29,415 DEBUG : BROWSER_OUTPUT: __startTimestamp1456768289406__endTimestamp 09:51:29 INFO - 2016-02-29 09:51:29,415 DEBUG : BROWSER_OUTPUT: 09:51:29 INFO - 2016-02-29 09:51:29,416 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:51:29 INFO - 2016-02-29 09:51:29,416 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:51:29 INFO - 2016-02-29 09:51:29,416 DEBUG : BROWSER_OUTPUT: 09:51:29 INFO - 2016-02-29 09:51:29,416 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.93 Median:167.93 stddev:0.00 (0.0%) 09:51:29 INFO - 2016-02-29 09:51:29,416 DEBUG : BROWSER_OUTPUT: Values: 167.9 09:51:29 INFO - 2016-02-29 09:51:29,416 DEBUG : BROWSER_OUTPUT: 09:51:29 INFO - 2016-02-29 09:51:29,416 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.96 Median:164.96 stddev:0.00 (0.0%) 09:51:29 INFO - 2016-02-29 09:51:29,416 DEBUG : BROWSER_OUTPUT: Values: 165.0 09:51:29 INFO - 2016-02-29 09:51:29,417 DEBUG : BROWSER_OUTPUT: 09:51:29 INFO - 2016-02-29 09:51:29,417 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.33 Median:279.33 stddev:0.00 (0.0%) 09:51:29 INFO - 2016-02-29 09:51:29,417 DEBUG : BROWSER_OUTPUT: Values: 279.3 09:51:29 INFO - 2016-02-29 09:51:29,417 DEBUG : BROWSER_OUTPUT: 09:51:29 INFO - 2016-02-29 09:51:29,417 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5170.10 Median:5170.10 stddev:0.00 (0.0%) 09:51:29 INFO - 2016-02-29 09:51:29,418 DEBUG : BROWSER_OUTPUT: Values: 5170.1 09:51:29 INFO - 2016-02-29 09:51:29,418 DEBUG : BROWSER_OUTPUT: 09:51:29 INFO - 2016-02-29 09:51:29,418 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1083.42 Median:1083.42 stddev:0.00 (0.0%) 09:51:29 INFO - 2016-02-29 09:51:29,418 DEBUG : BROWSER_OUTPUT: Values: 1083.4 09:51:29 INFO - 2016-02-29 09:51:29,418 DEBUG : BROWSER_OUTPUT: 09:51:29 INFO - 2016-02-29 09:51:29,419 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:543.77 Median:543.77 stddev:0.00 (0.0%) 09:51:29 INFO - 2016-02-29 09:51:29,419 DEBUG : BROWSER_OUTPUT: Values: 543.8 09:51:29 INFO - 2016-02-29 09:51:29,419 DEBUG : BROWSER_OUTPUT: 09:51:29 INFO - 2016-02-29 09:51:29,419 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10610.53 Median:10610.53 stddev:0.00 (0.0%) 09:51:29 INFO - 2016-02-29 09:51:29,419 DEBUG : BROWSER_OUTPUT: Values: 10610.5 09:51:29 INFO - 2016-02-29 09:51:29,419 DEBUG : BROWSER_OUTPUT: 09:51:29 INFO - 2016-02-29 09:51:29,419 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24214.29 Median:24214.29 stddev:0.00 (0.0%) 09:51:29 INFO - 2016-02-29 09:51:29,420 DEBUG : BROWSER_OUTPUT: Values: 24214.3 09:51:29 INFO - 2016-02-29 09:51:29,420 DEBUG : BROWSER_OUTPUT: 09:51:29 INFO - 2016-02-29 09:51:29,420 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:533.05 Median:533.05 stddev:0.00 (0.0%) 09:51:29 INFO - 2016-02-29 09:51:29,420 DEBUG : BROWSER_OUTPUT: Values: 533.0 09:51:29 INFO - 2016-02-29 09:51:29,420 DEBUG : BROWSER_OUTPUT: 09:51:29 INFO - 2016-02-29 09:51:29,420 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 09:51:29 INFO - 2016-02-29 09:51:29,420 DEBUG : BROWSER_OUTPUT: Values: 4835.7 09:51:29 INFO - 2016-02-29 09:51:29,421 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:51:29 INFO - 2016-02-29 09:51:29,421 DEBUG : BROWSER_OUTPUT: 09:51:30 INFO - 2016-02-29 09:51:30,046 INFO : Browser exited with error code: 0 09:51:30 INFO - 2016-02-29 09:51:30,062 INFO : Completed test v8_7 (00:00:38) 09:51:30 INFO - 2016-02-29 09:51:30,440 INFO : Completed test suite (00:20:05) 09:51:30 INFO - 2016-02-29 09:51:30,441 DEBUG : Working with test: dromaeo_css 09:51:30 INFO - 2016-02-29 09:51:30,441 DEBUG : Generating results file: dromaeo_css 09:51:30 INFO - 2016-02-29 09:51:30,443 DEBUG : Working with test: kraken 09:51:30 INFO - 2016-02-29 09:51:30,443 DEBUG : Generating results file: kraken 09:51:30 INFO - 2016-02-29 09:51:30,443 INFO : javascript benchmark 09:51:30 INFO - 2016-02-29 09:51:30,444 DEBUG : Working with test: v8_7 09:51:30 INFO - 2016-02-29 09:51:30,444 DEBUG : Generating results file: v8_7 09:51:30 INFO - 2016-02-29 09:51:30,444 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:51:30 INFO - 2016-02-29 09:51:30,494 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 09:51:30 INFO - 2016-02-29 09:51:30,494 DEBUG : process_Request line: dromaeo_css 7376.56 graph.html#tests=[[72,53,35]] 09:51:30 INFO - 2016-02-29 09:51:30,494 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:51:30 INFO - 2016-02-29 09:51:30,527 DEBUG : process_Request line: kraken 1475.80 graph.html#tests=[[232,53,35]] 09:51:30 INFO - 2016-02-29 09:51:30,527 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:51:30 INFO - 2016-02-29 09:51:30,557 DEBUG : process_Request line: v8_7 22911.08 graph.html#tests=[[230,53,35]] 09:51:30 INFO - 2016-02-29 09:51:30,558 INFO : javascript benchmark 09:51:30 INFO - 2016-02-29 09:51:30,562 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3331.16720819795, 3407.6421975692565, 3438.5873695156583, 3445.2592692571093, 3484.3031393721244, 5606.411326810688, 5607.495325420711, 5609.691466969321, 5610.382857885629, 5616.129499927518, 5563.833085007446, 5592.5805564582615, 5596.720163991794, 5623.128415095667, 5636.436356364362, 4961.702297862122, 5028.2709007193935, 5105.106606343884, 5106.7701953412, 5168.069747445458, 5015.623828212881, 5023.849284521452, 5026.773795179208, 5073.746312684351, 5112.0, 5088.389393272794, 5144.22836574515, 5159.097157997354, 5166.354205724285, 5169.457206993268, 5013.147764879979, 5074.847754567369, 5076.390833100052, 5130.666506677091, 5150.021495915764, 4928.964917367357, 4960.776765045545, 4992.650514463989, 5032.622553308516, 5103.821366252163, 5333.866653333659, 5341.7062061586985, 5349.277847490578, 5350.3312085989255, 5357.4106848246665, 5879.088741245071, 5942.851428714317, 5943.732532036069, 5943.881122377571, 5956.851078723023, 5787.305523337184, 5833.533317334645, 5860.882782344372, 5905.0, 5935.0, 5614.382417934024, 5716.085426331767, 5723.914141287884, 5725.341585717612, 5726.570507211975, 5980.970095149583, 6170.074488826712, 6185.752569897244, 6191.628502289877, 6199.039148931923, 5804.709764511757, 5806.738696758656, 5810.88378232433, 5845.678487683133, 5897.587168898136, 5967.31375891778, 6018.458338749533, 6024.909626355608, 6034.9396506035255, 6038.577299589075, 5740.1102829061565, 5849.385814489502, 5952.345242023374, 5953.613015154001, 5969.04495280753, 5907.911381329283, 5991.4907232884825, 5992.58051936369, 5996.700164991733, 6006.729697163638, 4586.747728874877, 4621.075784842978, 4626.306054091913, 4630.3748993886065, 4658.0, 4047.838086476508, 4076.9592304077173, 4118.588141185917, 4128.02991297045, 4176.749395036308, 5644.491995720405, 5662.773489060474, 5668.829935101953, 5717.799877004368, 5721.256236689203, 6037.849053773691, 6050.334463209044, 6055.000924847351, 6057.606255593372, 6059.8485037873525, 5433.728313584305, 5470.0, 5608.719564021782, 5649.06790379591, 5671.716414179357, 5234.136367499396, 5285.365756109215, 5292.788288468418, 5306.310179676618, 5306.708131052752, 6297.244330680256, 6297.401746834135, 6313.053042043638, 6315.810525684236, 6316.494680425647, 5920.496757775637, 5948.791792287249, 6037.728302226323, 6061.454469097803, 6073.635581865102, 6119.8776024478375, 6199.721012554536, 6229.937700622936, 6233.438990490697, 6242.157308763259, 5677.460641239076, 6003.0695242237525, 6016.4885984690045, 6022.728977196124, 6070.908936366046, 5962.97018514922, 5975.521958243243, 5983.760649573968, 6024.427679370453, 6096.969515152396, 5810.738516766671, 5899.587028907935, 5906.114082887601, 5906.763729450774, 5909.025010873158, 5649.943500564942, 5797.710114494174, 5887.352391236875, 5888.499477544457, 5896.469317761422, 5709.314882214161, 5810.244668193108, 5811.738471768866, 5812.854678632899, 5841.853953651193, 5847.883042339215, 5854.590178687451, 5881.353051164283, 5884.764609415575, 5885.0, 5551.472610102034, 5676.035074037506, 5720.513756330592, 5749.453801888814, 5755.021646320052], "unit": "ms", "name": "dojo.html", "value": 5524.387312191565}, {"lowerIsBetter": false, "replicates": [585.7071464267864, 611.4191518057846, 613.9109829074785, 630.1492984470965, 638.5402510192661, 38546.0, 39046.60953390465, 39052.60947390525, 39112.413313800316, 39114.0, 43874.561254387445, 44402.0, 44508.55491445085, 44511.33233001507, 44573.77713111438, 8140.796480087986, 8258.174182581724, 9117.908820911789, 9276.721698349027, 10320.948395258014, 10535.841962370532, 10659.78680426391, 10660.946695266513, 10667.626633067845, 10671.413072281022, 10793.244472886901, 10897.237193396466, 10898.128149748041, 10904.781904361947, 10910.672679819618, 10741.087007604363, 10800.783984320347, 10815.134789216843, 10830.67507974762, 10839.620613278537, 10514.631987880464, 10622.893771062269, 10624.256302058859, 10643.893561064368, 10644.414557199429, 42694.78652606748, 43124.56875431237, 43149.56850431487, 43158.56841431577, 43161.0, 3699.408094704834, 3993.9201215975518, 4224.070704445017, 4380.649548036181, 4386.232409328387, 31733.20666983321, 31849.0, 31972.0, 32193.83903080493, 32258.193545161324, 4070.959290407088, 4120.835166593332, 4153.552551382345, 4155.168966206771, 4183.853565125206, 306115.0, 306941.0, 306946.0, 307424.0, 307862.46068769734, 61369.38630613726, 61661.0, 61864.38135618586, 62132.68933655303, 70243.0, 61872.381276186665, 62257.06614400788, 62313.376866231665, 62346.0, 62598.68700656467, 18429.170687319103, 18620.0, 18620.44138675842, 18643.44069677912, 18702.251909923452, 14204.431822726976, 14412.567622971328, 14429.783553246709, 14435.783463248059, 14451.638709032359, 20734.896325518275, 21047.15811367559, 21051.263205787724, 21077.0, 21115.788842111688, 8224.0, 8438.831223375499, 8515.104864346153, 8561.1866872647, 8613.44012639189, 1022.9283950123569, 1114.9275297105662, 1131.1968502363413, 1131.3551275772732, 1131.801934661431, 29892.85053574718, 30331.204529907238, 32510.674893250765, 32898.50652240218, 32913.0, 1055.6410820320973, 1119.2221406122667, 1132.9376884271444, 1203.1036877526124, 1207.6618546806733, 13371.41837007939, 14592.0, 14615.56153315381, 15058.247087645574, 15267.465638702592, 272530.0, 275391.0, 275616.0, 275958.0, 276994.0, 54577.727111364984, 55174.44825551773, 55195.0, 55253.447465525634, 55336.0, 1378.427952399743, 1414.610981979943, 1438.697873446588, 1503.503843731566, 1528.0678785940568, 28641.283967900137, 28649.28376790597, 29078.41843163167, 29286.41427171487, 29660.55509167321, 44584.10831783281, 44939.32591011072, 44941.77529112333, 45026.54973450224, 45149.32276015928, 255585.0, 319792.0, 329643.0, 329645.0, 331824.0, 31087.68912310848, 31368.529472058395, 31617.52573711442, 31686.207844804063, 31709.524357135124, 41672.37491437692, 42375.0, 42741.0, 43782.0, 43807.561924381625, 37783.81108094442, 37897.4315385275, 37988.62011379851, 39003.0, 39306.2138757229, 37964.0, 40523.39214911838, 41058.38412423876, 42222.0, 42915.785421072695, 36777.63222367742, 37248.255034899696, 37564.0, 38035.0, 38046.23907521779, 32235.032949011566, 32325.0, 33910.0, 34499.82750086234, 34657.30685386329, 23989.640155398032, 25239.36901577475, 25792.323498972506, 25864.094756683426, 26858.73141268562], "unit": "ms", "name": "ext.html", "value": 19950.15259496417}, {"lowerIsBetter": false, "replicates": [379.62575476774066, 381.33457117330227, 382.73887540553875, 386.75054589789585, 391.33277761416787, 14602.853971460283, 15574.688506229868, 17152.399666011694, 19009.42971710849, 19018.239270429185, 19001.714974275397, 19182.52043698908, 19336.226550937947, 19521.804781952178, 19571.217151313966, 6145.569810113294, 6238.376162383748, 6270.435660790528, 6284.5600807943465, 6285.308616052231, 4621.0, 4751.667383283154, 6187.783427580036, 6695.096162018138, 6927.7228910843505, 5989.730462129215, 6848.007038979345, 6865.038894554767, 6911.067005954182, 6913.827154321157, 5131.615128865331, 6141.170941922828, 6141.508679305645, 6142.140100385905, 6151.907721384206, 5396.406395296514, 5512.448755124496, 5515.779368825242, 5583.832485025456, 5594.580406469531, 12984.805227921588, 13188.340582970908, 13193.86806131936, 16843.41048063312, 20843.270485533085, 2474.096954611554, 2493.463905260378, 2529.696436427622, 2530.8481491110592, 2552.8468291902545, 9818.509074546315, 9919.80160396788, 9966.252531060127, 10175.84736228957, 10601.893981060168, 1972.8421726261865, 1983.5041239690077, 1987.1951859496926, 2001.1495114576392, 2015.9899200503903, 65655.0, 67211.66394168047, 67731.66134169347, 68503.3149668507, 68818.31181688169, 11058.11535077192, 11286.209965302514, 11316.6039188628, 11331.0, 11402.771944561062, 14767.40930362795, 14890.255487225812, 14921.179335136672, 14926.626834329012, 14964.176970266522, 8222.0, 8417.0, 8434.114417986146, 8533.402661813738, 8791.780205494913, 7719.696630164624, 7758.262965018314, 7807.921920780719, 7886.0, 7926.088499822594, 4192.45498085247, 4791.0, 4845.563899249085, 4847.80608775652, 4877.951220487821, 1702.1630308291844, 1777.512157739722, 1835.5392706697469, 1836.4949638849423, 1882.9905850470934, 955.7753927826955, 958.9856152157723, 995.6117114325419, 1041.5677493839971, 1047.9004494573003, 16418.589535261715, 16686.916565417338, 17018.574535636708, 17252.396166134124, 17496.68774841892, 951.4909523404945, 960.9423434593947, 997.4962643864802, 1023.0843395161289, 1082.0423924826428, 10045.899541004497, 10125.898741012643, 10254.436006019741, 10259.333143345808, 10336.741581460523, 643198.0, 653254.0, 653918.0, 679042.0, 682523.0, 82459.0, 83374.0, 85057.57471212605, 85250.0, 86398.56800715956, 1153.7692461507731, 1162.0587324267372, 1164.062929341871, 1201.375284851872, 1210.9455074521668, 16147.0, 16256.105914174834, 16336.836631633532, 16337.182033191655, 16546.0, 38085.80957095197, 38274.0, 38510.42234366543, 38896.6110338893, 39634.603653964245, 50203.0, 50339.74830125972, 50754.238686420475, 52868.20697689461, 54396.46422982494, 10251.897481025095, 10305.948470257601, 10327.690169294932, 10443.738906527398, 10537.683869483928, 14183.787243191568, 18676.81323186751, 18721.25114995385, 18924.716129258348, 19074.427767167006, 11850.525978960744, 14292.499762508261, 14893.925530372278, 15023.323950422477, 15242.923785381003, 14334.856651433352, 14758.704825903207, 14924.776128357866, 14929.35854771531, 15588.610284742519, 15401.537953861402, 15615.843841561438, 15976.920115399349, 16219.18904054769, 16220.756688649444, 9386.67146649864, 9741.951290243787, 9869.062439068277, 10648.520816563117, 10668.613080299512, 15759.842401575837, 15796.68406631884, 15797.763033554276, 16067.0, 16128.677426451171], "unit": "ms", "name": "jquery.html", "value": 9552.124326083573}, {"lowerIsBetter": false, "replicates": [1551.6198531359819, 1609.903405795653, 1609.935602575897, 1769.380716749138, 1858.4424672598225, 7170.498065135443, 7628.351590114833, 7635.312821846032, 7636.503627264224, 7642.426817988652, 6716.1940567132015, 6849.075374824397, 7393.8151546211375, 7578.166401695809, 7601.619919004055, 2321.2223904991806, 2336.2991102669216, 2365.0, 2367.7868991790733, 2368.4670949036417, 2048.6414877396396, 2307.8038366738847, 2372.9762702373014, 2373.9881300593474, 2374.6556749271344, 2033.1460786469718, 2161.3083813179787, 2194.9122035118576, 2198.967015494769, 2200.4058904095964, 2278.2481781012307, 2288.199130304397, 2298.10373954158, 2366.3610825077135, 2369.099742098009, 2288.78256565626, 2293.5756884976167, 2294.7246330440453, 2306.8039216666602, 2314.8148148148275, 7598.658060387296, 7655.655495502716, 7669.0, 7672.961635191788, 7676.232376762335, 4704.270838020112, 4800.423949126127, 4803.351547541107, 4805.543473370024, 4807.855764327076, 3122.531620256957, 3140.795848269855, 3149.118246890874, 3168.8415579221178, 3191.1702957231055, 1101.0971003776906, 1133.6429024857143, 1135.2564070533813, 2029.7767245602972, 2095.4447071526056, 10201.0, 10287.89712102877, 10303.227257955683, 10318.948405258001, 10331.690049298459, 4121.052158003616, 4165.437665915123, 4249.490061192615, 4250.596193361625, 4255.829766809293, 8792.736217913473, 8797.692080777142, 8799.692010779592, 8808.427452215585, 8821.911780882108, 4685.5782979532005, 4688.343631891538, 4694.718316900997, 4712.835050773207, 4721.0, 4327.242732521796, 4333.479982402132, 4380.342948557742, 4385.079133382025, 4387.780610969438, 3480.3039392121173, 3493.7729047611824, 3495.4582039783872, 3504.0363899927725, 3540.309639620301, 1027.5427434791445, 1053.146950969717, 1062.644014255233, 1074.548689550375, 1085.4138765066934, 1199.3583432863377, 1206.1014544164655, 1242.5588915934893, 1418.1065928465207, 1435.8564143585559, 7288.817779555554, 7349.154847192534, 7498.287662672037, 7501.287377699219, 7502.687029769769, 642.6047980491963, 649.574528683713, 654.0842820051965, 668.6222284409293, 804.4811096842503, 5807.245058142415, 5845.269341332333, 5853.2976042875125, 5864.237649105674, 5872.060470324727, 8775.122487751174, 8798.956005219932, 9025.729228123168, 9032.819343613091, 9091.454512729257, 8867.556622168866, 8899.243564297107, 8909.465432073966, 8919.0, 8927.24118449926, 7214.531055481377, 7229.566226026455, 7355.5586664800285, 7361.815954601285, 7365.9631701841145, 7415.555066696015, 7419.109706835215, 7469.215732348025, 7475.177730449755, 7485.925140748523, 8106.229908158715, 8463.873041904499, 8465.0, 8481.830363392573, 8488.83022339562, 8355.373346998878, 8460.196281353261, 8474.915250847413, 8545.700900468453, 8576.0, 6924.58452492852, 6970.093887794555, 6992.1259842519685, 7097.858042839011, 7109.111361079865, 7298.744543940937, 7361.447891408059, 7387.519811212254, 7388.445866560137, 7389.889151662622, 6093.268807743099, 6949.235584085847, 7065.6820443081215, 7174.748883789042, 7312.415006799337, 6494.415502604788, 6542.0, 6991.510594258557, 6996.090508233897, 7060.858782824417, 6132.724027418866, 7029.05107810439, 7040.155181378267, 7044.260352663102, 7044.577325360495, 6489.123968264224, 6526.67366631676, 6527.216733991761, 6527.934720652733, 6531.967340163459, 6487.675616219264, 6606.207255129415, 6608.867822643617, 6636.2368327642935, 6676.0987266718375], "unit": "ms", "name": "mootools.html", "value": 4464.631958644789}, {"lowerIsBetter": false, "replicates": [355.0626346445384, 359.9514065601143, 360.2614639988024, 361.2738395824392, 363.06148605853883, 6116.9388306116925, 6128.172696685946, 6143.293521245058, 6162.938370616293, 6205.81382558524, 5872.471477567018, 5925.962956482609, 6027.246594175728, 6120.081987701847, 6129.908051379233, 4770.666053376265, 4778.976105119487, 4782.234842425212, 4786.282057691356, 4792.466410748561, 4524.9095018099615, 4779.713217206961, 4784.97607511962, 4789.98820495391, 4802.711837289774, 4593.333966574844, 4622.58396744293, 4692.41344831896, 4714.151452738506, 4719.362886010396, 4672.509386514401, 4695.506971767966, 4702.8589142325445, 4704.882377940545, 4713.835015774465, 4185.560516145792, 4187.016051227959, 4187.20443115807, 4192.874213773592, 4197.160567886435, 5705.77176912923, 5814.302283725938, 5828.970855145697, 5829.562782791265, 5837.970810145922, 5560.082586373243, 5571.498565129117, 5572.108462645957, 5575.414581468968, 5579.38626751057, 6468.902966455507, 6469.93530064698, 6478.902816457757, 6485.0, 6485.3514648534765, 4695.1783437898575, 4707.905841883177, 4712.6229901607785, 4713.104510143062, 4719.740414277213, 6608.207015158212, 6739.090222819955, 6784.76253331136, 6790.898136527907, 6800.52396332252, 4703.95296047042, 4733.053389322149, 4734.976325118352, 4736.550027747358, 4738.905221895543, 4771.928421073687, 4786.138495070921, 4787.066522028171, 4792.496787837296, 4805.6155507559315, 5337.572994160458, 5354.946450535523, 5448.83653490396, 5456.7544460499375, 5458.235846981426, 6510.023496475566, 6524.575902566318, 6548.27968923418, 6551.967240163769, 6555.967220163964, 1674.087622245879, 1676.1535424610493, 1689.847913687774, 1694.0344003917648, 1694.4154266777941, 1128.7291050147905, 1137.7724455108846, 1140.560884059643, 1144.7023773818867, 1165.7143999719954, 6413.775517856852, 6420.1974753155855, 6422.293547709748, 6423.164988551458, 6423.646699431576, 6579.76970806029, 6743.055972163901, 6794.0827988221945, 6799.082123913307, 6802.761903333359, 6328.082428047907, 6341.397567231105, 6499.935000650027, 6507.381798729113, 6514.283428822826, 3672.779633222015, 3701.7414079213195, 3714.9442758358646, 3715.851365945332, 3716.1638631307737, 6846.110005699227, 7053.047838541834, 7087.397571206505, 7090.716371345088, 7095.361417472453, 6997.090378250795, 7051.788446346618, 7054.682539285642, 7063.540869843444, 7065.964670176719, 7103.644817759195, 7105.75129870452, 7109.004739336497, 7113.608751518613, 7116.110486189226, 6777.648601182456, 6848.691808868513, 6893.414059804973, 6899.655017249017, 6901.689423975833, 6729.596224226562, 6736.023276624764, 6740.662966851736, 6741.797746067626, 6742.123523942052, 7074.71701131949, 7083.574985500886, 7086.326798954091, 7091.2554181810165, 7099.645017749195, 4621.630269578359, 4733.6213102951, 4738.265568836836, 4771.5705586496, 4777.713337199779, 6813.557118787263, 6817.590944543145, 6819.795406137824, 6821.2155602106395, 6850.691718872563, 6650.534462587572, 6653.334666533308, 6663.433608143168, 6666.6000039995815, 6668.499862510428, 6479.902801458076, 6571.145751052332, 6636.601803891782, 6638.0374845648075, 6642.335766423319, 6474.643894585654, 6499.935000649933, 6500.869982600416, 6501.577397469149, 6507.316731743099, 6633.900491492728, 6636.4027237546925, 6642.734290628321, 6648.634325112165, 6651.301613330531, 6266.37336266388, 6366.267879193767, 6484.383983521558, 6486.967565162144, 6487.3836985486305], "unit": "ms", "name": "prototype.html", "value": 4988.309762406299}, {"lowerIsBetter": false, "replicates": [785.5483097219101, 806.475612296521, 837.4807619276046, 925.7361651929201, 934.439336398161, 14556.563303100898, 14955.55133346, 14959.551213463586, 14983.175925324116, 15112.319945602447, 11878.94060529696, 12590.929770969478, 12759.17065390749, 12849.421776020079, 12899.16155449895, 870.4864130163203, 871.7166920750766, 873.2926329672986, 873.5588527793456, 875.6672464463495, 879.0111124984393, 879.5953861223845, 879.9472031678088, 881.0748713850463, 886.6586364249746, 919.4437365393941, 920.8296465153934, 921.7511271956602, 922.9538523073819, 929.3401684803797, 911.8951320598084, 919.7792529792869, 920.4523308631387, 921.3596550397476, 935.4153653966271, 676.5737585321216, 679.8538314262457, 682.3551743602297, 689.2969171445149, 695.5513693667607, 13463.124896881769, 13592.320383980861, 13614.727705445835, 13646.727065458635, 13649.181049136983, 11419.314841109477, 11501.827472587918, 12044.518219271218, 12417.130800843945, 13525.120867143603, 11047.668569942914, 11068.944655276753, 11159.944200279027, 11170.0, 11493.367864767435, 3920.9803950980063, 3959.78221197834, 3983.5618082010956, 3989.202159568098, 4089.488813898263, 44814.775926120485, 45002.54997450049, 45188.548114518766, 51248.23127653051, 51272.48727512715, 10750.73123171927, 10914.21567411195, 10920.12638988879, 11150.49822757978, 11162.832557511643, 19356.4193074208, 19456.513587160433, 19458.416247512316, 19465.02674866251, 19478.415647530597, 8286.917130828735, 8299.917000829915, 8318.70884519039, 8349.415540912078, 8353.916460835435, 4719.079779442975, 4769.213079841857, 4770.546798054179, 4841.757912104381, 4883.218685010453, 3412.6587341265677, 3433.0044287156943, 3434.5019972103905, 3440.3291358184915, 3440.587129544421, 624.341319907502, 632.6713900808265, 638.6200210874522, 642.5534253693731, 652.3802387731674, 668.6623255256162, 703.5040296590892, 725.5972935021008, 729.3800269770652, 745.697992313114, 9152.54237288133, 9276.493098467863, 9415.905840941503, 9555.187809036172, 10023.749406264902, 689.8551304226169, 708.5429897716048, 711.6441779110445, 754.2344520311888, 770.6994272233833, 4271.061541525605, 4594.517575654508, 4642.675012749075, 4657.650676199299, 4734.928976065362, 60193.0, 63636.36363636304, 64331.35668643347, 69830.30169698238, 70825.0, 52579.0, 53558.73220633872, 57583.42416575781, 57957.0, 58150.12774808381, 42566.57433425688, 47904.28143577849, 48974.99999999929, 50528.49471505238, 54925.45074549283, 9234.815303694022, 9429.009953954735, 9642.373245739005, 9943.558184063415, 10026.899731002888, 36333.0, 39564.40653390144, 40502.0, 40511.594884050784, 41045.794771025954, 47704.52295477001, 50252.49747502478, 50321.0, 51113.48886511236, 51207.48792512027, 11259.380734059705, 11327.603533876274, 11849.170558060852, 12163.939180304396, 12369.752604948031, 13051.673708157372, 13062.020348473712, 13151.27667978232, 14314.785278220626, 14465.132092074089, 11822.11334149925, 12184.390780460764, 12378.442970066188, 12645.24128552253, 12664.556740514037, 12199.390030498262, 12438.129330946747, 12500.499980001063, 12768.936155319165, 12882.35588220604, 12469.376531173224, 12966.092373533762, 13231.867681323449, 13249.668758281121, 13315.733685326433, 10434.321769084985, 10504.369737815756, 10558.260921735711, 10699.786004280026, 10961.83557246626, 10458.947705261426, 10533.4733263335, 10603.946980265358, 10628.415437150721, 10641.201909856947], "unit": "ms", "name": "yui.html", "value": 6870.903701661563}], "name": "dromaeo_css", "value": 7376.56029774078}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [107.0, 110.0, 103.0, 108.0, 109.0, 109.0, 98.0, 108.0, 107.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 106.8}, {"lowerIsBetter": true, "replicates": [153.0, 126.0, 146.0, 168.0, 124.0, 143.0, 155.0, 122.0, 161.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.0}, {"lowerIsBetter": true, "replicates": [161.0, 162.0, 159.0, 159.0, 159.0, 158.0, 160.0, 161.0, 161.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 159.9}, {"lowerIsBetter": true, "replicates": [71.0, 70.0, 71.0, 145.0, 71.0, 72.0, 72.0, 74.0, 166.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 88.4}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 140.0, 92.0, 92.0, 92.0, 136.0, 92.0, 94.0, 139.0], "unit": "ms", "name": "audio-oscillator", "value": 106.1}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 108.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 146.0, 144.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.2}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 94.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [57.0, 57.0, 56.0, 58.0, 59.0, 56.0, 58.0, 58.0, 71.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 58.4}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 64.0, 64.0, 64.0, 66.0, 64.0, 65.0, 68.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.9}, {"lowerIsBetter": true, "replicates": [76.0, 75.0, 77.0, 79.0, 78.0, 78.0, 79.0, 79.0, 78.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.7}, {"lowerIsBetter": true, "replicates": [117.0, 113.0, 119.0, 118.0, 113.0, 114.0, 125.0, 111.0, 120.0, 112.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 116.2}, {"lowerIsBetter": true, "replicates": [153.0, 141.0, 144.0, 145.0, 146.0, 137.0, 141.0, 141.0, 146.0, 130.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 142.4}, {"lowerIsBetter": true, "replicates": [65.0, 64.0, 64.0, 63.0, 63.0, 64.0, 63.0, 62.0, 63.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 63.6}], "name": "kraken", "value": 1475.8000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.9261125104954], "unit": "score", "name": "Richards", "value": 210.22340999999997}, {"lowerIsBetter": false, "replicates": [164.9620587264929], "unit": "score", "name": "DeltaBlue", "value": 400.8073159999999}, {"lowerIsBetter": false, "replicates": [279.3296089385475], "unit": "score", "name": "Encrypt", "value": 952.9279799999998}, {"lowerIsBetter": false, "replicates": [5170.103092783505], "unit": "score", "name": "Decrypt", "value": 51.48466001994015}, {"lowerIsBetter": false, "replicates": [1083.4236186348862], "unit": "score", "name": "RayTrace", "value": 683.0098469999997}, {"lowerIsBetter": false, "replicates": [543.773790103317], "unit": "score", "name": "Earley", "value": 1225.6254570000006}, {"lowerIsBetter": false, "replicates": [10610.526315789473], "unit": "score", "name": "Boyer", "value": 62.81149305555559}, {"lowerIsBetter": false, "replicates": [24214.285714285714], "unit": "score", "name": "RegExp", "value": 37.621799410029524}, {"lowerIsBetter": false, "replicates": [533.0490405117271], "unit": "score", "name": "Splay", "value": 152.87711600000006}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 22911.083573836815}]} 09:51:30 INFO - RETURN: dromaeo_css: 7376.56 09:51:30 INFO - RETURN: kraken: 1475.80 09:51:30 INFO - RETURN: v8_7: 22911.08 09:51:30 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "22911.08"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1475.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7376.56"}}} 09:51:30 INFO - Return code: 0 09:51:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 09:51:30 INFO - # TBPL SUCCESS # 09:51:30 INFO - Running post-action listener: _resource_record_post_action 09:51:30 INFO - Running post-run listener: _resource_record_post_run 09:51:31 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 315392; Write bytes: 505966592; Read time: 44; Write time: 538176 09:51:31 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 239263744; Read time: 0; Write time: 382144 09:51:31 INFO - run-tests - Wall time: 1207s; CPU: 13.0%; Read bytes: 315392; Write bytes: 266104832; Read time: 44; Write time: 156028 09:51:31 INFO - Running post-run listener: _upload_blobber_files 09:51:31 INFO - Blob upload gear active. 09:51:31 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:51:31 INFO - Copying logs to upload dir... 09:51:31 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1253.386380 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-02-29 09:51:31.697182) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-29 09:51:31.703045) ========= 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=4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009941 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456758320/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-29 09:51:31.760003) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:51:31.760757) ========= 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=4dd8011cc2821079d74a932a000001ae-1456767036.297846-1871794092 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004558 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:51:31.813396) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-29 09:51:31.814126) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-29 09:51:31.814798) ========= ========= Total master_lag: 0.28 =========