builder: b2g-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-008 starttime: 1447955243.65 results: success (0) buildid: 20151119083048 builduid: 0af28ab933c940c49866a03bdda16e7a revision: b69c86a07756c1ff1a0b3cf14faa06968298b5b1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:23.646516) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:23.646877) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:23.647187) ========= 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=7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006556 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:23.667140) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:23.667450) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:23.675395) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:23.675657) ========= 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=7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.003312 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:23.725198) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:23.725504) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:23.726143) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:23.726409) ========= 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=7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-19 09:47:24-- 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% 217M=0s 2015-11-19 09:47:24 (217 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.197325 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:23.935800) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:23.936143) ========= 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=7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018059 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:23.985830) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:23.986204) ========= bash -c 'python archiver_client.py mozharness --repo integration/b2g-inbound --rev b69c86a07756c1ff1a0b3cf14faa06968298b5b1 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/b2g-inbound --rev b69c86a07756c1ff1a0b3cf14faa06968298b5b1 --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=7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-19 09:47:24,729 truncating revision to first 12 chars 2015-11-19 09:47:24,729 Setting DEBUG logging. 2015-11-19 09:47:24,729 attempt 1/10 2015-11-19 09:47:24,729 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/b69c86a07756?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-19 09:47:24,977 unpacking tar archive at: b2g-inbound-b69c86a07756/testing/mozharness/ program finished with exit code 0 elapsedTime=0.516805 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:24.515966) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:24.516280) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:24.525907) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:24.526270) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 09:47:24.526747) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2015-11-19 09:47:24.527087) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound 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', 'B2G-Inbound', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'B2G-Inbound'] 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=7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:47:25 INFO - MultiFileLogger online at 20151119 09:47:25 in /builds/slave/test-pgo 09:47:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound 09:47:25 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:47:25 INFO - {'append_to_log': False, 09:47:25 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:47:25 INFO - 'blob_upload_branch': 'B2G-Inbound', 09:47:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:47:25 INFO - 'branch': 'B2G-Inbound', 09:47:25 INFO - 'buildbot_json_path': 'buildprops.json', 09:47:25 INFO - 'config_files': ('talos/linux_config.py',), 09:47:25 INFO - 'default_actions': ('clobber', 09:47:25 INFO - 'read-buildbot-config', 09:47:25 INFO - 'download-and-extract', 09:47:25 INFO - 'populate-webroot', 09:47:25 INFO - 'create-virtualenv', 09:47:25 INFO - 'install', 09:47:25 INFO - 'run-tests'), 09:47:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:47:25 INFO - 'download_minidump_stackwalk': True, 09:47:25 INFO - 'download_symbols': 'ondemand', 09:47:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:47:25 INFO - 'tooltool.py': '/tools/tooltool.py', 09:47:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:47:25 INFO - '/tools/misc-python/virtualenv.py')}, 09:47:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:47:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:47:25 INFO - 'installer_path': 'installer.exe', 09:47:25 INFO - 'log_level': 'info', 09:47:25 INFO - 'log_name': 'talos', 09:47:25 INFO - 'log_to_console': True, 09:47:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:47:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:47:25 INFO - 'opt_config_files': (), 09:47:25 INFO - 'pip_index': False, 09:47:25 INFO - 'sps_profile': False, 09:47:25 INFO - 'sps_profile_interval': 0, 09:47:25 INFO - 'suite': 'dromaeojs', 09:47:25 INFO - 'system_bits': '64', 09:47:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:47:25 INFO - 'title': 'talos-linux64-ix-008', 09:47:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:47:25 INFO - 'use_talos_json': True, 09:47:25 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:47:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:47:25 INFO - 'work_dir': 'build'} 09:47:25 INFO - ##### 09:47:25 INFO - ##### Running clobber step. 09:47:25 INFO - ##### 09:47:25 INFO - Running pre-action listener: _resource_record_pre_action 09:47:25 INFO - Running main action method: clobber 09:47:25 INFO - rmtree: /builds/slave/test-pgo/build 09:47:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:47:26 INFO - Running post-action listener: _resource_record_post_action 09:47:26 INFO - ##### 09:47:26 INFO - ##### Running read-buildbot-config step. 09:47:26 INFO - ##### 09:47:26 INFO - Running pre-action listener: _resource_record_pre_action 09:47:26 INFO - Running main action method: read_buildbot_config 09:47:26 INFO - Using buildbot properties: 09:47:26 INFO - { 09:47:26 INFO - "properties": { 09:47:26 INFO - "buildnumber": 981, 09:47:26 INFO - "product": "firefox", 09:47:26 INFO - "script_repo_revision": "production", 09:47:26 INFO - "builddir": "b2g-inbound_ubuntu64_hw_test-dromaeojs", 09:47:26 INFO - "repository": "", 09:47:26 INFO - "buildername": "Ubuntu HW 12.04 x64 b2g-inbound pgo talos dromaeojs", 09:47:26 INFO - "buildid": "20151119083048", 09:47:26 INFO - "slavename": "talos-linux64-ix-008", 09:47:26 INFO - "pgo_build": "True", 09:47:26 INFO - "basedir": "/builds/slave/test-pgo", 09:47:26 INFO - "project": "", 09:47:26 INFO - "platform": "ubuntu64_hw", 09:47:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:47:26 INFO - "slavebuilddir": "test", 09:47:26 INFO - "scheduler": "tests-b2g-inbound-linux64-pgo-talos", 09:47:26 INFO - "branch": "b2g-inbound", 09:47:26 INFO - "repo_path": "integration/b2g-inbound", 09:47:26 INFO - "stage_platform": "linux64-pgo", 09:47:26 INFO - "builduid": "0af28ab933c940c49866a03bdda16e7a", 09:47:26 INFO - "revision": "b69c86a07756c1ff1a0b3cf14faa06968298b5b1" 09:47:26 INFO - }, 09:47:26 INFO - "sourcestamp": { 09:47:26 INFO - "repository": "", 09:47:26 INFO - "hasPatch": false, 09:47:26 INFO - "project": "", 09:47:26 INFO - "branch": "b2g-inbound-linux64-pgo-talos", 09:47:26 INFO - "changes": [ 09:47:26 INFO - { 09:47:26 INFO - "category": null, 09:47:26 INFO - "files": [ 09:47:26 INFO - { 09:47:26 INFO - "url": null, 09:47:26 INFO - "name": "https://queue.taskcluster.net/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 09:47:26 INFO - } 09:47:26 INFO - ], 09:47:26 INFO - "repository": "", 09:47:26 INFO - "rev": "b69c86a07756c1ff1a0b3cf14faa06968298b5b1", 09:47:26 INFO - "who": "sendchange", 09:47:26 INFO - "when": 1447955219, 09:47:26 INFO - "number": 6683971, 09:47:26 INFO - "comments": "", 09:47:26 INFO - "project": "", 09:47:26 INFO - "at": "Thu 19 Nov 2015 09:46:59", 09:47:26 INFO - "branch": "b2g-inbound-linux64-pgo-talos", 09:47:26 INFO - "revlink": "", 09:47:26 INFO - "properties": [ 09:47:26 INFO - [ 09:47:26 INFO - "buildid", 09:47:26 INFO - "20151119083048", 09:47:26 INFO - "Change" 09:47:26 INFO - ], 09:47:26 INFO - [ 09:47:26 INFO - "builduid", 09:47:26 INFO - "0af28ab933c940c49866a03bdda16e7a", 09:47:26 INFO - "Change" 09:47:26 INFO - ], 09:47:26 INFO - [ 09:47:26 INFO - "pgo_build", 09:47:26 INFO - "True", 09:47:26 INFO - "Change" 09:47:26 INFO - ] 09:47:26 INFO - ], 09:47:26 INFO - "revision": "b69c86a07756c1ff1a0b3cf14faa06968298b5b1" 09:47:26 INFO - } 09:47:26 INFO - ], 09:47:26 INFO - "revision": "b69c86a07756c1ff1a0b3cf14faa06968298b5b1" 09:47:26 INFO - } 09:47:26 INFO - } 09:47:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 09:47:26 INFO - Running post-action listener: _resource_record_post_action 09:47:26 INFO - ##### 09:47:26 INFO - ##### Running download-and-extract step. 09:47:26 INFO - ##### 09:47:26 INFO - Running pre-action listener: _resource_record_pre_action 09:47:26 INFO - Running main action method: download_and_extract 09:47:26 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:47:26 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:47:26 INFO - https://queue.taskcluster.net/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:47:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/test_packages.json 09:47:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/test_packages.json 09:47:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:47:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:47:29 INFO - Downloaded 1302 bytes. 09:47:29 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:47:29 INFO - Using the following test package requirements: 09:47:29 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 09:47:29 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:47:29 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:47:29 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:47:29 INFO - u'jsshell-linux-x86_64.zip'], 09:47:29 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:47:29 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:47:29 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 09:47:29 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:47:29 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:47:29 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:47:29 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:47:29 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:47:29 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:47:29 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 09:47:29 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:47:29 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:47:29 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:47:29 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:47:29 INFO - https://queue.taskcluster.net/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:47:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:47:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:47:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:47:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:47:32 INFO - Downloaded 22520542 bytes. 09:47:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:47:32 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:47:32 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:47:33 INFO - Return code: 0 09:47:33 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:47:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:33 INFO - https://queue.taskcluster.net/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:47:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:47:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:47:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:47:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:47:35 INFO - Downloaded 22520542 bytes. 09:47:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:47:35 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:47:35 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:47:36 INFO - Return code: 0 09:47:36 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:47:36 INFO - https://queue.taskcluster.net/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:47:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 09:47:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 09:47:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 09:47:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:47:40 INFO - Downloaded 11130997 bytes. 09:47:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 09:47:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 09:47:40 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 09:47:40 INFO - Return code: 0 09:47:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:40 INFO - https://queue.taskcluster.net/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:47:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:47:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:47:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:47:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:47:43 INFO - Downloaded 57339907 bytes. 09:47:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:47:43 INFO - mkdir: /builds/slave/test-pgo/properties 09:47:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:47:43 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:47:43 INFO - Contents: 09:47:43 INFO - build_url:https://queue.taskcluster.net/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:47:43 INFO - Running post-action listener: _resource_record_post_action 09:47:43 INFO - Running post-action listener: set_extra_try_arguments 09:47:43 INFO - ##### 09:47:43 INFO - ##### Running populate-webroot step. 09:47:43 INFO - ##### 09:47:43 INFO - Running pre-action listener: _resource_record_pre_action 09:47:43 INFO - Running main action method: populate_webroot 09:47:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:47:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:47:43 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 09:47:43 INFO - '../fennec_ids.txt'], 09:47:43 INFO - 'tests': ['tcheck2']}, 09:47:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:47:43 INFO - '--tppagecycles', 09:47:43 INFO - '7'], 09:47:43 INFO - 'tests': ['tsvgm']}}, 09:47:43 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:47:43 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:47:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:47:43 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:47:43 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:47:43 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:47:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:47:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:43 INFO - 'talos_options': ['--e10s'], 09:47:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:47:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:43 INFO - 'tests': ['damp', 'tps']}, 09:47:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:43 INFO - 'talos_options': ['--e10s'], 09:47:43 INFO - 'tests': ['damp', 'tps']}, 09:47:43 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:47:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:47:43 INFO - 'other': {'tests': ['a11yr', 09:47:43 INFO - 'ts_paint', 09:47:43 INFO - 'tpaint', 09:47:43 INFO - 'sessionrestore', 09:47:43 INFO - 'sessionrestore_no_auto_restore']}, 09:47:43 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:47:43 INFO - 'tests': ['a11yr', 09:47:43 INFO - 'ts_paint', 09:47:43 INFO - 'tpaint', 09:47:43 INFO - 'sessionrestore', 09:47:43 INFO - 'sessionrestore_no_auto_restore']}, 09:47:43 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:47:43 INFO - 'tests': ['a11yr', 09:47:43 INFO - 'ts_paint', 09:47:43 INFO - 'tpaint', 09:47:43 INFO - 'sessionrestore', 09:47:43 INFO - 'sessionrestore_no_auto_restore']}, 09:47:43 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:47:43 INFO - 'tests': ['a11yr', 09:47:43 INFO - 'ts_paint', 09:47:43 INFO - 'tpaint', 09:47:43 INFO - 'sessionrestore', 09:47:43 INFO - 'sessionrestore_no_auto_restore']}, 09:47:43 INFO - 'other_l64': {'tests': ['a11yr', 09:47:43 INFO - 'ts_paint', 09:47:43 INFO - 'tpaint', 09:47:43 INFO - 'sessionrestore', 09:47:43 INFO - 'sessionrestore_no_auto_restore']}, 09:47:43 INFO - 'other_nol64': {'tests': ['a11yr', 09:47:43 INFO - 'ts_paint', 09:47:43 INFO - 'tpaint', 09:47:43 INFO - 'sessionrestore', 09:47:43 INFO - 'sessionrestore_no_auto_restore']}, 09:47:43 INFO - 'svgr': {'tests': ['tsvgx', 09:47:43 INFO - 'tsvgr_opacity', 09:47:43 INFO - 'tart', 09:47:43 INFO - 'tscrollx', 09:47:43 INFO - 'cart']}, 09:47:43 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:47:43 INFO - 'tests': ['tsvgx', 09:47:43 INFO - 'tsvgr_opacity', 09:47:43 INFO - 'tart', 09:47:43 INFO - 'tscrollx', 09:47:43 INFO - 'cart']}, 09:47:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:43 INFO - 'tests': ['tp5o']}, 09:47:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:43 INFO - 'talos_options': ['--e10s'], 09:47:43 INFO - 'tests': ['tp5o']}, 09:47:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:43 INFO - 'talos_options': ['--xperf_path', 09:47:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:47:43 INFO - 'tests': ['tp5n']}, 09:47:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:43 INFO - 'talos_options': ['--e10s', 09:47:43 INFO - '--xperf_path', 09:47:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:47:43 INFO - 'tests': ['tp5n']}}, 09:47:43 INFO - 'talos.zip': {'path': '', 09:47:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:47:43 INFO - Running post-action listener: _resource_record_post_action 09:47:43 INFO - ##### 09:47:43 INFO - ##### Running create-virtualenv step. 09:47:43 INFO - ##### 09:47:43 INFO - Running pre-action listener: _resource_record_pre_action 09:47:43 INFO - Running main action method: create_virtualenv 09:47:43 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:47:43 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:47:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:47:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:47:43 INFO - Using real prefix '/usr' 09:47:43 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:47:44 INFO - Installing distribute.............................................................................................................................................................................................done. 09:47:45 INFO - Installing pip.................done. 09:47:45 INFO - Return code: 0 09:47:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf0cf1bc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf036c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc46e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffaa90>, '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 0x104cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d2e0>, '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': '7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905', '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:47:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 09:47:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:47:45 INFO - Using env: {'DISPLAY': ':0', 09:47:45 INFO - 'HOME': '/home/cltbld', 09:47:45 INFO - 'LANG': 'en_US.UTF-8', 09:47:45 INFO - 'LANGUAGE': 'en_US:en', 09:47:45 INFO - 'LOGNAME': 'cltbld', 09:47:45 INFO - 'MAIL': '/var/mail/cltbld', 09:47:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:45 INFO - 'MOZ_NO_REMOTE': '1', 09:47:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:46 INFO - 'NO_EM_RESTART': '1', 09:47:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:47:46 INFO - 'PWD': '/builds/slave/test-pgo', 09:47:46 INFO - 'SHELL': '/bin/bash', 09:47:46 INFO - 'SHLVL': '1', 09:47:46 INFO - 'TERM': 'linux', 09:47:46 INFO - 'TMOUT': '86400', 09:47:46 INFO - 'USER': 'cltbld', 09:47:46 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905', 09:47:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:46 INFO - '_': '/tools/buildbot/bin/python'} 09:47:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:46 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:47:46 INFO - Cleaning up... 09:47:46 INFO - Return code: 0 09:47:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:47:46 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:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:46 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:47:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:46 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf0cf1bc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf036c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc46e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffaa90>, '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 0x104cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d2e0>, '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': '7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905', '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:47:46 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 09:47:46 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:47:46 INFO - Using env: {'DISPLAY': ':0', 09:47:46 INFO - 'HOME': '/home/cltbld', 09:47:46 INFO - 'LANG': 'en_US.UTF-8', 09:47:46 INFO - 'LANGUAGE': 'en_US:en', 09:47:46 INFO - 'LOGNAME': 'cltbld', 09:47:46 INFO - 'MAIL': '/var/mail/cltbld', 09:47:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:46 INFO - 'MOZ_NO_REMOTE': '1', 09:47:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:46 INFO - 'NO_EM_RESTART': '1', 09:47:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:47:46 INFO - 'PWD': '/builds/slave/test-pgo', 09:47:46 INFO - 'SHELL': '/bin/bash', 09:47:46 INFO - 'SHLVL': '1', 09:47:46 INFO - 'TERM': 'linux', 09:47:46 INFO - 'TMOUT': '86400', 09:47:46 INFO - 'USER': 'cltbld', 09:47:46 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905', 09:47:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:46 INFO - '_': '/tools/buildbot/bin/python'} 09:47:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:46 INFO - Downloading/unpacking psutil>=0.7.1 09:47:46 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:47:46 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:47:46 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:47:46 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:47:46 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:47:46 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:47:48 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 09:47:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 09:47:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:47:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:47:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:47:48 INFO - Installing collected packages: psutil 09:47:48 INFO - Running setup.py install for psutil 09:47:48 INFO - building 'psutil._psutil_linux' extension 09:47:48 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:47:48 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:47:48 INFO - building 'psutil._psutil_posix' extension 09:47:48 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:47:48 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:47:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:47:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:47:48 INFO - Successfully installed psutil 09:47:48 INFO - Cleaning up... 09:47:48 INFO - Return code: 0 09:47:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:47:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf0cf1bc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf036c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc46e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffaa90>, '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 0x104cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d2e0>, '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': '7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905', '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:47:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 09:47:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:47:48 INFO - Using env: {'DISPLAY': ':0', 09:47:48 INFO - 'HOME': '/home/cltbld', 09:47:48 INFO - 'LANG': 'en_US.UTF-8', 09:47:48 INFO - 'LANGUAGE': 'en_US:en', 09:47:48 INFO - 'LOGNAME': 'cltbld', 09:47:48 INFO - 'MAIL': '/var/mail/cltbld', 09:47:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:48 INFO - 'MOZ_NO_REMOTE': '1', 09:47:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:48 INFO - 'NO_EM_RESTART': '1', 09:47:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:47:48 INFO - 'PWD': '/builds/slave/test-pgo', 09:47:48 INFO - 'SHELL': '/bin/bash', 09:47:48 INFO - 'SHLVL': '1', 09:47:48 INFO - 'TERM': 'linux', 09:47:48 INFO - 'TMOUT': '86400', 09:47:48 INFO - 'USER': 'cltbld', 09:47:48 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905', 09:47:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:48 INFO - '_': '/tools/buildbot/bin/python'} 09:47:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:48 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:50 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:47:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 09:47:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:47:50 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:47:50 INFO - Installing collected packages: mozsystemmonitor 09:47:50 INFO - Running setup.py install for mozsystemmonitor 09:47:50 INFO - Successfully installed mozsystemmonitor 09:47:50 INFO - Cleaning up... 09:47:50 INFO - Return code: 0 09:47:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:47: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:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47: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:47:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:50 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf0cf1bc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf036c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc46e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffaa90>, '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 0x104cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d2e0>, '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': '7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905', '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:47:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 09:47:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:47:50 INFO - Using env: {'DISPLAY': ':0', 09:47:50 INFO - 'HOME': '/home/cltbld', 09:47:50 INFO - 'LANG': 'en_US.UTF-8', 09:47:50 INFO - 'LANGUAGE': 'en_US:en', 09:47:50 INFO - 'LOGNAME': 'cltbld', 09:47:50 INFO - 'MAIL': '/var/mail/cltbld', 09:47:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:50 INFO - 'MOZ_NO_REMOTE': '1', 09:47:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:50 INFO - 'NO_EM_RESTART': '1', 09:47:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:47:50 INFO - 'PWD': '/builds/slave/test-pgo', 09:47:50 INFO - 'SHELL': '/bin/bash', 09:47:50 INFO - 'SHLVL': '1', 09:47:50 INFO - 'TERM': 'linux', 09:47:50 INFO - 'TMOUT': '86400', 09:47:50 INFO - 'USER': 'cltbld', 09:47:50 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905', 09:47:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:50 INFO - '_': '/tools/buildbot/bin/python'} 09:47:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:50 INFO - Downloading/unpacking blobuploader==1.2.4 09:47: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:47: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:47: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:47: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:47: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:47: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:47:52 INFO - Downloading blobuploader-1.2.4.tar.gz 09:47:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 09:47:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:47:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:47: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:47: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:47: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:47: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:47: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:47: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:47:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 09:47:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:47:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:47:53 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:47:53 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:47:53 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:47:53 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:47:53 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:47:53 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:47:53 INFO - Downloading docopt-0.6.1.tar.gz 09:47:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 09:47:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:47:53 INFO - Installing collected packages: blobuploader, requests, docopt 09:47:53 INFO - Running setup.py install for blobuploader 09:47:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:47:53 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:47:53 INFO - Running setup.py install for requests 09:47:53 INFO - Running setup.py install for docopt 09:47:53 INFO - Successfully installed blobuploader requests docopt 09:47:53 INFO - Cleaning up... 09:47:53 INFO - Return code: 0 09:47:53 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:47:53 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:47:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:53 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:47:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf0cf1bc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf036c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc46e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffaa90>, '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 0x104cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d2e0>, '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': '7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905', '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:47:53 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:47:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:47:53 INFO - Using env: {'DISPLAY': ':0', 09:47:53 INFO - 'HOME': '/home/cltbld', 09:47:53 INFO - 'LANG': 'en_US.UTF-8', 09:47:53 INFO - 'LANGUAGE': 'en_US:en', 09:47:53 INFO - 'LOGNAME': 'cltbld', 09:47:53 INFO - 'MAIL': '/var/mail/cltbld', 09:47:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:53 INFO - 'MOZ_NO_REMOTE': '1', 09:47:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:53 INFO - 'NO_EM_RESTART': '1', 09:47:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:47:53 INFO - 'PWD': '/builds/slave/test-pgo', 09:47:53 INFO - 'SHELL': '/bin/bash', 09:47:53 INFO - 'SHLVL': '1', 09:47:53 INFO - 'TERM': 'linux', 09:47:53 INFO - 'TMOUT': '86400', 09:47:53 INFO - 'USER': 'cltbld', 09:47:53 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905', 09:47:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:53 INFO - '_': '/tools/buildbot/bin/python'} 09:47:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:47:54 INFO - Running setup.py (path:/tmp/pip-AOnHe2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:47:54 INFO - Running setup.py (path:/tmp/pip-cDLJpC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:47:54 INFO - Running setup.py (path:/tmp/pip-_7BxPn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:47:54 INFO - Running setup.py (path:/tmp/pip-SBMeYQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:47:54 INFO - Running setup.py (path:/tmp/pip-ihgOz5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:47:54 INFO - Running setup.py (path:/tmp/pip-aUpDcF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:47:54 INFO - Running setup.py (path:/tmp/pip-HZQqWy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:47:54 INFO - Running setup.py (path:/tmp/pip-IDJzAf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:47:54 INFO - Running setup.py (path:/tmp/pip-W3tHIJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:47:54 INFO - Running setup.py (path:/tmp/pip-cdV6tv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:47:54 INFO - Running setup.py (path:/tmp/pip-RZ8ZEb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:47:54 INFO - Running setup.py (path:/tmp/pip-amDf_3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:47:54 INFO - Running setup.py (path:/tmp/pip-5ZASSH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:47:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:47:55 INFO - Running setup.py (path:/tmp/pip-negshR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:47:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:47:55 INFO - Running setup.py (path:/tmp/pip-P5aQc8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:47:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:47:55 INFO - Running setup.py (path:/tmp/pip-IfS1AN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:47:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:47:55 INFO - Running setup.py (path:/tmp/pip-FPxpkp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:47:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:47:55 INFO - Running setup.py install for manifestparser 09:47:55 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:47:55 INFO - Running setup.py install for mozcrash 09:47:55 INFO - Running setup.py install for mozdebug 09:47:55 INFO - Running setup.py install for mozdevice 09:47:55 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:47:55 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:47:55 INFO - Running setup.py install for mozfile 09:47:55 INFO - Running setup.py install for mozhttpd 09:47:55 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:47:55 INFO - Running setup.py install for mozinfo 09:47:56 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:47:56 INFO - Running setup.py install for mozInstall 09:47:56 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:47:56 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:47:56 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:47:56 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:47:56 INFO - Running setup.py install for mozleak 09:47:56 INFO - Running setup.py install for mozlog 09:47:56 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:47:56 INFO - Running setup.py install for moznetwork 09:47:56 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:47:56 INFO - Running setup.py install for mozprocess 09:47:56 INFO - Running setup.py install for mozprofile 09:47:57 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:47:57 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:47:57 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:47:57 INFO - Running setup.py install for mozrunner 09:47:57 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:47:57 INFO - Running setup.py install for mozscreenshot 09:47:57 INFO - Running setup.py install for moztest 09:47:57 INFO - Running setup.py install for mozversion 09:47:57 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:47:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:47:57 INFO - Cleaning up... 09:47:57 INFO - Return code: 0 09:47:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:47:57 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:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:57 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:47:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf0cf1bc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf036c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc46e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffaa90>, '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 0x104cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d2e0>, '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': '7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905', '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:47:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:47:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:47:57 INFO - Using env: {'DISPLAY': ':0', 09:47:57 INFO - 'HOME': '/home/cltbld', 09:47:57 INFO - 'LANG': 'en_US.UTF-8', 09:47:57 INFO - 'LANGUAGE': 'en_US:en', 09:47:57 INFO - 'LOGNAME': 'cltbld', 09:47:57 INFO - 'MAIL': '/var/mail/cltbld', 09:47:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:57 INFO - 'MOZ_NO_REMOTE': '1', 09:47:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:57 INFO - 'NO_EM_RESTART': '1', 09:47:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:47:57 INFO - 'PWD': '/builds/slave/test-pgo', 09:47:57 INFO - 'SHELL': '/bin/bash', 09:47:57 INFO - 'SHLVL': '1', 09:47:57 INFO - 'TERM': 'linux', 09:47:57 INFO - 'TMOUT': '86400', 09:47:57 INFO - 'USER': 'cltbld', 09:47:57 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905', 09:47:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:57 INFO - '_': '/tools/buildbot/bin/python'} 09:47:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:47:57 INFO - Running setup.py (path:/tmp/pip-VtiZhT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:47:57 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:47:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:47:57 INFO - Running setup.py (path:/tmp/pip-JhpKOK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:47:57 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:47:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:47:57 INFO - Running setup.py (path:/tmp/pip-342G6u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:47:57 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:47:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:47:57 INFO - Running setup.py (path:/tmp/pip-urp7w0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:47:58 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:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:47:58 INFO - Running setup.py (path:/tmp/pip-xR9sZK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:47:58 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:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:47:58 INFO - Running setup.py (path:/tmp/pip-hpxUCE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:47:58 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:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:47:58 INFO - Running setup.py (path:/tmp/pip-qiRgt5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:47:58 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:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:47:58 INFO - Running setup.py (path:/tmp/pip-X21oMZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:47:58 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:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:47:58 INFO - Running setup.py (path:/tmp/pip-O7fcIo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:47:58 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:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:47:58 INFO - Running setup.py (path:/tmp/pip-LQLtFq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:47:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:47:58 INFO - Running setup.py (path:/tmp/pip-CPj_fT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:47:58 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:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:47:58 INFO - Running setup.py (path:/tmp/pip-Ge5t6r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:47:58 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:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:47:58 INFO - Running setup.py (path:/tmp/pip-szycHq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:47:58 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:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:47:58 INFO - Running setup.py (path:/tmp/pip-TWyXL8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:47:58 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:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:47:58 INFO - Running setup.py (path:/tmp/pip-UCkbL2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:47:58 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:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:47:58 INFO - Running setup.py (path:/tmp/pip-fYHb_0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:47:58 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:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:47:58 INFO - Running setup.py (path:/tmp/pip-LhRQq_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:47:58 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:47:58 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:47:58 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:47:58 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:47:58 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:47:58 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:47:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:47:58 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:47:58 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:47:58 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:47:58 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:47:58 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:47:58 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:48:00 INFO - Downloading blessings-1.5.1.tar.gz 09:48:00 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 09:48:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:48:00 INFO - Installing collected packages: blessings 09:48:00 INFO - Running setup.py install for blessings 09:48:00 INFO - Successfully installed blessings 09:48:00 INFO - Cleaning up... 09:48:00 INFO - Return code: 0 09:48:00 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:48:00 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:48:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:48:01 INFO - Reading from file tmpfile_stdout 09:48:01 INFO - Current package versions: 09:48:01 INFO - argparse == 1.2.1 09:48:01 INFO - blessings == 1.5.1 09:48:01 INFO - blobuploader == 1.2.4 09:48:01 INFO - docopt == 0.6.1 09:48:01 INFO - manifestparser == 1.1 09:48:01 INFO - mozInstall == 1.12 09:48:01 INFO - mozcrash == 0.16 09:48:01 INFO - mozdebug == 0.1 09:48:01 INFO - mozdevice == 0.47 09:48:01 INFO - mozfile == 1.2 09:48:01 INFO - mozhttpd == 0.7 09:48:01 INFO - mozinfo == 0.9 09:48:01 INFO - mozleak == 0.1 09:48:01 INFO - mozlog == 3.0 09:48:01 INFO - moznetwork == 0.27 09:48:01 INFO - mozprocess == 0.22 09:48:01 INFO - mozprofile == 0.27 09:48:01 INFO - mozrunner == 6.11 09:48:01 INFO - mozscreenshot == 0.1 09:48:01 INFO - mozsystemmonitor == 0.0 09:48:01 INFO - moztest == 0.7 09:48:01 INFO - mozversion == 1.4 09:48:01 INFO - psutil == 3.1.1 09:48:01 INFO - requests == 1.2.3 09:48:01 INFO - wsgiref == 0.1.2 09:48:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7fcf0cf1bc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf036c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc46e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffaa90>, '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 0x104cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d2e0>, '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': '7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905', '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:48:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 09:48:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:48:01 INFO - Using env: {'DISPLAY': ':0', 09:48:01 INFO - 'HOME': '/home/cltbld', 09:48:01 INFO - 'LANG': 'en_US.UTF-8', 09:48:01 INFO - 'LANGUAGE': 'en_US:en', 09:48:01 INFO - 'LOGNAME': 'cltbld', 09:48:01 INFO - 'MAIL': '/var/mail/cltbld', 09:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:01 INFO - 'MOZ_NO_REMOTE': '1', 09:48:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:01 INFO - 'NO_EM_RESTART': '1', 09:48:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:01 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:01 INFO - 'SHELL': '/bin/bash', 09:48:01 INFO - 'SHLVL': '1', 09:48:01 INFO - 'TERM': 'linux', 09:48:01 INFO - 'TMOUT': '86400', 09:48:01 INFO - 'USER': 'cltbld', 09:48:01 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905', 09:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:01 INFO - '_': '/tools/buildbot/bin/python'} 09:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:01 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:48:01 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:48:01 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:48:01 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:48:01 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:48:01 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:48:01 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:48:01 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:48:01 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:48:01 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:48:01 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:48:01 INFO - Cleaning up... 09:48:01 INFO - Return code: 0 09:48:01 INFO - Running post-action listener: _resource_record_post_action 09:48:01 INFO - Running post-action listener: _start_resource_monitoring 09:48:01 INFO - Starting resource monitoring. 09:48:01 INFO - ##### 09:48:01 INFO - ##### Running install step. 09:48:01 INFO - ##### 09:48:01 INFO - Running pre-action listener: _resource_record_pre_action 09:48:01 INFO - Running main action method: install 09:48:01 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:48:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:48:01 INFO - Reading from file tmpfile_stdout 09:48:01 INFO - Detecting whether we're running mozinstall >=1.0... 09:48:01 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:48:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:48:01 INFO - Reading from file tmpfile_stdout 09:48:01 INFO - Output received: 09:48:01 INFO - Usage: mozinstall [options] installer 09:48:01 INFO - Options: 09:48:01 INFO - -h, --help show this help message and exit 09:48:01 INFO - -d DEST, --destination=DEST 09:48:01 INFO - Directory to install application into. [default: 09:48:01 INFO - "/builds/slave/test-pgo"] 09:48:01 INFO - --app=APP Application being installed. [default: firefox] 09:48:01 INFO - mkdir: /builds/slave/test-pgo/build/application 09:48:01 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 09:48:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 09:48:16 INFO - Reading from file tmpfile_stdout 09:48:16 INFO - Output received: 09:48:16 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:48:16 INFO - Running post-action listener: _resource_record_post_action 09:48:16 INFO - ##### 09:48:16 INFO - ##### Running run-tests step. 09:48:16 INFO - ##### 09:48:16 INFO - Running pre-action listener: _resource_record_pre_action 09:48:16 INFO - Running main action method: run_tests 09:48:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:48:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:48:16 INFO - Python 2.7.3 09:48:16 INFO - Return code: 0 09:48:16 INFO - grabbing minidump binary from tooltool 09:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:16 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 0xffaa90>, '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 0x104cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d2e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:48:16 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:48:16 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:48:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:48:16 INFO - Return code: 0 09:48:16 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:48:16 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:48:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:48:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:48:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:48:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:48:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName B2G-Inbound --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-008 --webServer localhost 09:48:16 INFO - Using env: {'DISPLAY': ':0', 09:48:16 INFO - 'HOME': '/home/cltbld', 09:48:16 INFO - 'LANG': 'en_US.UTF-8', 09:48:16 INFO - 'LANGUAGE': 'en_US:en', 09:48:16 INFO - 'LOGNAME': 'cltbld', 09:48:16 INFO - 'MAIL': '/var/mail/cltbld', 09:48:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:48:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:48:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:16 INFO - 'MOZ_NO_REMOTE': '1', 09:48:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:48:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:16 INFO - 'NO_EM_RESTART': '1', 09:48:16 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:16 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:16 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:48:16 INFO - 'SHELL': '/bin/bash', 09:48:16 INFO - 'SHLVL': '1', 09:48:16 INFO - 'TERM': 'linux', 09:48:16 INFO - 'TMOUT': '86400', 09:48:16 INFO - 'USER': 'cltbld', 09:48:16 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905', 09:48:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:16 INFO - '_': '/tools/buildbot/bin/python'} 09:48:16 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost'] with output_timeout 3600 09:48:16 INFO - mozversion INFO | application_buildid: 20151119083048 09:48:16 INFO - mozversion INFO | application_changeset: b69c86a07756c1ff1a0b3cf14faa06968298b5b1 09:48:16 INFO - mozversion INFO | application_display_name: Nightly 09:48:16 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:48:16 INFO - mozversion INFO | application_name: Firefox 09:48:16 INFO - mozversion INFO | application_remotingname: firefox 09:48:16 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/b2g-inbound 09:48:16 INFO - mozversion INFO | application_vendor: Mozilla 09:48:16 INFO - mozversion INFO | application_version: 45.0a1 09:48:16 INFO - mozversion INFO | platform_buildid: 20151119083048 09:48:16 INFO - mozversion INFO | platform_changeset: b69c86a07756c1ff1a0b3cf14faa06968298b5b1 09:48:16 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/b2g-inbound 09:48:16 INFO - mozversion INFO | platform_version: 45.0a1 09:48:16 INFO - 2015-11-19 09:48:16,260 DEBUG : using testdate: 1447955296 09:48:16 INFO - 2015-11-19 09:48:16,260 DEBUG : actual date: 1447955296 09:48:16 INFO - 2015-11-19 09:48:16,269 INFO : starting webserver on 'localhost:43527' 09:48:16 INFO - 2015-11-19 09:48:16,270 INFO : Starting test suite talos-linux64-ix-008 09:48:16 INFO - 2015-11-19 09:48:16,270 INFO : Starting test dromaeo_css 09:48:16 INFO - 2015-11-19 09:48:16,270 DEBUG : operating with platform_type : linux_ 09:48:16 INFO - 2015-11-19 09:48:16,271 INFO : Initialising browser for dromaeo_css test... 09:48:16 INFO - 2015-11-19 09:48:16,286 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzVVoX7/profile http://localhost:43527/getInfo.html 09:48:21 INFO - 2015-11-19 09:48:21,269 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:48:21 INFO - 2015-11-19 09:48:21,269 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:48:21 INFO - 2015-11-19 09:48:21,269 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:48:21 INFO - 2015-11-19 09:48:21,269 DEBUG : BROWSER_OUTPUT: __metrics 09:48:21 INFO - 2015-11-19 09:48:21,418 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:48:22 INFO - 2015-11-19 09:48:22,555 INFO : Browser initialized. 09:48:22 INFO - 2015-11-19 09:48:22,555 INFO : Running cycle 1/1 for dromaeo_css test... 09:48:22 INFO - 2015-11-19 09:48:22,556 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzVVoX7/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:48:34 INFO - 2015-11-19 09:48:34,387 DEBUG : BROWSER_OUTPUT: RSS: Main: 142135296 09:48:34 INFO - 2015-11-19 09:48:34,388 DEBUG : BROWSER_OUTPUT: 09:49:25 INFO - 2015-11-19 09:49:25,962 DEBUG : BROWSER_OUTPUT: 1447955365961 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:51:34 INFO - 2015-11-19 09:51:34,667 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43527/tests/dromaeo/cssquery-ext.html) 09:51:35 INFO - 2015-11-19 09:51:35,832 DEBUG : BROWSER_OUTPUT: RSS: Main: 256602112 09:51:35 INFO - 2015-11-19 09:51:35,832 DEBUG : BROWSER_OUTPUT: 09:54:38 INFO - 2015-11-19 09:54:38,187 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/dromaeo/cssquery-ext.html (next: http://localhost:43527/tests/dromaeo/cssquery-jquery.html) 09:54:38 INFO - 2015-11-19 09:54:38,756 DEBUG : BROWSER_OUTPUT: RSS: Main: 230191104 09:54:38 INFO - 2015-11-19 09:54:38,756 DEBUG : BROWSER_OUTPUT: 09:57:40 INFO - 2015-11-19 09:57:40,553 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43527/tests/dromaeo/cssquery-mootools.html) 09:57:41 INFO - 2015-11-19 09:57:41,122 DEBUG : BROWSER_OUTPUT: RSS: Main: 224161792 09:57:41 INFO - 2015-11-19 09:57:41,122 DEBUG : BROWSER_OUTPUT: 10:00:42 INFO - 2015-11-19 10:00:42,885 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43527/tests/dromaeo/cssquery-prototype.html) 10:00:43 INFO - 2015-11-19 10:00:43,472 DEBUG : BROWSER_OUTPUT: RSS: Main: 204619776 10:00:43 INFO - 2015-11-19 10:00:43,472 DEBUG : BROWSER_OUTPUT: 10:03:45 INFO - 2015-11-19 10:03:45,996 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43527/tests/dromaeo/cssquery-yui.html) 10:03:46 INFO - 2015-11-19 10:03:46,565 DEBUG : BROWSER_OUTPUT: RSS: Main: 207032320 10:03:46 INFO - 2015-11-19 10:03:46,565 DEBUG : BROWSER_OUTPUT: 10:06:49 INFO - 2015-11-19 10:06:49,132 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/dromaeo/cssquery-yui.html (next: http://localhost:43527/tests/dromaeo/cssquery-dojo.html) 10:06:49 INFO - 2015-11-19 10:06:49,382 DEBUG : BROWSER_OUTPUT: RSS: Main: 263671808 10:06:49 INFO - 2015-11-19 10:06:49,383 DEBUG : BROWSER_OUTPUT: 10:06:49 INFO - 2015-11-19 10:06:49,383 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:06:49 INFO - 2015-11-19 10:06:49,383 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:06:49 INFO - 2015-11-19 10:06:49,383 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:06:49 INFO - 2015-11-19 10:06:49,383 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:06:49 INFO - 2015-11-19 10:06:49,384 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3176.428242916275;3215.40515004724;3258.592675915509;3266.0528446750445;3282.556854824598;5165.509276618715;5265.3681558212975;5408.2698835657175;5410.05324068288;5517.586181036423;5427.782888684448;5431.266778984836;5435.157550579656;5440.292761940942;5575.219469274305;4961.702297862131;4992.926520798028;5017.096922553938;5025.472325405834;5035.798568057292;4914;5067.113255180348;5069.949300506984;5095.464976177504;5152.433232344439;4893.168161412568;4947.282644016597;4948.356713627223;4975.203967365204;4983.526564976321;5001.324821149171;5024.095662780698;5028.1200789862;5039.823606173803;5075.4163271223915;4807.158747219222;4911.09144808208;4987.750612469398;5011.198208286693;5044.798208071673;5211.895762084775;5329.733513324319;5338.092524270884;5343;5375;5941.316748573926;5945.40545945402;5964.314103878066;5975.073863551156;5975.43233392827;5697.344805347397;5865.472107510344;5968.7015649217365;5989.251343582052;5992.101184822312;5418.322709661293;5498.3127109111365;5498.890022199534;5508.614396992212;5550.888982220374;6009.06859436788;6009.729562169713;6015.187949626745;6027.427394397525;6072.392760723892;5674.092145256739;5749.13762935563;5802.94197058024;5838.12428135783;5851.297844258716;5784.623999440023;5845.41545845412;5852.4147585241135;5853.239078919713;5913.704314784243;5835.649861008353;5896.7936122235515;5914.645121292736;5917.911231331534;5918.2898052234;5739.827805165852;5864.589478736533;5870.618409803349;5902.144189092557;5912.881742365129;4440.911181776411;4444.155610434007;4455.331700244989;4503.572160644799;4524;4039.3940908863906;4049.655779258796;4056.3915412688334;4067.349224124185;4087.529934057562;5317.388500322435;5383.057964856134;5439.456054394529;5575;5598.748056337475;5758.539316854642;5827.4172582741385;5887.175795388649;5888.411158884077;5894.263217097863;5543.61194716374;5572.86067848307;5580.609357344947;5583.274174357308;5591.10542313228;5129.692218466904;5140.717260550706;5143.202803565453;5148.30497882781;5199.2201169824075;5895.380984996599;6019.909701354483;6028.638281703112;6030.095485677096;6107.144999699956;5895.20414744004;5944.494717949023;5945.702714864153;5947.078202878561;5949.791757288474;6059.515238780798;6095.542834287357;6133.754649813957;6141.109539116893;6149.631022138686;5688.3742788294085;5817.243758311392;5830.47525722687;5833;5838.912416313674;5783.624064435799;5819.679917604572;5833.270841144857;5836.533077353886;5881.4118588142555;5770.769169233184;5784.248047753765;5785.595008349375;5790;5805.245318108788;5493.752781124779;5708.600397972268;5765.91351129725;5770.307563092456;5776.075827867521;5419.647722897999;5444.537214336826;5495.697736624524;5546.556275498031;5601.29983752031;5814.680192589279;5850.853728656818;5856.882862342644;5861.824145275648;5872.794452194153;5512.503874651301;5558.277423934863;5719.971400142973;5721.542276617777;5735.971320143372 10:06:49 INFO - 2015-11-19 10:06:49,388 DEBUG : BROWSER_OUTPUT: |1;ext.html;558.8127977127662;560.2744445942504;562.9521490673293;587.541717460381;596.3470000349615;31787.36425271496;32480;32489.51265731013;40707;41601;44498.33252501207;44930.77534612331;44945.325820112645;45097.09805803882;45114.77442612782;8655.17775811297;8840.602172902234;8880.378373513857;8907.73276801697;8911.153440423182;7702.576358300291;7911.287984081431;10522.631707890225;10837.620683276087;10839.945800270987;10429.478526073704;10540.947295263513;10567.15462762981;10577.259591828575;10590;7681.807954801119;7890.8816367754525;10460.320079194827;10540.209484288709;10581.365118092863;7617.695292188306;10488.318259313197;10781.460926953621;10784.245102842804;10819.242653014293;39957.80021099905;40366;40413;40445.595544044474;40484;3199.408109499727;3215.437298472781;3296.868125274986;3414.726821854246;3435.914102147441;29892;30226.395472090437;30523.542146867814;30979.84510077458;31464.528032079535;3499.8950031499094;3538.133157376459;3543.167355671415;3549.183687751806;3596.514470546469;274310;274822;275155;276084;277222;55298.44701552933;56489.43510564924;59813.40186598165;60018;60107.39892601018;67295;68661.65669171722;68730;68733;68753.3124668757;14815.777763333559;14961.700765984837;15002.399904003718;15022.924885375722;15036.924815375853;11207.103431725442;11228.270162439416;11233.045191158679;11242.887571124347;11305.152113591514;19433.31983380575;19585.902070489556;19738.70391944122;19831.504212394517;19906.800931990496;7929.920700793034;8129.227723366271;8263.752087437386;8268.875966860502;8278.793030174294;952.3904700991233;1039.2153923787491;1040.3497813866334;1086.5327908999207;1124.302932182036;28574.571381429294;29190.854045729637;30854.228644284074;31173.688263117532;31351.84324078365;1068.625980906692;1070.925035247525;1106.004595863729;1120.238237998169;1124.58952482345;14250.280282961723;14428.783568246485;14521;14533.636659083608;14608.561743147722;313877;317639;317682;317885;318293;51155.7442212794;51776.22335664968;51909;51953.220701689504;52000.47999520032;1377.1324065838457;1386.0782579584666;1387.8334599848083;1390.4577214886203;1405.33246707815;26773.196804095907;27709;28136;28154.2961425966;28161.295967600974;47050;47378.28932566083;47379.526204737514;47414.28878566755;47457.525424746695;289188;290158;290653;292531;292732;27572;28910.421791564473;30802.383952321277;32014.199645009063;32107.678923210467;39194;40106.19787604173;41641.58358416377;42247.155056899304;42280.78859605805;35114.47328290027;35491.82254088816;35613.28773424465;36590;36653.26693466063;35526.28947421089;35582.82208589044;39225.22004028624;39543.604563953995;40078.1984360317;30755.077347679606;31170.532442013842;36451.81774091112;37032.62967370292;37248.06879827918;29951.85024074953;32936.34127317488;32975.175620609676;33380.66619333873;33434.66565334413;25074.62388064144;25307.10935041213;25415.110471133423;25574.744252557237;25782.355441113374 10:06:49 INFO - 2015-11-19 10:06:49,389 DEBUG : BROWSER_OUTPUT: |2;jquery.html;358.9551306086739;360.4305197787495;360.5853268740948;361.41270435542225;365.16377495535227;14425;14452.494162704308;17002.234899429524;17535.298588056477;18249;17457.91271043643;17473.864198827097;17625.20686569104;17733.113344332764;17741.112944352797;5340.332458442705;5464.098423760095;5465.890682186354;5466.699331536764;5467.890642187154;3970.7816070116132;4061.614146656063;5438.293021907147;5876.559258055641;6108.938910610904;5971.820845374646;6007.249093863267;6027.5479339049525;6054.697265136748;6061.181740465025;4710.929336059962;4818.204996175627;4823.155947709154;4823.372961515016;4824.710517368969;4103.835846566164;4155.833766649331;4156.168766246733;4157.688173387008;4332;13419.798703019462;13507.729845403037;13774.104683195561;13794.172349659053;13803.861961380358;1910.9140088696042;1932.5211431759183;1965.0960558143272;2005.2981456490259;2069.2757534862826;9880.505974701307;10001.449920254383;10032.94983525085;10239.59041638341;10255.487225638763;1727;1766.470058982313;1776.9022703751289;1811.1487600827722;1856.33172058059;59615.40384596142;60664.39335606631;61510.69244653793;61789;61852.07221891675;6666.899996500057;6774.153230846145;6789.456843452512;6820;6845.657717114124;8122.268995790408;8129.634166462405;8136.023677158779;8140.796480087926;8142.918570814216;5487.478689524489;5495;5496.31296087989;5527.419620939824;5546.473085056913;5714.914276285859;5744.281964754406;5757.625754325955;5763.135529670583;5765.1640512125505;3070.4933685942015;3112.3464072544566;3113.470709979309;3137.7176054155234;3141.952870706941;1082.8429877667852;1123.915706322029;1126.4030064066058;1129.9209055366046;1164.5691094295164;448.1194452900067;463.21253868423815;464.2224274340466;481.2804856739197;494.4091810286745;10714.142868570496;10988.120950323955;11805.291682498906;12370.938145309216;12418.441170147365;510.4410670315998;511.15659162382366;513.6686836990195;521.9478052194826;523.2700382965742;6249.843753906189;6440.194975628046;6613.404793568602;6626.53614246998;6661.967395053774;516439;517445;528500;528912;531148;74152.25847741561;77444;77499;78279.21720782833;78442.60778696185;567.4637467593168;570.9543236541067;574.3337728235227;574.91663708762;575.0683892094834;11405.372704501333;11449.255798373079;11511.712207194887;11576.131790115607;11636.94181529087;35605;36186.8190659045;36689.633103668624;36744.63255367412;37312.626873732;48468.2729759061;48672;48760.2685959718;49601.25598116103;50250;6633.635150066792;6642.800715978528;6661.55744884146;6715.127033485811;6746.932530674827;12300.753984920073;12542;12542.24746515212;12554.309512976508;12648;10600.0989915858;10906.454677266263;10924.071453926505;10966.670999870017;11087.611933582286;10312.329698569869;10700.732481688021;10805.51375188134;10845.728856778644;10857;10655.946720266349;10809.945950270197;10900;10901;10945.56217751313;6490.410986227376;6560.147180866456;6624.966875165786;6627.966860165669;6629.933700662931;10211;10507.106895913934;10615.203859710398;11062.280951738112;11066.778664426827 10:06:49 INFO - 2015-11-19 10:06:49,391 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1281.5450515067157;1693.2803558487644;1745.3803899615637;1766.549529869883;1775.6892543804838;6809.965950170248;7320.5241659292105;7426.071741032364;7431.47979641424;7435.405167586594;7586.77239682809;7685;7697.268759467842;7698.692052317901;7701.268379503938;2240.1375470443872;2250.628646273363;2251.8536295140843;2258.1757658454626;2260.740014898283;2289.5306462175213;2317.5249073939804;2395.7484464131276;2396.424858034077;2398.5442765874513;2291.3584196424945;2306.331163962449;2316.1198744477074;2326.1625814706777;2349.283468542094;2279.840411171219;2313.086330899302;2323.2449453927456;2325.267540724666;2332.8483648562787;2300.861948283092;2309.2494939144844;2312.2022902098747;2312.4218945263683;2313.0169677886997;6629.86740265192;6771.830704232384;7624.428167887375;7661.3487853532515;7670.7698769037015;4549.13566422382;4653.813847446098;4660.021395506948;4668.136394766945;4669.533046695337;3113.937721245585;3147.5593416921647;3149.370125974814;3154.9684503154904;3316.4196265653654;991.8958509356557;998.8901220865699;1037.6420135053395;1043.1498328861953;1056.1761825775907;10494.475276236157;10585.523651435704;10586.206034547362;10601.257911946166;10619.78760424795;4223.957760422418;4293.74237545742;4324.13517296542;4357.2592659248;4374.475062992397;8756.86160799092;8761.123887611187;8762.342824288204;8770.254528365032;8777.780555486037;4797.952020479751;4809.495003024702;4827.758612069452;4828.782704778293;4833.758312084382;4398.73607583546;4422.513523512475;4441.822327106879;4443.666724995638;4445.911081778347;3338.5659864217496;3365.7139143172617;3375.9831200843837;3383.0696558446625;3397.745169112277;1011.0698157694939;1016.9237307201989;1017.2218253036433;1019.4342140112161;1024.4570377699831;1012.6961911426542;1036.76154484469;1093.5844379135876;1264.165650670553;1265.1966001589058;7310.7075716971785;7395.445341599294;7515.21090285523;7526.32263096324;7527.059117610299;474.7127987567541;476.9546893045155;478.2085648252148;483.5986131510838;492.5074925074925;5326.7869285228935;5376.8118115865755;5782.10377391505;5820.534357251409;5827.825165245049;8986.55067246635;9001.954990225138;9059.139381758723;9112.134347237003;9138.13187747163;9087.170848918673;9119.81760364789;9231.261499080058;9351.906480935104;9447.338686291894;7191.496595238283;7209.243029481829;7238.819029524304;7290.526115802456;7323.853522929511;7133.610240569467;7208.098987626547;7243.239459856639;7254.238304977901;7259.709611615476;7983;8173.6321865515;8226.383021273308;8235.34117270605;8253.917460825314;8183.222593853575;8324.209200125979;8442.873356899774;8542.615582298935;8590.65637374498;6732.831679208059;6763.12079429671;6785.5250132490255;6793.89809152873;6797.728090876507;7546.9622651888585;7558.9244107558225;7567.583782891994;7619.504732192389;7629.809254768453;6899;6977.162740471176;7173.46199035064;7233.168185658506;7310.597917114397;7057.964710176417;7101.786946391617;7117.39502142303;7167.964160179165;7213.1704853942465;7188.424926006011;7195.532290401107;7216.7834964951135;7221.494495385273;7222.530535515175;6533.281339052795;6585.769498067544;6636.369544893228;6654.5341826073645;6662.233843108104;6575.178102737158;6621.27166011748;6656.2345330287635;6661.800145995434;6668.266490685924 10:06:49 INFO - 2015-11-19 10:06:49,391 DEBUG : BROWSER_OUTPUT: |4;prototype.html;330.4019724299019;337.38090603742125;350.1561237417824;350.8017969847036;358.86004458261266;5865.384134665863;5958.374370691069;6025.186599809024;6047.848803779908;6071.7267722952465;5789.478946894778;5973.1040343948425;5977.402259774031;5978.103284507326;6001.909971350433;4597.172508948388;4602.309653551961;4617.376654151697;4618.376519169903;4618.907621847561;4404.427424434819;4471.08342789729;4537.115262523809;4618.099470603233;4672.883177920562;4589.495155532889;4598.103369842882;4599.356090147382;4613.146567884952;4628.352030715702;4593.494715581283;4596.310553416981;4605.470370907322;4610.285405762129;4621.913850245223;4049.1699201663882;4050.432939388488;4053.4527838741983;4055.5538890721996;4059.2896243157625;5604.635698679573;5662.745176467069;5694.145878118274;5697.9715101424645;5706.172604972256;5381.838544843661;5389.73051347435;5390.110631745757;5391.56867450603;5410.458954104558;6059.818205453844;6339.809805705836;6350.396712312323;6353.650549219791;6364.363563643598;4341.327094300388;4372.912541749179;4455.443069616298;4505.684602077856;4511.661625378077;6586.901196482057;6614;6620.9006864897065;6621.106150669694;6628.867422651569;4606.88482787933;4609.861704148881;4611.423572053493;4614;4619.86140415788;4420.911581768347;4534.523874993144;4553.726776393427;4673.182193116191;4679.9532004680195;5287.4976877196605;5317.680939143664;5321.201819727072;5328.893422131536;5332.093544097513;6427.710753016032;6429.324920883333;6430.324815894357;6434.485241180694;6437.324080971524;1577.0616483192482;1615.6445581971948;1616.0707593133995;1617.0459428936983;1617.5228307649272;1075.086176749772;1087.1520214232753;1090.1006669497697;1091.0398849012818;1120.3110087296254;6233.376662333821;6248.750049997949;6250.124982502453;6256;6257.7809776658505;6613.603183808987;6614.140161778938;6614.140161778938;6618.271990081087;6623.105880706043;6073.4837538808815;6218.036204388327;6223.96888015557;6231.9688401557705;6235.501159907197;3546.982265088658;3551.662592053751;3583.7849729016343;3584.032311275941;3587;6829.829254268683;6831.385175334244;6832.931670683329;6839.281875403011;6846.075779769667;6639.269680335159;6756.864862702719;6757.966210168917;6759.831004224934;6766.28953959837;6771.661416929134;6806.217285012188;6811.9659401702675;6815.148106486689;6821.2155602106395;6354.26925903527;6465;6549.901751473636;6582.243042050225;6587.308332625197;6567.408933196035;6597;6600.2409722882485;6600.702968366339;6611.966940165268;6839.794806155824;6881.174259088742;6884.311568843276;6891.3797758202;6892.241853396223;4339.587739164775;4458.933116003198;4464.107178564235;4473.642108631367;4512.684112112121;6564.671766411756;6566.868662626816;6570.310117437792;6574.671266436563;6579.539432239697;6401.231852177769;6413.551051426542;6415.647139407378;6417.807465776034;6421.229452465734;6260.655663938527;6395.8720825582295;6406.359364063556;6485.8702825944165;6490;6275.843103922256;6279.591826531261;6285.057241413824;6291.370862913672;6294.968525157345;6522.510811689237;6527;6528.804135875931;6531.967340163459;6534.705938232696;6219.346968568416;6346.333634968447;6352.460040896391;6360.363963603602;6366.9681651591445 10:06:49 INFO - 2015-11-19 10:06:49,392 DEBUG : BROWSER_OUTPUT: |5;yui.html;827.6234313387409;831.9001719793624;836.8451836410261;845.1801752300269;849.3629777666754;13285.93357033216;13660.65848353789;13670;13677.38451769669;13700.246486443242;13073.542426015092;13099.279539625317;13132.474701011972;13172.604821855335;13189.406476708547;815.1278132398336;815.6207363575936;830.1034882327102;830.1449507007761;830.754927296449;686.3513979289573;692.1486571517012;808.6967387229789;814.2142832166958;815.4373482297196;846.9026062002856;857.9871301930476;858.9398742088056;863.0420233540765;863.434450434963;849.0702680564802;849.5964416901984;850.3494826457764;850.408965768791;850.8425941200898;514.1747495270113;520.2274622186051;624.257134010526;629.5561629051508;636.1602684456519;13327.866721332857;13355.465781368734;13403;13418.530351437652;13425.597232083053;11800.527978880835;12406.62780116598;12737.108402411835;12741.55404560836;12973.156744811651;10304.608538617296;10384.584616615326;10442.425666588335;10860.076893464066;11029.669109926715;3578.499010138583;3590.982045089758;3627.292677927805;3637.727170462226;3643.8542458301636;49555;49586;49742.25386619203;50112.74943625295;50126.999999999636;10462.686119416278;10562.894371056345;10575.894241057646;10684;10813.243072984973;19331;19438;19573.608527829365;19590.21639134447;19658.60682786336;7368.263173682696;7471.476996610185;7478.177400485943;7574.128975167926;8192.713255036046;3594.478800573902;3635.727320450924;3636.8363423646;3645.3802853514967;3732.5520937487677;3140.262038420969;3171.0486853943726;3171.9207019824225;3183.793053451518;3190.345979074284;482.31511254019375;485.8955324605227;497.6118627470579;498.36209651645953;501.3707796715099;605.6154341992778;666.1639642248947;686.289690170671;699.783802589403;704.6300692136567;8527.914720852836;9153.22197613197;9155.13026262504;9179.793320442795;9231.538423078819;542.0622323380574;554.9528290095387;610.5298919831705;631.9905201421982;641.9386745796241;4002.7398219115667;4142.357934520154;4373.978130109329;4434.490033646172;4500.077484115748;55575.44424555784;55809;56191.15713264304;56351.43648563544;56379;51323.23015154776;52093.739531302104;52229;52231;52315.73842130765;46569;46820.53179468298;46973.5302646976;47143;47360;8712.215900568726;8794.472331660121;8823.073577274294;8891.422057566237;8946.821063578822;36271.45592816057;36449.69586101107;36893.44659830051;37120.44319335158;37335.253294933405;43569.78215108904;45088.0982380344;45519.71684530459;45623.543764563256;45904.77047614741;8165.428420010542;8396.286315663237;8533.359998000298;8646.178613031752;8719.782005449915;12322.32227227511;12385.442655080724;12452.875471245534;12523.374684410715;12667.049971252009;11645.6506304811;11679.76640467203;11714.1800073994;11790.764184716429;12062;11594.76810463803;11626.651200463999;11815.881841181477;11972.880271197177;12150;11117.332960022424;11695;11770.777506500874;11957.76084478323;12208.450619721956;9845.60617575289;9898.604055837974;9964.313760574252;10014.39913605186;10244.100814330608;9302.441853488812;10044.296899216984;10104.141148002502;10140.898591013995;10166.440845753554 10:06:49 INFO - 2015-11-19 10:06:49,392 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:06:49 INFO - 2015-11-19 10:06:49,392 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:06:49 INFO - 2015-11-19 10:06:49,392 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,953 10:06:49 INFO - 2015-11-19 10:06:49,392 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:06:49 INFO - 2015-11-19 10:06:49,392 DEBUG : BROWSER_OUTPUT: __startTimestamp1447956409381__endTimestamp 10:06:49 INFO - 2015-11-19 10:06:49,392 DEBUG : BROWSER_OUTPUT: 10:06:49 INFO - 2015-11-19 10:06:49,393 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:06:49 INFO - 2015-11-19 10:06:49,393 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:06:49 INFO - 2015-11-19 10:06:49,393 DEBUG : BROWSER_OUTPUT: 10:06:49 INFO - 2015-11-19 10:06:49,393 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5452.03 Median:5601.30 stddev:612.05 (10.9%) stddev-sans-first:587.32 10:06:49 INFO - 2015-11-19 10:06:49,393 DEBUG : BROWSER_OUTPUT: Values: 3176.4 3215.4 3258.6 3266.1 3282.6 5165.5 5265.4 5408.3 5410.1 5517.6 5427.8 5431.3 5435.2 5440.3 5575.2 4961.7 4992.9 5017.1 5025.5 5035.8 4914.0 5067.1 5069.9 5095.5 5152.4 4893.2 4947.3 4948.4 4975.2 4983.5 5001.3 5024.1 5028.1 5039.8 5075.4 4807.2 4911.1 4987.8 5011.2 5044.8 5211.9 5329.7 5338.1 5343.0 5375.0 5941.3 5945.4 5964.3 5975.1 5975.4 5697.3 5865.5 5968.7 5989.3 5992.1 5418.3 5498.3 5498.9 5508.6 5550.9 6009.1 6009.7 6015.2 6027.4 6072.4 5674.1 5749.1 5802.9 5838.1 5851.3 5784.6 5845.4 5852.4 5853.2 5913.7 5835.6 5896.8 5914.6 5917.9 5918.3 5739.8 5864.6 5870.6 5902.1 5912.9 4440.9 4444.2 4455.3 4503.6 4524.0 4039.4 4049.7 4056.4 4067.3 4087.5 5317.4 5383.1 5439.5 5575.0 5598.7 5758.5 5827.4 5887.2 5888.4 5894.3 5543.6 5572.9 5580.6 5583.3 5591.1 5129.7 5140.7 5143.2 5148.3 5199.2 5895.4 6019.9 6028.6 6030.1 6107.1 5895.2 5944.5 5945.7 5947.1 5949.8 6059.5 6095.5 6133.8 6141.1 6149.6 5688.4 5817.2 5830.5 5833.0 5838.9 5783.6 5819.7 5833.3 5836.5 5881.4 5770.8 5784.2 5785.6 5790.0 5805.2 5493.8 5708.6 5765.9 5770.3 5776.1 5419.6 5444.5 5495.7 5546.6 5601.3 5814.7 5850.9 5856.9 5861.8 5872.8 5512.5 5558.3 5720.0 5721.5 5736.0 10:06:49 INFO - 2015-11-19 10:06:49,393 DEBUG : BROWSER_OUTPUT: 10:06:49 INFO - 2015-11-19 10:06:49,393 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:46804.63 Median:160746.20 stddev:77072.38 (47.9%) stddev-sans-first:77210.37 10:06:49 INFO - 2015-11-19 10:06:49,393 DEBUG : BROWSER_OUTPUT: Values: 558.8 560.3 563.0 587.5 596.3 31787.4 32480.0 32489.5 40707.0 41601.0 44498.3 44930.8 44945.3 45097.1 45114.8 8655.2 8840.6 8880.4 8907.7 8911.2 7702.6 7911.3 10522.6 10837.6 10839.9 10429.5 10540.9 10567.2 10577.3 10590.0 7681.8 7890.9 10460.3 10540.2 10581.4 7617.7 10488.3 10781.5 10784.2 10819.2 39957.8 40366.0 40413.0 40445.6 40484.0 3199.4 3215.4 3296.9 3414.7 3435.9 29892.0 30226.4 30523.5 30979.8 31464.5 3499.9 3538.1 3543.2 3549.2 3596.5 274310.0 274822.0 275155.0 276084.0 277222.0 55298.4 56489.4 59813.4 60018.0 60107.4 67295.0 68661.7 68730.0 68733.0 68753.3 14815.8 14961.7 15002.4 15022.9 15036.9 11207.1 11228.3 11233.0 11242.9 11305.2 19433.3 19585.9 19738.7 19831.5 19906.8 7929.9 8129.2 8263.8 8268.9 8278.8 952.4 1039.2 1040.3 1086.5 1124.3 28574.6 29190.9 30854.2 31173.7 31351.8 1068.6 1070.9 1106.0 1120.2 1124.6 14250.3 14428.8 14521.0 14533.6 14608.6 313877.0 317639.0 317682.0 317885.0 318293.0 51155.7 51776.2 51909.0 51953.2 52000.5 1377.1 1386.1 1387.8 1390.5 1405.3 26773.2 27709.0 28136.0 28154.3 28161.3 47050.0 47378.3 47379.5 47414.3 47457.5 289188.0 290158.0 290653.0 292531.0 292732.0 27572.0 28910.4 30802.4 32014.2 32107.7 39194.0 40106.2 41641.6 42247.2 42280.8 35114.5 35491.8 35613.3 36590.0 36653.3 35526.3 35582.8 39225.2 39543.6 40078.2 30755.1 31170.5 36451.8 37032.6 37248.1 29951.9 32936.3 32975.2 33380.7 33434.7 25074.6 25307.1 25415.1 25574.7 25782.4 10:06:49 INFO - 2015-11-19 10:06:49,393 DEBUG : BROWSER_OUTPUT: 10:06:49 INFO - 2015-11-19 10:06:49,394 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26842.44 Median:2390.06 stddev:86010.92 (3598.7%) stddev-sans-first:86229.21 10:06:49 INFO - 2015-11-19 10:06:49,395 DEBUG : BROWSER_OUTPUT: Values: 359.0 360.4 360.6 361.4 365.2 14425.0 14452.5 17002.2 17535.3 18249.0 17457.9 17473.9 17625.2 17733.1 17741.1 5340.3 5464.1 5465.9 5466.7 5467.9 3970.8 4061.6 5438.3 5876.6 6108.9 5971.8 6007.2 6027.5 6054.7 6061.2 4710.9 4818.2 4823.2 4823.4 4824.7 4103.8 4155.8 4156.2 4157.7 4332.0 13419.8 13507.7 13774.1 13794.2 13803.9 1910.9 1932.5 1965.1 2005.3 2069.3 9880.5 10001.4 10032.9 10239.6 10255.5 1727.0 1766.5 1776.9 1811.1 1856.3 59615.4 60664.4 61510.7 61789.0 61852.1 6666.9 6774.2 6789.5 6820.0 6845.7 8122.3 8129.6 8136.0 8140.8 8142.9 5487.5 5495.0 5496.3 5527.4 5546.5 5714.9 5744.3 5757.6 5763.1 5765.2 3070.5 3112.3 3113.5 3137.7 3142.0 1082.8 1123.9 1126.4 1129.9 1164.6 448.1 463.2 464.2 481.3 494.4 10714.1 10988.1 11805.3 12370.9 12418.4 510.4 511.2 513.7 521.9 523.3 6249.8 6440.2 6613.4 6626.5 6662.0 516439.0 517445.0 528500.0 528912.0 531148.0 74152.3 77444.0 77499.0 78279.2 78442.6 567.5 571.0 574.3 574.9 575.1 11405.4 11449.3 11511.7 11576.1 11636.9 35605.0 36186.8 36689.6 36744.6 37312.6 48468.3 48672.0 48760.3 49601.3 50250.0 6633.6 6642.8 6661.6 6715.1 6746.9 12300.8 12542.0 12542.2 12554.3 12648.0 10600.1 10906.5 10924.1 10966.7 11087.6 10312.3 10700.7 10805.5 10845.7 10857.0 10655.9 10809.9 10900.0 10901.0 10945.6 6490.4 6560.1 6625.0 6628.0 6629.9 10211.0 10507.1 10615.2 11062.3 11066.8 10:06:49 INFO - 2015-11-19 10:06:49,395 DEBUG : BROWSER_OUTPUT: 10:06:49 INFO - 2015-11-19 10:06:49,395 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5408.68 Median:6603.52 stddev:2798.28 (42.4%) stddev-sans-first:2788.93 10:06:49 INFO - 2015-11-19 10:06:49,395 DEBUG : BROWSER_OUTPUT: Values: 1281.5 1693.3 1745.4 1766.5 1775.7 6810.0 7320.5 7426.1 7431.5 7435.4 7586.8 7685.0 7697.3 7698.7 7701.3 2240.1 2250.6 2251.9 2258.2 2260.7 2289.5 2317.5 2395.7 2396.4 2398.5 2291.4 2306.3 2316.1 2326.2 2349.3 2279.8 2313.1 2323.2 2325.3 2332.8 2300.9 2309.2 2312.2 2312.4 2313.0 6629.9 6771.8 7624.4 7661.3 7670.8 4549.1 4653.8 4660.0 4668.1 4669.5 3113.9 3147.6 3149.4 3155.0 3316.4 991.9 998.9 1037.6 1043.1 1056.2 10494.5 10585.5 10586.2 10601.3 10619.8 4224.0 4293.7 4324.1 4357.3 4374.5 8756.9 8761.1 8762.3 8770.3 8777.8 4798.0 4809.5 4827.8 4828.8 4833.8 4398.7 4422.5 4441.8 4443.7 4445.9 3338.6 3365.7 3376.0 3383.1 3397.7 1011.1 1016.9 1017.2 1019.4 1024.5 1012.7 1036.8 1093.6 1264.2 1265.2 7310.7 7395.4 7515.2 7526.3 7527.1 474.7 477.0 478.2 483.6 492.5 5326.8 5376.8 5782.1 5820.5 5827.8 8986.6 9002.0 9059.1 9112.1 9138.1 9087.2 9119.8 9231.3 9351.9 9447.3 7191.5 7209.2 7238.8 7290.5 7323.9 7133.6 7208.1 7243.2 7254.2 7259.7 7983.0 8173.6 8226.4 8235.3 8253.9 8183.2 8324.2 8442.9 8542.6 8590.7 6732.8 6763.1 6785.5 6793.9 6797.7 7547.0 7558.9 7567.6 7619.5 7629.8 6899.0 6977.2 7173.5 7233.2 7310.6 7058.0 7101.8 7117.4 7168.0 7213.2 7188.4 7195.5 7216.8 7221.5 7222.5 6533.3 6585.8 6636.4 6654.5 6662.2 6575.2 6621.3 6656.2 6661.8 6668.3 10:06:49 INFO - 2015-11-19 10:06:49,395 DEBUG : BROWSER_OUTPUT: 10:06:49 INFO - 2015-11-19 10:06:49,396 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5353.44 Median:6072.61 stddev:1614.33 (26.6%) stddev-sans-first:1574.21 10:06:49 INFO - 2015-11-19 10:06:49,397 DEBUG : BROWSER_OUTPUT: Values: 330.4 337.4 350.2 350.8 358.9 5865.4 5958.4 6025.2 6047.8 6071.7 5789.5 5973.1 5977.4 5978.1 6001.9 4597.2 4602.3 4617.4 4618.4 4618.9 4404.4 4471.1 4537.1 4618.1 4672.9 4589.5 4598.1 4599.4 4613.1 4628.4 4593.5 4596.3 4605.5 4610.3 4621.9 4049.2 4050.4 4053.5 4055.6 4059.3 5604.6 5662.7 5694.1 5698.0 5706.2 5381.8 5389.7 5390.1 5391.6 5410.5 6059.8 6339.8 6350.4 6353.7 6364.4 4341.3 4372.9 4455.4 4505.7 4511.7 6586.9 6614.0 6620.9 6621.1 6628.9 4606.9 4609.9 4611.4 4614.0 4619.9 4420.9 4534.5 4553.7 4673.2 4680.0 5287.5 5317.7 5321.2 5328.9 5332.1 6427.7 6429.3 6430.3 6434.5 6437.3 1577.1 1615.6 1616.1 1617.0 1617.5 1075.1 1087.2 1090.1 1091.0 1120.3 6233.4 6248.8 6250.1 6256.0 6257.8 6613.6 6614.1 6614.1 6618.3 6623.1 6073.5 6218.0 6224.0 6232.0 6235.5 3547.0 3551.7 3583.8 3584.0 3587.0 6829.8 6831.4 6832.9 6839.3 6846.1 6639.3 6756.9 6758.0 6759.8 6766.3 6771.7 6806.2 6812.0 6815.1 6821.2 6354.3 6465.0 6549.9 6582.2 6587.3 6567.4 6597.0 6600.2 6600.7 6612.0 6839.8 6881.2 6884.3 6891.4 6892.2 4339.6 4458.9 4464.1 4473.6 4512.7 6564.7 6566.9 6570.3 6574.7 6579.5 6401.2 6413.6 6415.6 6417.8 6421.2 6260.7 6395.9 6406.4 6485.9 6490.0 6275.8 6279.6 6285.1 6291.4 6295.0 6522.5 6527.0 6528.8 6532.0 6534.7 6219.3 6346.3 6352.5 6360.4 6367.0 10:06:49 INFO - 2015-11-19 10:06:49,397 DEBUG : BROWSER_OUTPUT: 10:06:49 INFO - 2015-11-19 10:06:49,397 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13978.36 Median:46236.89 stddev:16136.23 (34.9%) stddev-sans-first:16151.27 10:06:49 INFO - 2015-11-19 10:06:49,397 DEBUG : BROWSER_OUTPUT: Values: 827.6 831.9 836.8 845.2 849.4 13285.9 13660.7 13670.0 13677.4 13700.2 13073.5 13099.3 13132.5 13172.6 13189.4 815.1 815.6 830.1 830.1 830.8 686.4 692.1 808.7 814.2 815.4 846.9 858.0 858.9 863.0 863.4 849.1 849.6 850.3 850.4 850.8 514.2 520.2 624.3 629.6 636.2 13327.9 13355.5 13403.0 13418.5 13425.6 11800.5 12406.6 12737.1 12741.6 12973.2 10304.6 10384.6 10442.4 10860.1 11029.7 3578.5 3591.0 3627.3 3637.7 3643.9 49555.0 49586.0 49742.3 50112.7 50127.0 10462.7 10562.9 10575.9 10684.0 10813.2 19331.0 19438.0 19573.6 19590.2 19658.6 7368.3 7471.5 7478.2 7574.1 8192.7 3594.5 3635.7 3636.8 3645.4 3732.6 3140.3 3171.0 3171.9 3183.8 3190.3 482.3 485.9 497.6 498.4 501.4 605.6 666.2 686.3 699.8 704.6 8527.9 9153.2 9155.1 9179.8 9231.5 542.1 555.0 610.5 632.0 641.9 4002.7 4142.4 4374.0 4434.5 4500.1 55575.4 55809.0 56191.2 56351.4 56379.0 51323.2 52093.7 52229.0 52231.0 52315.7 46569.0 46820.5 46973.5 47143.0 47360.0 8712.2 8794.5 8823.1 8891.4 8946.8 36271.5 36449.7 36893.4 37120.4 37335.3 43569.8 45088.1 45519.7 45623.5 45904.8 8165.4 8396.3 8533.4 8646.2 8719.8 12322.3 12385.4 12452.9 12523.4 12667.0 11645.7 11679.8 11714.2 11790.8 12062.0 11594.8 11626.7 11815.9 11972.9 12150.0 11117.3 11695.0 11770.8 11957.8 12208.5 9845.6 9898.6 9964.3 10014.4 10244.1 9302.4 10044.3 10104.1 10140.9 10166.4 10:06:49 INFO - 2015-11-19 10:06:49,397 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:06:49 INFO - 2015-11-19 10:06:49,397 DEBUG : BROWSER_OUTPUT: 10:06:50 INFO - 2015-11-19 10:06:50,053 INFO : Browser exited with error code: 0 10:06:50 INFO - 2015-11-19 10:06:50,070 INFO : Completed test dromaeo_css (00:18:33) 10:06:50 INFO - 2015-11-19 10:06:50,070 INFO : Starting test kraken 10:06:50 INFO - 2015-11-19 10:06:50,070 DEBUG : operating with platform_type : linux_ 10:06:50 INFO - 2015-11-19 10:06:50,070 INFO : Initialising browser for kraken test... 10:06:50 INFO - 2015-11-19 10:06:50,076 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCBgDpe/profile http://localhost:43527/getInfo.html 10:06:53 INFO - 2015-11-19 10:06:53,689 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:06:53 INFO - 2015-11-19 10:06:53,690 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:06:53 INFO - 2015-11-19 10:06:53,690 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:06:53 INFO - 2015-11-19 10:06:53,690 DEBUG : BROWSER_OUTPUT: __metrics 10:06:53 INFO - 2015-11-19 10:06:53,754 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:06:54 INFO - 2015-11-19 10:06:54,997 INFO : Browser initialized. 10:06:54 INFO - 2015-11-19 10:06:54,998 INFO : Running cycle 1/1 for kraken test... 10:06:54 INFO - 2015-11-19 10:06:54,998 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCBgDpe/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:07:06 INFO - 2015-11-19 10:07:06,760 DEBUG : BROWSER_OUTPUT: RSS: Main: 142172160 10:07:06 INFO - 2015-11-19 10:07:06,761 DEBUG : BROWSER_OUTPUT: 10:07:09 INFO - 2015-11-19 10:07:09,215 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43527/tests/kraken/driver.html?testName=audio-beat-detection) 10:07:09 INFO - 2015-11-19 10:07:09,797 DEBUG : BROWSER_OUTPUT: RSS: Main: 178696192 10:07:09 INFO - 2015-11-19 10:07:09,797 DEBUG : BROWSER_OUTPUT: 10:07:12 INFO - 2015-11-19 10:07:12,376 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43527/tests/kraken/driver.html?testName=audio-dft) 10:07:12 INFO - 2015-11-19 10:07:12,950 DEBUG : BROWSER_OUTPUT: RSS: Main: 168824832 10:07:12 INFO - 2015-11-19 10:07:12,950 DEBUG : BROWSER_OUTPUT: 10:07:15 INFO - 2015-11-19 10:07:15,759 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43527/tests/kraken/driver.html?testName=audio-fft) 10:07:16 INFO - 2015-11-19 10:07:16,320 DEBUG : BROWSER_OUTPUT: RSS: Main: 151269376 10:07:16 INFO - 2015-11-19 10:07:16,320 DEBUG : BROWSER_OUTPUT: 10:07:18 INFO - 2015-11-19 10:07:18,279 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43527/tests/kraken/driver.html?testName=audio-oscillator) 10:07:18 INFO - 2015-11-19 10:07:18,845 DEBUG : BROWSER_OUTPUT: RSS: Main: 155099136 10:07:18 INFO - 2015-11-19 10:07:18,846 DEBUG : BROWSER_OUTPUT: 10:07:21 INFO - 2015-11-19 10:07:21,019 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43527/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:07:21 INFO - 2015-11-19 10:07:21,588 DEBUG : BROWSER_OUTPUT: RSS: Main: 156610560 10:07:21 INFO - 2015-11-19 10:07:21,588 DEBUG : BROWSER_OUTPUT: 10:07:24 INFO - 2015-11-19 10:07:24,508 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43527/tests/kraken/driver.html?testName=imaging-darkroom) 10:07:25 INFO - 2015-11-19 10:07:25,075 DEBUG : BROWSER_OUTPUT: RSS: Main: 156278784 10:07:25 INFO - 2015-11-19 10:07:25,075 DEBUG : BROWSER_OUTPUT: 10:07:28 INFO - 2015-11-19 10:07:28,424 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43527/tests/kraken/driver.html?testName=imaging-desaturate) 10:07:28 INFO - 2015-11-19 10:07:28,998 DEBUG : BROWSER_OUTPUT: RSS: Main: 156508160 10:07:28 INFO - 2015-11-19 10:07:28,998 DEBUG : BROWSER_OUTPUT: 10:07:31 INFO - 2015-11-19 10:07:31,756 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43527/tests/kraken/driver.html?testName=json-parse-financial) 10:07:32 INFO - 2015-11-19 10:07:32,329 DEBUG : BROWSER_OUTPUT: RSS: Main: 155463680 10:07:32 INFO - 2015-11-19 10:07:32,329 DEBUG : BROWSER_OUTPUT: 10:07:33 INFO - 2015-11-19 10:07:33,918 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43527/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:07:34 INFO - 2015-11-19 10:07:34,481 DEBUG : BROWSER_OUTPUT: RSS: Main: 162590720 10:07:34 INFO - 2015-11-19 10:07:34,481 DEBUG : BROWSER_OUTPUT: 10:07:36 INFO - 2015-11-19 10:07:36,292 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43527/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:07:36 INFO - 2015-11-19 10:07:36,881 DEBUG : BROWSER_OUTPUT: RSS: Main: 160878592 10:07:36 INFO - 2015-11-19 10:07:36,882 DEBUG : BROWSER_OUTPUT: 10:07:38 INFO - 2015-11-19 10:07:38,933 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43527/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:07:39 INFO - 2015-11-19 10:07:39,503 DEBUG : BROWSER_OUTPUT: RSS: Main: 167419904 10:07:39 INFO - 2015-11-19 10:07:39,503 DEBUG : BROWSER_OUTPUT: 10:07:41 INFO - 2015-11-19 10:07:41,908 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43527/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:07:42 INFO - 2015-11-19 10:07:42,489 DEBUG : BROWSER_OUTPUT: RSS: Main: 178974720 10:07:42 INFO - 2015-11-19 10:07:42,489 DEBUG : BROWSER_OUTPUT: 10:07:44 INFO - 2015-11-19 10:07:44,988 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43527/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:07:45 INFO - 2015-11-19 10:07:45,561 DEBUG : BROWSER_OUTPUT: RSS: Main: 179101696 10:07:45 INFO - 2015-11-19 10:07:45,561 DEBUG : BROWSER_OUTPUT: 10:07:47 INFO - 2015-11-19 10:07:47,210 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43527/tests/kraken/driver.html?testName=ai-astar) 10:07:47 INFO - 2015-11-19 10:07:47,460 DEBUG : BROWSER_OUTPUT: RSS: Main: 236392448 10:07:47 INFO - 2015-11-19 10:07:47,461 DEBUG : BROWSER_OUTPUT: 10:07:47 INFO - 2015-11-19 10:07:47,462 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:07:47 INFO - 2015-11-19 10:07:47,462 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:07:47 INFO - 2015-11-19 10:07:47,462 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:07:47 INFO - 2015-11-19 10:07:47,462 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:07:47 INFO - 2015-11-19 10:07:47,463 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;110;103;108;110;109;99;110;111;109 10:07:47 INFO - 2015-11-19 10:07:47,463 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;145;123;148;162;124;148;156;124;174;130 10:07:47 INFO - 2015-11-19 10:07:47,463 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;161;163;161;160;160;161;162;161;161 10:07:47 INFO - 2015-11-19 10:07:47,463 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;84;90;72;71;72;96;91;71 10:07:47 INFO - 2015-11-19 10:07:47,463 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;90;135;89;90;90;137;90;91;132 10:07:47 INFO - 2015-11-19 10:07:47,463 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;110;109;110;109;109;110;111 10:07:47 INFO - 2015-11-19 10:07:47,464 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;145;146;146;145;145;145;145;145 10:07:47 INFO - 2015-11-19 10:07:47,464 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;95;96;96;96;96;95;96 10:07:47 INFO - 2015-11-19 10:07:47,464 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;58;57;58;58;59;58;59;66;57;65 10:07:47 INFO - 2015-11-19 10:07:47,464 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;70;69;70;70;70;70;70;70;70 10:07:47 INFO - 2015-11-19 10:07:47,464 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;87;86;86;85;86;85;86;86;88 10:07:47 INFO - 2015-11-19 10:07:47,464 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;120;114;118;124;117;117;125;117;130;122 10:07:47 INFO - 2015-11-19 10:07:47,464 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;147;145;153;148;139;147;146;149;137 10:07:47 INFO - 2015-11-19 10:07:47,464 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;66;70;66;65;64;66;66;66;66 10:07:47 INFO - 2015-11-19 10:07:47,465 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:07:47 INFO - 2015-11-19 10:07:47,465 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:07:47 INFO - 2015-11-19 10:07:47,465 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,907 10:07:47 INFO - 2015-11-19 10:07:47,465 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:07:47 INFO - 2015-11-19 10:07:47,465 DEBUG : BROWSER_OUTPUT: __startTimestamp1447956467461__endTimestamp 10:07:47 INFO - 2015-11-19 10:07:47,465 DEBUG : BROWSER_OUTPUT: 10:07:47 INFO - 2015-11-19 10:07:47,469 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:07:47 INFO - 2015-11-19 10:07:47,469 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:07:47 INFO - 2015-11-19 10:07:47,469 DEBUG : BROWSER_OUTPUT: 10:07:47 INFO - 2015-11-19 10:07:47,470 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.90 Median:110.00 stddev:3.84 (3.5%) stddev-sans-first:4.00 10:07:47 INFO - 2015-11-19 10:07:47,470 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 103.0 108.0 110.0 109.0 99.0 110.0 111.0 109.0 10:07:47 INFO - 2015-11-19 10:07:47,471 DEBUG : BROWSER_OUTPUT: 10:07:47 INFO - 2015-11-19 10:07:47,471 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.40 Median:148.00 stddev:17.75 (12.0%) stddev-sans-first:18.81 10:07:47 INFO - 2015-11-19 10:07:47,471 DEBUG : BROWSER_OUTPUT: Values: 145.0 123.0 148.0 162.0 124.0 148.0 156.0 124.0 174.0 130.0 10:07:47 INFO - 2015-11-19 10:07:47,471 DEBUG : BROWSER_OUTPUT: 10:07:47 INFO - 2015-11-19 10:07:47,471 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 10:07:47 INFO - 2015-11-19 10:07:47,471 DEBUG : BROWSER_OUTPUT: Values: 162.0 161.0 163.0 161.0 160.0 160.0 161.0 162.0 161.0 161.0 10:07:47 INFO - 2015-11-19 10:07:47,471 DEBUG : BROWSER_OUTPUT: 10:07:47 INFO - 2015-11-19 10:07:47,471 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.00 Median:78.00 stddev:10.10 (12.9%) stddev-sans-first:10.29 10:07:47 INFO - 2015-11-19 10:07:47,471 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 84.0 90.0 72.0 71.0 72.0 96.0 91.0 71.0 10:07:47 INFO - 2015-11-19 10:07:47,471 DEBUG : BROWSER_OUTPUT: 10:07:47 INFO - 2015-11-19 10:07:47,471 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.40 Median:90.00 stddev:21.61 (24.0%) stddev-sans-first:22.37 10:07:47 INFO - 2015-11-19 10:07:47,471 DEBUG : BROWSER_OUTPUT: Values: 90.0 90.0 135.0 89.0 90.0 90.0 137.0 90.0 91.0 132.0 10:07:47 INFO - 2015-11-19 10:07:47,471 DEBUG : BROWSER_OUTPUT: 10:07:47 INFO - 2015-11-19 10:07:47,471 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:109.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 10:07:47 INFO - 2015-11-19 10:07:47,471 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 110.0 109.0 110.0 109.0 109.0 110.0 111.0 10:07:47 INFO - 2015-11-19 10:07:47,471 DEBUG : BROWSER_OUTPUT: 10:07:47 INFO - 2015-11-19 10:07:47,472 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 10:07:47 INFO - 2015-11-19 10:07:47,472 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 145.0 146.0 146.0 145.0 145.0 145.0 145.0 145.0 10:07:47 INFO - 2015-11-19 10:07:47,472 DEBUG : BROWSER_OUTPUT: 10:07:47 INFO - 2015-11-19 10:07:47,472 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 10:07:47 INFO - 2015-11-19 10:07:47,472 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 95.0 96.0 96.0 96.0 96.0 95.0 96.0 10:07:47 INFO - 2015-11-19 10:07:47,472 DEBUG : BROWSER_OUTPUT: 10:07:47 INFO - 2015-11-19 10:07:47,472 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:59.50 Median:58.50 stddev:3.24 (5.5%) stddev-sans-first:3.39 10:07:47 INFO - 2015-11-19 10:07:47,472 DEBUG : BROWSER_OUTPUT: Values: 58.0 57.0 58.0 58.0 59.0 58.0 59.0 66.0 57.0 65.0 10:07:47 INFO - 2015-11-19 10:07:47,472 DEBUG : BROWSER_OUTPUT: 10:07:47 INFO - 2015-11-19 10:07:47,472 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:0.42 (0.6%) stddev-sans-first:0.33 10:07:47 INFO - 2015-11-19 10:07:47,473 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 69.0 70.0 70.0 70.0 70.0 70.0 70.0 70.0 10:07:47 INFO - 2015-11-19 10:07:47,473 DEBUG : BROWSER_OUTPUT: 10:07:47 INFO - 2015-11-19 10:07:47,473 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:86.00 stddev:0.94 (1.1%) stddev-sans-first:0.93 10:07:47 INFO - 2015-11-19 10:07:47,473 DEBUG : BROWSER_OUTPUT: Values: 85.0 87.0 86.0 86.0 85.0 86.0 85.0 86.0 86.0 88.0 10:07:47 INFO - 2015-11-19 10:07:47,473 DEBUG : BROWSER_OUTPUT: 10:07:47 INFO - 2015-11-19 10:07:47,473 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:120.40 Median:121.00 stddev:4.84 (4.0%) stddev-sans-first:5.13 10:07:47 INFO - 2015-11-19 10:07:47,473 DEBUG : BROWSER_OUTPUT: Values: 120.0 114.0 118.0 124.0 117.0 117.0 125.0 117.0 130.0 122.0 10:07:47 INFO - 2015-11-19 10:07:47,473 DEBUG : BROWSER_OUTPUT: 10:07:47 INFO - 2015-11-19 10:07:47,473 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.10 Median:147.00 stddev:4.98 (3.4%) stddev-sans-first:4.92 10:07:47 INFO - 2015-11-19 10:07:47,473 DEBUG : BROWSER_OUTPUT: Values: 140.0 147.0 145.0 153.0 148.0 139.0 147.0 146.0 149.0 137.0 10:07:47 INFO - 2015-11-19 10:07:47,473 DEBUG : BROWSER_OUTPUT: 10:07:47 INFO - 2015-11-19 10:07:47,473 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:1.56 (2.4%) stddev-sans-first:1.62 10:07:47 INFO - 2015-11-19 10:07:47,473 DEBUG : BROWSER_OUTPUT: Values: 65.0 66.0 70.0 66.0 65.0 64.0 66.0 66.0 66.0 66.0 10:07:47 INFO - 2015-11-19 10:07:47,473 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:07:47 INFO - 2015-11-19 10:07:47,473 DEBUG : BROWSER_OUTPUT: 10:07:48 INFO - 2015-11-19 10:07:48,317 INFO : Browser exited with error code: 0 10:07:48 INFO - 2015-11-19 10:07:48,371 INFO : Completed test kraken (00:00:58) 10:07:48 INFO - 2015-11-19 10:07:48,371 INFO : Starting test v8_7 10:07:48 INFO - 2015-11-19 10:07:48,371 DEBUG : operating with platform_type : linux_ 10:07:48 INFO - 2015-11-19 10:07:48,371 INFO : Initialising browser for v8_7 test... 10:07:48 INFO - 2015-11-19 10:07:48,377 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgQgn0n/profile http://localhost:43527/getInfo.html 10:07:52 INFO - 2015-11-19 10:07:52,009 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:07:52 INFO - 2015-11-19 10:07:52,009 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:07:52 INFO - 2015-11-19 10:07:52,010 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:07:52 INFO - 2015-11-19 10:07:52,010 DEBUG : BROWSER_OUTPUT: __metrics 10:07:52 INFO - 2015-11-19 10:07:52,126 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:07:53 INFO - 2015-11-19 10:07:53,298 INFO : Browser initialized. 10:07:53 INFO - 2015-11-19 10:07:53,299 INFO : Running cycle 1/1 for v8_7 test... 10:07:53 INFO - 2015-11-19 10:07:53,299 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgQgn0n/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:08:05 INFO - 2015-11-19 10:08:05,061 DEBUG : BROWSER_OUTPUT: RSS: Main: 141422592 10:08:05 INFO - 2015-11-19 10:08:05,061 DEBUG : BROWSER_OUTPUT: 10:08:26 INFO - 2015-11-19 10:08:26,473 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43527/tests/v8_7/run.html (next: http://localhost:43527/tests/v8_7/run.html) 10:08:26 INFO - 2015-11-19 10:08:26,723 DEBUG : BROWSER_OUTPUT: RSS: Main: 609083392 10:08:26 INFO - 2015-11-19 10:08:26,723 DEBUG : BROWSER_OUTPUT: 10:08:26 INFO - 2015-11-19 10:08:26,723 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:08:26 INFO - 2015-11-19 10:08:26,724 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:08:26 INFO - 2015-11-19 10:08:26,724 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:08:26 INFO - 2015-11-19 10:08:26,724 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:08:26 INFO - 2015-11-19 10:08:26,724 DEBUG : BROWSER_OUTPUT: |0;Richards;162.76041666666666 10:08:26 INFO - 2015-11-19 10:08:26,724 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;192.97568506368196 10:08:26 INFO - 2015-11-19 10:08:26,724 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.28611898017 10:08:26 INFO - 2015-11-19 10:08:26,724 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5050.50505050505 10:08:26 INFO - 2015-11-19 10:08:26,725 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1103.7527593818984 10:08:26 INFO - 2015-11-19 10:08:26,725 DEBUG : BROWSER_OUTPUT: |5;Earley;778.816199376947 10:08:26 INFO - 2015-11-19 10:08:26,725 DEBUG : BROWSER_OUTPUT: |6;Boyer;12231.707317073171 10:08:26 INFO - 2015-11-19 10:08:26,725 DEBUG : BROWSER_OUTPUT: |7;RegExp;25743.589743589742 10:08:26 INFO - 2015-11-19 10:08:26,725 DEBUG : BROWSER_OUTPUT: |8;Splay;651.7509727626459 10:08:26 INFO - 2015-11-19 10:08:26,726 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 10:08:26 INFO - 2015-11-19 10:08:26,726 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:08:26 INFO - 2015-11-19 10:08:26,726 DEBUG : BROWSER_OUTPUT: __startTimestamp1447956506722__endTimestamp 10:08:26 INFO - 2015-11-19 10:08:26,726 DEBUG : BROWSER_OUTPUT: 10:08:26 INFO - 2015-11-19 10:08:26,726 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:08:26 INFO - 2015-11-19 10:08:26,726 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:08:26 INFO - 2015-11-19 10:08:26,727 DEBUG : BROWSER_OUTPUT: 10:08:26 INFO - 2015-11-19 10:08:26,727 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:162.76 Median:162.76 stddev:0.00 (0.0%) 10:08:26 INFO - 2015-11-19 10:08:26,728 DEBUG : BROWSER_OUTPUT: Values: 162.8 10:08:26 INFO - 2015-11-19 10:08:26,728 DEBUG : BROWSER_OUTPUT: 10:08:26 INFO - 2015-11-19 10:08:26,728 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:192.98 Median:192.98 stddev:0.00 (0.0%) 10:08:26 INFO - 2015-11-19 10:08:26,729 DEBUG : BROWSER_OUTPUT: Values: 193.0 10:08:26 INFO - 2015-11-19 10:08:26,729 DEBUG : BROWSER_OUTPUT: 10:08:26 INFO - 2015-11-19 10:08:26,729 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.29 Median:283.29 stddev:0.00 (0.0%) 10:08:26 INFO - 2015-11-19 10:08:26,729 DEBUG : BROWSER_OUTPUT: Values: 283.3 10:08:26 INFO - 2015-11-19 10:08:26,729 DEBUG : BROWSER_OUTPUT: 10:08:26 INFO - 2015-11-19 10:08:26,729 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5050.51 Median:5050.51 stddev:0.00 (0.0%) 10:08:26 INFO - 2015-11-19 10:08:26,729 DEBUG : BROWSER_OUTPUT: Values: 5050.5 10:08:26 INFO - 2015-11-19 10:08:26,730 DEBUG : BROWSER_OUTPUT: 10:08:26 INFO - 2015-11-19 10:08:26,730 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1103.75 Median:1103.75 stddev:0.00 (0.0%) 10:08:26 INFO - 2015-11-19 10:08:26,730 DEBUG : BROWSER_OUTPUT: Values: 1103.8 10:08:26 INFO - 2015-11-19 10:08:26,730 DEBUG : BROWSER_OUTPUT: 10:08:26 INFO - 2015-11-19 10:08:26,730 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:778.82 Median:778.82 stddev:0.00 (0.0%) 10:08:26 INFO - 2015-11-19 10:08:26,730 DEBUG : BROWSER_OUTPUT: Values: 778.8 10:08:26 INFO - 2015-11-19 10:08:26,730 DEBUG : BROWSER_OUTPUT: 10:08:26 INFO - 2015-11-19 10:08:26,731 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12231.71 Median:12231.71 stddev:0.00 (0.0%) 10:08:26 INFO - 2015-11-19 10:08:26,731 DEBUG : BROWSER_OUTPUT: Values: 12231.7 10:08:26 INFO - 2015-11-19 10:08:26,731 DEBUG : BROWSER_OUTPUT: 10:08:26 INFO - 2015-11-19 10:08:26,731 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25743.59 Median:25743.59 stddev:0.00 (0.0%) 10:08:26 INFO - 2015-11-19 10:08:26,731 DEBUG : BROWSER_OUTPUT: Values: 25743.6 10:08:26 INFO - 2015-11-19 10:08:26,731 DEBUG : BROWSER_OUTPUT: 10:08:26 INFO - 2015-11-19 10:08:26,731 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:651.75 Median:651.75 stddev:0.00 (0.0%) 10:08:26 INFO - 2015-11-19 10:08:26,731 DEBUG : BROWSER_OUTPUT: Values: 651.8 10:08:26 INFO - 2015-11-19 10:08:26,732 DEBUG : BROWSER_OUTPUT: 10:08:26 INFO - 2015-11-19 10:08:26,732 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 10:08:26 INFO - 2015-11-19 10:08:26,732 DEBUG : BROWSER_OUTPUT: Values: 4830.9 10:08:26 INFO - 2015-11-19 10:08:26,732 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:08:26 INFO - 2015-11-19 10:08:26,732 DEBUG : BROWSER_OUTPUT: 10:08:27 INFO - 2015-11-19 10:08:27,589 INFO : Browser exited with error code: 0 10:08:27 INFO - 2015-11-19 10:08:27,601 INFO : Completed test v8_7 (00:00:39) 10:08:27 INFO - 2015-11-19 10:08:27,726 INFO : Completed test suite (00:20:11) 10:08:27 INFO - 2015-11-19 10:08:27,726 DEBUG : Working with test: dromaeo_css 10:08:27 INFO - 2015-11-19 10:08:27,726 DEBUG : Generating results file: dromaeo_css 10:08:27 INFO - 2015-11-19 10:08:27,728 DEBUG : Working with test: kraken 10:08:27 INFO - 2015-11-19 10:08:27,728 DEBUG : Generating results file: kraken 10:08:27 INFO - 2015-11-19 10:08:27,728 INFO : javascript benchmark 10:08:27 INFO - 2015-11-19 10:08:27,728 DEBUG : Working with test: v8_7 10:08:27 INFO - 2015-11-19 10:08:27,728 DEBUG : Generating results file: v8_7 10:08:27 INFO - 2015-11-19 10:08:27,729 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:08:27 INFO - 2015-11-19 10:08:27,793 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,201,35]] 10:08:27 INFO - 2015-11-19 10:08:27,793 DEBUG : process_Request line: dromaeo_css 6766.65 graph.html#tests=[[72,201,35]] 10:08:27 INFO - 2015-11-19 10:08:27,793 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:08:27 INFO - 2015-11-19 10:08:27,825 DEBUG : process_Request line: kraken 1492.20 graph.html#tests=[[232,201,35]] 10:08:27 INFO - 2015-11-19 10:08:27,825 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:08:27 INFO - 2015-11-19 10:08:27,858 DEBUG : process_Request line: v8_7 20947.64 graph.html#tests=[[230,201,35]] 10:08:27 INFO - 2015-11-19 10:08:27,860 INFO : javascript benchmark 10:08:27 INFO - 2015-11-19 10:08:27,863 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3176.428242916275, 3215.40515004724, 3258.592675915509, 3266.0528446750445, 3282.556854824598, 5165.509276618715, 5265.3681558212975, 5408.2698835657175, 5410.05324068288, 5517.586181036423, 5427.782888684448, 5431.266778984836, 5435.157550579656, 5440.292761940942, 5575.219469274305, 4961.702297862131, 4992.926520798028, 5017.096922553938, 5025.472325405834, 5035.798568057292, 4914.0, 5067.113255180348, 5069.949300506984, 5095.464976177504, 5152.433232344439, 4893.168161412568, 4947.282644016597, 4948.356713627223, 4975.203967365204, 4983.526564976321, 5001.324821149171, 5024.095662780698, 5028.1200789862, 5039.823606173803, 5075.4163271223915, 4807.158747219222, 4911.09144808208, 4987.750612469398, 5011.198208286693, 5044.798208071673, 5211.895762084775, 5329.733513324319, 5338.092524270884, 5343.0, 5375.0, 5941.316748573926, 5945.40545945402, 5964.314103878066, 5975.073863551156, 5975.43233392827, 5697.344805347397, 5865.472107510344, 5968.7015649217365, 5989.251343582052, 5992.101184822312, 5418.322709661293, 5498.3127109111365, 5498.890022199534, 5508.614396992212, 5550.888982220374, 6009.06859436788, 6009.729562169713, 6015.187949626745, 6027.427394397525, 6072.392760723892, 5674.092145256739, 5749.13762935563, 5802.94197058024, 5838.12428135783, 5851.297844258716, 5784.623999440023, 5845.41545845412, 5852.4147585241135, 5853.239078919713, 5913.704314784243, 5835.649861008353, 5896.7936122235515, 5914.645121292736, 5917.911231331534, 5918.2898052234, 5739.827805165852, 5864.589478736533, 5870.618409803349, 5902.144189092557, 5912.881742365129, 4440.911181776411, 4444.155610434007, 4455.331700244989, 4503.572160644799, 4524.0, 4039.3940908863906, 4049.655779258796, 4056.3915412688334, 4067.349224124185, 4087.529934057562, 5317.388500322435, 5383.057964856134, 5439.456054394529, 5575.0, 5598.748056337475, 5758.539316854642, 5827.4172582741385, 5887.175795388649, 5888.411158884077, 5894.263217097863, 5543.61194716374, 5572.86067848307, 5580.609357344947, 5583.274174357308, 5591.10542313228, 5129.692218466904, 5140.717260550706, 5143.202803565453, 5148.30497882781, 5199.2201169824075, 5895.380984996599, 6019.909701354483, 6028.638281703112, 6030.095485677096, 6107.144999699956, 5895.20414744004, 5944.494717949023, 5945.702714864153, 5947.078202878561, 5949.791757288474, 6059.515238780798, 6095.542834287357, 6133.754649813957, 6141.109539116893, 6149.631022138686, 5688.3742788294085, 5817.243758311392, 5830.47525722687, 5833.0, 5838.912416313674, 5783.624064435799, 5819.679917604572, 5833.270841144857, 5836.533077353886, 5881.4118588142555, 5770.769169233184, 5784.248047753765, 5785.595008349375, 5790.0, 5805.245318108788, 5493.752781124779, 5708.600397972268, 5765.91351129725, 5770.307563092456, 5776.075827867521, 5419.647722897999, 5444.537214336826, 5495.697736624524, 5546.556275498031, 5601.29983752031, 5814.680192589279, 5850.853728656818, 5856.882862342644, 5861.824145275648, 5872.794452194153, 5512.503874651301, 5558.277423934863, 5719.971400142973, 5721.542276617777, 5735.971320143372], "unit": "ms", "name": "dojo.html", "value": 5411.815387595907}, {"lowerIsBetter": false, "replicates": [558.8127977127662, 560.2744445942504, 562.9521490673293, 587.541717460381, 596.3470000349615, 31787.36425271496, 32480.0, 32489.51265731013, 40707.0, 41601.0, 44498.33252501207, 44930.77534612331, 44945.325820112645, 45097.09805803882, 45114.77442612782, 8655.17775811297, 8840.602172902234, 8880.378373513857, 8907.73276801697, 8911.153440423182, 7702.576358300291, 7911.287984081431, 10522.631707890225, 10837.620683276087, 10839.945800270987, 10429.478526073704, 10540.947295263513, 10567.15462762981, 10577.259591828575, 10590.0, 7681.807954801119, 7890.8816367754525, 10460.320079194827, 10540.209484288709, 10581.365118092863, 7617.695292188306, 10488.318259313197, 10781.460926953621, 10784.245102842804, 10819.242653014293, 39957.80021099905, 40366.0, 40413.0, 40445.595544044474, 40484.0, 3199.408109499727, 3215.437298472781, 3296.868125274986, 3414.726821854246, 3435.914102147441, 29892.0, 30226.395472090437, 30523.542146867814, 30979.84510077458, 31464.528032079535, 3499.8950031499094, 3538.133157376459, 3543.167355671415, 3549.183687751806, 3596.514470546469, 274310.0, 274822.0, 275155.0, 276084.0, 277222.0, 55298.44701552933, 56489.43510564924, 59813.40186598165, 60018.0, 60107.39892601018, 67295.0, 68661.65669171722, 68730.0, 68733.0, 68753.3124668757, 14815.777763333559, 14961.700765984837, 15002.399904003718, 15022.924885375722, 15036.924815375853, 11207.103431725442, 11228.270162439416, 11233.045191158679, 11242.887571124347, 11305.152113591514, 19433.31983380575, 19585.902070489556, 19738.70391944122, 19831.504212394517, 19906.800931990496, 7929.920700793034, 8129.227723366271, 8263.752087437386, 8268.875966860502, 8278.793030174294, 952.3904700991233, 1039.2153923787491, 1040.3497813866334, 1086.5327908999207, 1124.302932182036, 28574.571381429294, 29190.854045729637, 30854.228644284074, 31173.688263117532, 31351.84324078365, 1068.625980906692, 1070.925035247525, 1106.004595863729, 1120.238237998169, 1124.58952482345, 14250.280282961723, 14428.783568246485, 14521.0, 14533.636659083608, 14608.561743147722, 313877.0, 317639.0, 317682.0, 317885.0, 318293.0, 51155.7442212794, 51776.22335664968, 51909.0, 51953.220701689504, 52000.47999520032, 1377.1324065838457, 1386.0782579584666, 1387.8334599848083, 1390.4577214886203, 1405.33246707815, 26773.196804095907, 27709.0, 28136.0, 28154.2961425966, 28161.295967600974, 47050.0, 47378.28932566083, 47379.526204737514, 47414.28878566755, 47457.525424746695, 289188.0, 290158.0, 290653.0, 292531.0, 292732.0, 27572.0, 28910.421791564473, 30802.383952321277, 32014.199645009063, 32107.678923210467, 39194.0, 40106.19787604173, 41641.58358416377, 42247.155056899304, 42280.78859605805, 35114.47328290027, 35491.82254088816, 35613.28773424465, 36590.0, 36653.26693466063, 35526.28947421089, 35582.82208589044, 39225.22004028624, 39543.604563953995, 40078.1984360317, 30755.077347679606, 31170.532442013842, 36451.81774091112, 37032.62967370292, 37248.06879827918, 29951.85024074953, 32936.34127317488, 32975.175620609676, 33380.66619333873, 33434.66565334413, 25074.62388064144, 25307.10935041213, 25415.110471133423, 25574.744252557237, 25782.355441113374], "unit": "ms", "name": "ext.html", "value": 18788.067213117625}, {"lowerIsBetter": false, "replicates": [358.9551306086739, 360.4305197787495, 360.5853268740948, 361.41270435542225, 365.16377495535227, 14425.0, 14452.494162704308, 17002.234899429524, 17535.298588056477, 18249.0, 17457.91271043643, 17473.864198827097, 17625.20686569104, 17733.113344332764, 17741.112944352797, 5340.332458442705, 5464.098423760095, 5465.890682186354, 5466.699331536764, 5467.890642187154, 3970.7816070116132, 4061.614146656063, 5438.293021907147, 5876.559258055641, 6108.938910610904, 5971.820845374646, 6007.249093863267, 6027.5479339049525, 6054.697265136748, 6061.181740465025, 4710.929336059962, 4818.204996175627, 4823.155947709154, 4823.372961515016, 4824.710517368969, 4103.835846566164, 4155.833766649331, 4156.168766246733, 4157.688173387008, 4332.0, 13419.798703019462, 13507.729845403037, 13774.104683195561, 13794.172349659053, 13803.861961380358, 1910.9140088696042, 1932.5211431759183, 1965.0960558143272, 2005.2981456490259, 2069.2757534862826, 9880.505974701307, 10001.449920254383, 10032.94983525085, 10239.59041638341, 10255.487225638763, 1727.0, 1766.470058982313, 1776.9022703751289, 1811.1487600827722, 1856.33172058059, 59615.40384596142, 60664.39335606631, 61510.69244653793, 61789.0, 61852.07221891675, 6666.899996500057, 6774.153230846145, 6789.456843452512, 6820.0, 6845.657717114124, 8122.268995790408, 8129.634166462405, 8136.023677158779, 8140.796480087926, 8142.918570814216, 5487.478689524489, 5495.0, 5496.31296087989, 5527.419620939824, 5546.473085056913, 5714.914276285859, 5744.281964754406, 5757.625754325955, 5763.135529670583, 5765.1640512125505, 3070.4933685942015, 3112.3464072544566, 3113.470709979309, 3137.7176054155234, 3141.952870706941, 1082.8429877667852, 1123.915706322029, 1126.4030064066058, 1129.9209055366046, 1164.5691094295164, 448.1194452900067, 463.21253868423815, 464.2224274340466, 481.2804856739197, 494.4091810286745, 10714.142868570496, 10988.120950323955, 11805.291682498906, 12370.938145309216, 12418.441170147365, 510.4410670315998, 511.15659162382366, 513.6686836990195, 521.9478052194826, 523.2700382965742, 6249.843753906189, 6440.194975628046, 6613.404793568602, 6626.53614246998, 6661.967395053774, 516439.0, 517445.0, 528500.0, 528912.0, 531148.0, 74152.25847741561, 77444.0, 77499.0, 78279.21720782833, 78442.60778696185, 567.4637467593168, 570.9543236541067, 574.3337728235227, 574.91663708762, 575.0683892094834, 11405.372704501333, 11449.255798373079, 11511.712207194887, 11576.131790115607, 11636.94181529087, 35605.0, 36186.8190659045, 36689.633103668624, 36744.63255367412, 37312.626873732, 48468.2729759061, 48672.0, 48760.2685959718, 49601.25598116103, 50250.0, 6633.635150066792, 6642.800715978528, 6661.55744884146, 6715.127033485811, 6746.932530674827, 12300.753984920073, 12542.0, 12542.24746515212, 12554.309512976508, 12648.0, 10600.0989915858, 10906.454677266263, 10924.071453926505, 10966.670999870017, 11087.611933582286, 10312.329698569869, 10700.732481688021, 10805.51375188134, 10845.728856778644, 10857.0, 10655.946720266349, 10809.945950270197, 10900.0, 10901.0, 10945.56217751313, 6490.410986227376, 6560.147180866456, 6624.966875165786, 6627.966860165669, 6629.933700662931, 10211.0, 10507.106895913934, 10615.203859710398, 11062.280951738112, 11066.778664426827], "unit": "ms", "name": "jquery.html", "value": 7090.213738060344}, {"lowerIsBetter": false, "replicates": [1281.5450515067157, 1693.2803558487644, 1745.3803899615637, 1766.549529869883, 1775.6892543804838, 6809.965950170248, 7320.5241659292105, 7426.071741032364, 7431.47979641424, 7435.405167586594, 7586.77239682809, 7685.0, 7697.268759467842, 7698.692052317901, 7701.268379503938, 2240.1375470443872, 2250.628646273363, 2251.8536295140843, 2258.1757658454626, 2260.740014898283, 2289.5306462175213, 2317.5249073939804, 2395.7484464131276, 2396.424858034077, 2398.5442765874513, 2291.3584196424945, 2306.331163962449, 2316.1198744477074, 2326.1625814706777, 2349.283468542094, 2279.840411171219, 2313.086330899302, 2323.2449453927456, 2325.267540724666, 2332.8483648562787, 2300.861948283092, 2309.2494939144844, 2312.2022902098747, 2312.4218945263683, 2313.0169677886997, 6629.86740265192, 6771.830704232384, 7624.428167887375, 7661.3487853532515, 7670.7698769037015, 4549.13566422382, 4653.813847446098, 4660.021395506948, 4668.136394766945, 4669.533046695337, 3113.937721245585, 3147.5593416921647, 3149.370125974814, 3154.9684503154904, 3316.4196265653654, 991.8958509356557, 998.8901220865699, 1037.6420135053395, 1043.1498328861953, 1056.1761825775907, 10494.475276236157, 10585.523651435704, 10586.206034547362, 10601.257911946166, 10619.78760424795, 4223.957760422418, 4293.74237545742, 4324.13517296542, 4357.2592659248, 4374.475062992397, 8756.86160799092, 8761.123887611187, 8762.342824288204, 8770.254528365032, 8777.780555486037, 4797.952020479751, 4809.495003024702, 4827.758612069452, 4828.782704778293, 4833.758312084382, 4398.73607583546, 4422.513523512475, 4441.822327106879, 4443.666724995638, 4445.911081778347, 3338.5659864217496, 3365.7139143172617, 3375.9831200843837, 3383.0696558446625, 3397.745169112277, 1011.0698157694939, 1016.9237307201989, 1017.2218253036433, 1019.4342140112161, 1024.4570377699831, 1012.6961911426542, 1036.76154484469, 1093.5844379135876, 1264.165650670553, 1265.1966001589058, 7310.7075716971785, 7395.445341599294, 7515.21090285523, 7526.32263096324, 7527.059117610299, 474.7127987567541, 476.9546893045155, 478.2085648252148, 483.5986131510838, 492.5074925074925, 5326.7869285228935, 5376.8118115865755, 5782.10377391505, 5820.534357251409, 5827.825165245049, 8986.55067246635, 9001.954990225138, 9059.139381758723, 9112.134347237003, 9138.13187747163, 9087.170848918673, 9119.81760364789, 9231.261499080058, 9351.906480935104, 9447.338686291894, 7191.496595238283, 7209.243029481829, 7238.819029524304, 7290.526115802456, 7323.853522929511, 7133.610240569467, 7208.098987626547, 7243.239459856639, 7254.238304977901, 7259.709611615476, 7983.0, 8173.6321865515, 8226.383021273308, 8235.34117270605, 8253.917460825314, 8183.222593853575, 8324.209200125979, 8442.873356899774, 8542.615582298935, 8590.65637374498, 6732.831679208059, 6763.12079429671, 6785.5250132490255, 6793.89809152873, 6797.728090876507, 7546.9622651888585, 7558.9244107558225, 7567.583782891994, 7619.504732192389, 7629.809254768453, 6899.0, 6977.162740471176, 7173.46199035064, 7233.168185658506, 7310.597917114397, 7057.964710176417, 7101.786946391617, 7117.39502142303, 7167.964160179165, 7213.1704853942465, 7188.424926006011, 7195.532290401107, 7216.7834964951135, 7221.494495385273, 7222.530535515175, 6533.281339052795, 6585.769498067544, 6636.369544893228, 6654.5341826073645, 6662.233843108104, 6575.178102737158, 6621.27166011748, 6656.2345330287635, 6661.800145995434, 6668.266490685924], "unit": "ms", "name": "mootools.html", "value": 4367.986990581615}, {"lowerIsBetter": false, "replicates": [330.4019724299019, 337.38090603742125, 350.1561237417824, 350.8017969847036, 358.86004458261266, 5865.384134665863, 5958.374370691069, 6025.186599809024, 6047.848803779908, 6071.7267722952465, 5789.478946894778, 5973.1040343948425, 5977.402259774031, 5978.103284507326, 6001.909971350433, 4597.172508948388, 4602.309653551961, 4617.376654151697, 4618.376519169903, 4618.907621847561, 4404.427424434819, 4471.08342789729, 4537.115262523809, 4618.099470603233, 4672.883177920562, 4589.495155532889, 4598.103369842882, 4599.356090147382, 4613.146567884952, 4628.352030715702, 4593.494715581283, 4596.310553416981, 4605.470370907322, 4610.285405762129, 4621.913850245223, 4049.1699201663882, 4050.432939388488, 4053.4527838741983, 4055.5538890721996, 4059.2896243157625, 5604.635698679573, 5662.745176467069, 5694.145878118274, 5697.9715101424645, 5706.172604972256, 5381.838544843661, 5389.73051347435, 5390.110631745757, 5391.56867450603, 5410.458954104558, 6059.818205453844, 6339.809805705836, 6350.396712312323, 6353.650549219791, 6364.363563643598, 4341.327094300388, 4372.912541749179, 4455.443069616298, 4505.684602077856, 4511.661625378077, 6586.901196482057, 6614.0, 6620.9006864897065, 6621.106150669694, 6628.867422651569, 4606.88482787933, 4609.861704148881, 4611.423572053493, 4614.0, 4619.86140415788, 4420.911581768347, 4534.523874993144, 4553.726776393427, 4673.182193116191, 4679.9532004680195, 5287.4976877196605, 5317.680939143664, 5321.201819727072, 5328.893422131536, 5332.093544097513, 6427.710753016032, 6429.324920883333, 6430.324815894357, 6434.485241180694, 6437.324080971524, 1577.0616483192482, 1615.6445581971948, 1616.0707593133995, 1617.0459428936983, 1617.5228307649272, 1075.086176749772, 1087.1520214232753, 1090.1006669497697, 1091.0398849012818, 1120.3110087296254, 6233.376662333821, 6248.750049997949, 6250.124982502453, 6256.0, 6257.7809776658505, 6613.603183808987, 6614.140161778938, 6614.140161778938, 6618.271990081087, 6623.105880706043, 6073.4837538808815, 6218.036204388327, 6223.96888015557, 6231.9688401557705, 6235.501159907197, 3546.982265088658, 3551.662592053751, 3583.7849729016343, 3584.032311275941, 3587.0, 6829.829254268683, 6831.385175334244, 6832.931670683329, 6839.281875403011, 6846.075779769667, 6639.269680335159, 6756.864862702719, 6757.966210168917, 6759.831004224934, 6766.28953959837, 6771.661416929134, 6806.217285012188, 6811.9659401702675, 6815.148106486689, 6821.2155602106395, 6354.26925903527, 6465.0, 6549.901751473636, 6582.243042050225, 6587.308332625197, 6567.408933196035, 6597.0, 6600.2409722882485, 6600.702968366339, 6611.966940165268, 6839.794806155824, 6881.174259088742, 6884.311568843276, 6891.3797758202, 6892.241853396223, 4339.587739164775, 4458.933116003198, 4464.107178564235, 4473.642108631367, 4512.684112112121, 6564.671766411756, 6566.868662626816, 6570.310117437792, 6574.671266436563, 6579.539432239697, 6401.231852177769, 6413.551051426542, 6415.647139407378, 6417.807465776034, 6421.229452465734, 6260.655663938527, 6395.8720825582295, 6406.359364063556, 6485.8702825944165, 6490.0, 6275.843103922256, 6279.591826531261, 6285.057241413824, 6291.370862913672, 6294.968525157345, 6522.510811689237, 6527.0, 6528.804135875931, 6531.967340163459, 6534.705938232696, 6219.346968568416, 6346.333634968447, 6352.460040896391, 6360.363963603602, 6366.9681651591445], "unit": "ms", "name": "prototype.html", "value": 4830.5698446384295}, {"lowerIsBetter": false, "replicates": [827.6234313387409, 831.9001719793624, 836.8451836410261, 845.1801752300269, 849.3629777666754, 13285.93357033216, 13660.65848353789, 13670.0, 13677.38451769669, 13700.246486443242, 13073.542426015092, 13099.279539625317, 13132.474701011972, 13172.604821855335, 13189.406476708547, 815.1278132398336, 815.6207363575936, 830.1034882327102, 830.1449507007761, 830.754927296449, 686.3513979289573, 692.1486571517012, 808.6967387229789, 814.2142832166958, 815.4373482297196, 846.9026062002856, 857.9871301930476, 858.9398742088056, 863.0420233540765, 863.434450434963, 849.0702680564802, 849.5964416901984, 850.3494826457764, 850.408965768791, 850.8425941200898, 514.1747495270113, 520.2274622186051, 624.257134010526, 629.5561629051508, 636.1602684456519, 13327.866721332857, 13355.465781368734, 13403.0, 13418.530351437652, 13425.597232083053, 11800.527978880835, 12406.62780116598, 12737.108402411835, 12741.55404560836, 12973.156744811651, 10304.608538617296, 10384.584616615326, 10442.425666588335, 10860.076893464066, 11029.669109926715, 3578.499010138583, 3590.982045089758, 3627.292677927805, 3637.727170462226, 3643.8542458301636, 49555.0, 49586.0, 49742.25386619203, 50112.74943625295, 50126.999999999636, 10462.686119416278, 10562.894371056345, 10575.894241057646, 10684.0, 10813.243072984973, 19331.0, 19438.0, 19573.608527829365, 19590.21639134447, 19658.60682786336, 7368.263173682696, 7471.476996610185, 7478.177400485943, 7574.128975167926, 8192.713255036046, 3594.478800573902, 3635.727320450924, 3636.8363423646, 3645.3802853514967, 3732.5520937487677, 3140.262038420969, 3171.0486853943726, 3171.9207019824225, 3183.793053451518, 3190.345979074284, 482.31511254019375, 485.8955324605227, 497.6118627470579, 498.36209651645953, 501.3707796715099, 605.6154341992778, 666.1639642248947, 686.289690170671, 699.783802589403, 704.6300692136567, 8527.914720852836, 9153.22197613197, 9155.13026262504, 9179.793320442795, 9231.538423078819, 542.0622323380574, 554.9528290095387, 610.5298919831705, 631.9905201421982, 641.9386745796241, 4002.7398219115667, 4142.357934520154, 4373.978130109329, 4434.490033646172, 4500.077484115748, 55575.44424555784, 55809.0, 56191.15713264304, 56351.43648563544, 56379.0, 51323.23015154776, 52093.739531302104, 52229.0, 52231.0, 52315.73842130765, 46569.0, 46820.53179468298, 46973.5302646976, 47143.0, 47360.0, 8712.215900568726, 8794.472331660121, 8823.073577274294, 8891.422057566237, 8946.821063578822, 36271.45592816057, 36449.69586101107, 36893.44659830051, 37120.44319335158, 37335.253294933405, 43569.78215108904, 45088.0982380344, 45519.71684530459, 45623.543764563256, 45904.77047614741, 8165.428420010542, 8396.286315663237, 8533.359998000298, 8646.178613031752, 8719.782005449915, 12322.32227227511, 12385.442655080724, 12452.875471245534, 12523.374684410715, 12667.049971252009, 11645.6506304811, 11679.76640467203, 11714.1800073994, 11790.764184716429, 12062.0, 11594.76810463803, 11626.651200463999, 11815.881841181477, 11972.880271197177, 12150.0, 11117.332960022424, 11695.0, 11770.777506500874, 11957.76084478323, 12208.450619721956, 9845.60617575289, 9898.604055837974, 9964.313760574252, 10014.39913605186, 10244.100814330608, 9302.441853488812, 10044.296899216984, 10104.141148002502, 10140.898591013995, 10166.440845753554], "unit": "ms", "name": "yui.html", "value": 6310.178154840431}], "name": "dromaeo_css", "value": 6766.652913978844}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 110.0, 103.0, 108.0, 110.0, 109.0, 99.0, 110.0, 111.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 107.9}, {"lowerIsBetter": true, "replicates": [145.0, 123.0, 148.0, 162.0, 124.0, 148.0, 156.0, 124.0, 174.0, 130.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.4}, {"lowerIsBetter": true, "replicates": [162.0, 161.0, 163.0, 161.0, 160.0, 160.0, 161.0, 162.0, 161.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 161.2}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 84.0, 90.0, 72.0, 71.0, 72.0, 96.0, 91.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 79.0}, {"lowerIsBetter": true, "replicates": [90.0, 90.0, 135.0, 89.0, 90.0, 90.0, 137.0, 90.0, 91.0, 132.0], "unit": "ms", "name": "audio-oscillator", "value": 103.4}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 110.0, 109.0, 110.0, 109.0, 109.0, 110.0, 111.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [58.0, 57.0, 58.0, 58.0, 59.0, 58.0, 59.0, 66.0, 57.0, 65.0], "unit": "ms", "name": "json-parse-financial", "value": 59.5}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 69.0, 70.0, 70.0, 70.0, 70.0, 70.0, 70.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.8}, {"lowerIsBetter": true, "replicates": [85.0, 87.0, 86.0, 86.0, 85.0, 86.0, 85.0, 86.0, 86.0, 88.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.0}, {"lowerIsBetter": true, "replicates": [120.0, 114.0, 118.0, 124.0, 117.0, 117.0, 125.0, 117.0, 130.0, 122.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 120.4}, {"lowerIsBetter": true, "replicates": [140.0, 147.0, 145.0, 153.0, 148.0, 139.0, 147.0, 146.0, 149.0, 137.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 145.1}, {"lowerIsBetter": true, "replicates": [65.0, 66.0, 70.0, 66.0, 65.0, 64.0, 66.0, 66.0, 66.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.0}], "name": "kraken", "value": 1492.2}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [162.76041666666666], "unit": "score", "name": "Richards", "value": 216.895488}, {"lowerIsBetter": false, "replicates": [192.97568506368196], "unit": "score", "name": "DeltaBlue", "value": 342.6234759999999}, {"lowerIsBetter": false, "replicates": [283.28611898017], "unit": "score", "name": "Encrypt", "value": 939.6189299999998}, {"lowerIsBetter": false, "replicates": [5050.50505050505], "unit": "score", "name": "Decrypt", "value": 52.70383799999996}, {"lowerIsBetter": false, "replicates": [1103.7527593818984], "unit": "score", "name": "RayTrace", "value": 670.4300340000001}, {"lowerIsBetter": false, "replicates": [778.816199376947], "unit": "score", "name": "Earley", "value": 855.7384919999998}, {"lowerIsBetter": false, "replicates": [12231.707317073171], "unit": "score", "name": "Boyer", "value": 54.4865064805583}, {"lowerIsBetter": false, "replicates": [25743.589743589742], "unit": "score", "name": "RegExp", "value": 35.38686752988046}, {"lowerIsBetter": false, "replicates": [651.7509727626459], "unit": "score", "name": "Splay", "value": 125.03395223880592}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 20947.64197811024}]} 10:08:27 INFO - RETURN: dromaeo_css: 6766.65 10:08:27 INFO - RETURN: kraken: 1492.20 10:08:27 INFO - RETURN: v8_7: 20947.64 10:08:27 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,201,35]]", "result": "20947.64"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,201,35]]", "result": "1492.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,201,35]]", "result": "6766.65"}}} 10:08:27 INFO - Return code: 0 10:08:27 INFO - # TBPL SUCCESS # 10:08:27 INFO - Running post-action listener: _resource_record_post_action 10:08:27 INFO - Running post-run listener: _resource_record_post_run 10:08:29 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 0; Write bytes: 497401856; Read time: 0; Write time: 439304 10:08:29 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 164139008; Read time: 0; Write time: 179328 10:08:29 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 0; Write bytes: 333258752; Read time: 0; Write time: 259976 10:08:29 INFO - Running post-run listener: _upload_blobber_files 10:08:29 INFO - Blob upload gear active. 10:08:29 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:08:29 INFO - Copying logs to upload dir... 10:08:29 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1263.840350 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2015-11-19 10:08:28.378294) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 10:08:28.381623) ========= 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=7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009919 build_url: 'https://queue.taskcluster.net/v1/task/HhtYpD6PRmCipMIwJ7dcSQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 10:08:28.433743) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 10:08:28.434189) ========= 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=7d909545e45784438f4e8e36000001b3-1447955243.253248-1857553905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004503 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 10:08:28.485387) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 10:08:28.485805) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 10:08:28.486151) ========= ========= Total master_lag: 0.22 =========