builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-067 starttime: 1450445317.61 results: success (0) buildid: 20151218030232 builduid: dd2c433550c748459b7231a0d77fc8ff revision: 66fb852962c0d5f6f5fe0604204da4f5d17763c9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.608560) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.609076) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.609556) ========= 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=3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006325 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.650210) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.650518) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.716600) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.716856) ========= 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=3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004530 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.768122) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.768396) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.768739) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.769034) ========= 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=3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-18 05:28:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 33.8M=0s 2015-12-18 05:28:38 (33.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.169706 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.952930) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:37.953467) ========= 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=3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018387 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:38.005380) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:38.005705) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 66fb852962c0d5f6f5fe0604204da4f5d17763c9 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 66fb852962c0d5f6f5fe0604204da4f5d17763c9 --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=3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-18 05:28:38,980 truncating revision to first 12 chars 2015-12-18 05:28:38,980 Setting DEBUG logging. 2015-12-18 05:28:38,980 attempt 1/10 2015-12-18 05:28:38,981 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/66fb852962c0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-18 05:28:39,230 unpacking tar archive at: mozilla-central-66fb852962c0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.530034 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:38.551420) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:38.551686) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:38.562061) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:38.562312) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-18 05:28:38.562675) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2015-12-18 05:28:38.562959) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:28:39 INFO - MultiFileLogger online at 20151218 05:28:39 in /builds/slave/test-pgo 05:28:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:28:39 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:28:39 INFO - {'append_to_log': False, 05:28:39 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:28:39 INFO - 'blob_upload_branch': 'Firefox', 05:28:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:28:39 INFO - 'branch': 'Firefox', 05:28:39 INFO - 'buildbot_json_path': 'buildprops.json', 05:28:39 INFO - 'config_files': ('talos/linux_config.py',), 05:28:39 INFO - 'default_actions': ('clobber', 05:28:39 INFO - 'read-buildbot-config', 05:28:39 INFO - 'download-and-extract', 05:28:39 INFO - 'populate-webroot', 05:28:39 INFO - 'create-virtualenv', 05:28:39 INFO - 'install', 05:28:39 INFO - 'run-tests'), 05:28:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:28:39 INFO - 'download_minidump_stackwalk': True, 05:28:39 INFO - 'download_symbols': 'ondemand', 05:28:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:28:39 INFO - 'tooltool.py': '/tools/tooltool.py', 05:28:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:28:39 INFO - '/tools/misc-python/virtualenv.py')}, 05:28:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:28:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:28:39 INFO - 'installer_path': 'installer.exe', 05:28:39 INFO - 'log_level': 'info', 05:28:39 INFO - 'log_name': 'talos', 05:28:39 INFO - 'log_to_console': True, 05:28:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:28:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:28:39 INFO - 'opt_config_files': (), 05:28:39 INFO - 'pip_index': False, 05:28:39 INFO - 'sps_profile': False, 05:28:39 INFO - 'sps_profile_interval': 0, 05:28:39 INFO - 'suite': 'dromaeojs', 05:28:39 INFO - 'system_bits': '64', 05:28:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:28:39 INFO - 'title': 'talos-linux64-ix-067', 05:28:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:28:39 INFO - 'use_talos_json': True, 05:28:39 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:28:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:28:39 INFO - 'work_dir': 'build'} 05:28:39 INFO - ##### 05:28:39 INFO - ##### Running clobber step. 05:28:39 INFO - ##### 05:28:39 INFO - Running pre-action listener: _resource_record_pre_action 05:28:39 INFO - Running main action method: clobber 05:28:39 INFO - rmtree: /builds/slave/test-pgo/build 05:28:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:28:40 INFO - Running post-action listener: _resource_record_post_action 05:28:40 INFO - ##### 05:28:40 INFO - ##### Running read-buildbot-config step. 05:28:40 INFO - ##### 05:28:40 INFO - Running pre-action listener: _resource_record_pre_action 05:28:40 INFO - Running main action method: read_buildbot_config 05:28:40 INFO - Using buildbot properties: 05:28:40 INFO - { 05:28:40 INFO - "project": "", 05:28:40 INFO - "product": "firefox", 05:28:40 INFO - "script_repo_revision": "production", 05:28:40 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:28:40 INFO - "repository": "", 05:28:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:28:40 INFO - "buildid": "20151218030232", 05:28:40 INFO - "stage_platform": "linux64-pgo", 05:28:40 INFO - "basedir": "/builds/slave/test-pgo", 05:28:40 INFO - "nightly_build": "True", 05:28:40 INFO - "buildnumber": 607, 05:28:40 INFO - "slavename": "talos-linux64-ix-067", 05:28:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:28:40 INFO - "platform": "ubuntu64_hw", 05:28:40 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:28:40 INFO - "branch": "mozilla-central", 05:28:40 INFO - "repo_path": "mozilla-central", 05:28:40 INFO - "revision": "66fb852962c0d5f6f5fe0604204da4f5d17763c9", 05:28:40 INFO - "pgo_build": "True", 05:28:40 INFO - "builduid": "dd2c433550c748459b7231a0d77fc8ff", 05:28:40 INFO - "slavebuilddir": "test" 05:28:40 INFO - } 05:28:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 05:28:40 INFO - Running post-action listener: _resource_record_post_action 05:28:40 INFO - ##### 05:28:40 INFO - ##### Running download-and-extract step. 05:28:40 INFO - ##### 05:28:40 INFO - Running pre-action listener: _resource_record_pre_action 05:28:40 INFO - Running main action method: download_and_extract 05:28:40 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:28: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')]} 05:28:40 INFO - https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:28:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/test_packages.json 05:28:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/test_packages.json 05:28:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:28:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:28:43 INFO - Downloaded 1302 bytes. 05:28:43 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:28:43 INFO - Using the following test package requirements: 05:28:43 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:28:43 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:28:43 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:43 INFO - u'jsshell-linux-x86_64.zip'], 05:28:43 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:28:43 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:28:43 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:28:43 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:28:43 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:28:43 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:28:43 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:28:43 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:43 INFO - https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:28:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:28:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:28:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:28:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:28:44 INFO - Downloaded 23120197 bytes. 05:28:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:28:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:28:44 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:28:45 INFO - Return code: 0 05:28:45 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:28: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')]} 05:28:45 INFO - https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:28:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:28:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:28:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:28:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:28:56 INFO - Downloaded 23120197 bytes. 05:28:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:28:56 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:28:56 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:28:57 INFO - Return code: 0 05:28: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')]} 05:28:57 INFO - https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:28:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:28:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:28:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:28:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:28:58 INFO - Downloaded 11132039 bytes. 05:28:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 05:28:58 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:28:58 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 05:28:58 INFO - Return code: 0 05:28:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:58 INFO - https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:28:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:28:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:28:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:28:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:28:59 INFO - Downloaded 54644993 bytes. 05:28:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:28:59 INFO - mkdir: /builds/slave/test-pgo/properties 05:28:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:28:59 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:28:59 INFO - Contents: 05:28:59 INFO - build_url:https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:28:59 INFO - Running post-action listener: _resource_record_post_action 05:28:59 INFO - Running post-action listener: set_extra_try_arguments 05:28:59 INFO - ##### 05:28:59 INFO - ##### Running populate-webroot step. 05:28:59 INFO - ##### 05:28:59 INFO - Running pre-action listener: _resource_record_pre_action 05:28:59 INFO - Running main action method: populate_webroot 05:28:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:28:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:28:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:28:59 INFO - '--tppagecycles', 05:28:59 INFO - '7'], 05:28:59 INFO - 'tests': ['tsvgm']}}, 05:28:59 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:28:59 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:28:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:28:59 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:28:59 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:28:59 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:28:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:59 INFO - 'talos_options': ['--e10s'], 05:28:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:59 INFO - 'tests': ['damp', 'tps']}, 05:28:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:59 INFO - 'talos_options': ['--e10s'], 05:28:59 INFO - 'tests': ['damp', 'tps']}, 05:28:59 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:28:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:28:59 INFO - 'other': {'tests': ['a11yr', 05:28:59 INFO - 'ts_paint', 05:28:59 INFO - 'tpaint', 05:28:59 INFO - 'sessionrestore', 05:28:59 INFO - 'sessionrestore_no_auto_restore']}, 05:28:59 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:28:59 INFO - 'tests': ['a11yr', 05:28:59 INFO - 'ts_paint', 05:28:59 INFO - 'tpaint', 05:28:59 INFO - 'sessionrestore', 05:28:59 INFO - 'sessionrestore_no_auto_restore']}, 05:28:59 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:28:59 INFO - 'tests': ['a11yr', 05:28:59 INFO - 'ts_paint', 05:28:59 INFO - 'tpaint', 05:28:59 INFO - 'sessionrestore', 05:28:59 INFO - 'sessionrestore_no_auto_restore']}, 05:28:59 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:28:59 INFO - 'tests': ['a11yr', 05:28:59 INFO - 'ts_paint', 05:28:59 INFO - 'tpaint', 05:28:59 INFO - 'sessionrestore', 05:28:59 INFO - 'sessionrestore_no_auto_restore']}, 05:28:59 INFO - 'other_l64': {'tests': ['a11yr', 05:28:59 INFO - 'ts_paint', 05:28:59 INFO - 'tpaint', 05:28:59 INFO - 'sessionrestore', 05:28:59 INFO - 'sessionrestore_no_auto_restore']}, 05:28:59 INFO - 'other_nol64': {'tests': ['a11yr', 05:28:59 INFO - 'ts_paint', 05:28:59 INFO - 'tpaint', 05:28:59 INFO - 'sessionrestore', 05:28:59 INFO - 'sessionrestore_no_auto_restore']}, 05:28:59 INFO - 'svgr': {'tests': ['tsvgx', 05:28:59 INFO - 'tsvgr_opacity', 05:28:59 INFO - 'tart', 05:28:59 INFO - 'tscrollx', 05:28:59 INFO - 'cart']}, 05:28:59 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:28:59 INFO - 'tests': ['tsvgx', 05:28:59 INFO - 'tsvgr_opacity', 05:28:59 INFO - 'tart', 05:28:59 INFO - 'tscrollx', 05:28:59 INFO - 'cart']}, 05:28:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:59 INFO - 'tests': ['tp5o']}, 05:28:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:59 INFO - 'talos_options': ['--e10s'], 05:28:59 INFO - 'tests': ['tp5o']}, 05:28:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:59 INFO - 'talos_options': ['--xperf_path', 05:28:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:59 INFO - 'tests': ['tp5n']}, 05:28:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:59 INFO - 'talos_options': ['--e10s', 05:28:59 INFO - '--xperf_path', 05:28:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:59 INFO - 'tests': ['tp5n']}}, 05:28:59 INFO - 'talos.zip': {'path': '', 05:28:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:28:59 INFO - Running post-action listener: _resource_record_post_action 05:28:59 INFO - ##### 05:28:59 INFO - ##### Running create-virtualenv step. 05:28:59 INFO - ##### 05:28:59 INFO - Running pre-action listener: _resource_record_pre_action 05:28:59 INFO - Running main action method: create_virtualenv 05:28:59 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:28:59 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 05:28:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:28:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:28:59 INFO - Using real prefix '/usr' 05:28:59 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:29:00 INFO - Installing distribute.............................................................................................................................................................................................done. 05:29:02 INFO - Installing pip.................done. 05:29:02 INFO - Return code: 0 05:29:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:02 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 0x7fbeb9e92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2109600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2198350>, '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 0x2199250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21996e0>, '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': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', '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 05:29:02 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 05:29:02 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 05:29:02 INFO - Using env: {'DISPLAY': ':0', 05:29:02 INFO - 'HOME': '/home/cltbld', 05:29:02 INFO - 'LANG': 'en_US.UTF-8', 05:29:02 INFO - 'LANGUAGE': 'en_US:en', 05:29:02 INFO - 'LOGNAME': 'cltbld', 05:29:02 INFO - 'MAIL': '/var/mail/cltbld', 05:29:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:02 INFO - 'MOZ_NO_REMOTE': '1', 05:29:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:02 INFO - 'NO_EM_RESTART': '1', 05:29:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:02 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:02 INFO - 'SHELL': '/bin/bash', 05:29:02 INFO - 'SHLVL': '1', 05:29:02 INFO - 'TERM': 'linux', 05:29:02 INFO - 'TMOUT': '86400', 05:29:02 INFO - 'USER': 'cltbld', 05:29:02 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', 05:29:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:02 INFO - '_': '/tools/buildbot/bin/python'} 05:29:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:02 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 05:29:02 INFO - Cleaning up... 05:29:02 INFO - Return code: 0 05:29:02 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:02 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 0x7fbeb9e92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2109600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2198350>, '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 0x2199250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21996e0>, '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': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', '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 05:29:02 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 05:29:02 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 05:29:02 INFO - Using env: {'DISPLAY': ':0', 05:29:02 INFO - 'HOME': '/home/cltbld', 05:29:02 INFO - 'LANG': 'en_US.UTF-8', 05:29:02 INFO - 'LANGUAGE': 'en_US:en', 05:29:02 INFO - 'LOGNAME': 'cltbld', 05:29:02 INFO - 'MAIL': '/var/mail/cltbld', 05:29:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:02 INFO - 'MOZ_NO_REMOTE': '1', 05:29:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:02 INFO - 'NO_EM_RESTART': '1', 05:29:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:02 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:02 INFO - 'SHELL': '/bin/bash', 05:29:02 INFO - 'SHLVL': '1', 05:29:02 INFO - 'TERM': 'linux', 05:29:02 INFO - 'TMOUT': '86400', 05:29:02 INFO - 'USER': 'cltbld', 05:29:02 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', 05:29:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:02 INFO - '_': '/tools/buildbot/bin/python'} 05:29:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:02 INFO - Downloading/unpacking psutil>=0.7.1 05:29:02 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 05:29:02 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 05:29:02 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 05:29:02 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 05:29:02 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 05:29:02 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 05:29:04 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:29:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:29:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:29:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:04 INFO - Installing collected packages: psutil 05:29:04 INFO - Running setup.py install for psutil 05:29:04 INFO - building 'psutil._psutil_linux' extension 05:29:04 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 05:29:04 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 05:29:04 INFO - building 'psutil._psutil_posix' extension 05:29:04 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 05:29:04 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 05:29:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:05 INFO - Successfully installed psutil 05:29:05 INFO - Cleaning up... 05:29:05 INFO - Return code: 0 05:29:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:29:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:05 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 0x7fbeb9e92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2109600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2198350>, '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 0x2199250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21996e0>, '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': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', '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 05:29:05 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 05:29:05 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 05:29:05 INFO - Using env: {'DISPLAY': ':0', 05:29:05 INFO - 'HOME': '/home/cltbld', 05:29:05 INFO - 'LANG': 'en_US.UTF-8', 05:29:05 INFO - 'LANGUAGE': 'en_US:en', 05:29:05 INFO - 'LOGNAME': 'cltbld', 05:29:05 INFO - 'MAIL': '/var/mail/cltbld', 05:29:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:05 INFO - 'MOZ_NO_REMOTE': '1', 05:29:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:05 INFO - 'NO_EM_RESTART': '1', 05:29:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:05 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:05 INFO - 'SHELL': '/bin/bash', 05:29:05 INFO - 'SHLVL': '1', 05:29:05 INFO - 'TERM': 'linux', 05:29:05 INFO - 'TMOUT': '86400', 05:29:05 INFO - 'USER': 'cltbld', 05:29:05 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', 05:29:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:05 INFO - '_': '/tools/buildbot/bin/python'} 05:29:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:06 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:29:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:29:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:29:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:29:07 INFO - Installing collected packages: mozsystemmonitor 05:29:07 INFO - Running setup.py install for mozsystemmonitor 05:29:07 INFO - Successfully installed mozsystemmonitor 05:29:07 INFO - Cleaning up... 05:29:07 INFO - Return code: 0 05:29:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:07 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 0x7fbeb9e92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2109600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2198350>, '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 0x2199250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21996e0>, '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': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', '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 05:29:07 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 05:29:07 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 05:29:07 INFO - Using env: {'DISPLAY': ':0', 05:29:07 INFO - 'HOME': '/home/cltbld', 05:29:07 INFO - 'LANG': 'en_US.UTF-8', 05:29:07 INFO - 'LANGUAGE': 'en_US:en', 05:29:07 INFO - 'LOGNAME': 'cltbld', 05:29:07 INFO - 'MAIL': '/var/mail/cltbld', 05:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:07 INFO - 'MOZ_NO_REMOTE': '1', 05:29:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:07 INFO - 'NO_EM_RESTART': '1', 05:29:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:07 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:07 INFO - 'SHELL': '/bin/bash', 05:29:07 INFO - 'SHLVL': '1', 05:29:07 INFO - 'TERM': 'linux', 05:29:07 INFO - 'TMOUT': '86400', 05:29:07 INFO - 'USER': 'cltbld', 05:29:07 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', 05:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:07 INFO - '_': '/tools/buildbot/bin/python'} 05:29:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:07 INFO - Downloading/unpacking blobuploader==1.2.4 05:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:09 INFO - Downloading blobuploader-1.2.4.tar.gz 05:29:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:29:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:29:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:29:09 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 05:29:09 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 05:29:09 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 05:29:09 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 05:29:09 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 05:29:09 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 05:29:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:29:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:29:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:29:09 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 05:29:09 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 05:29:09 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 05:29:09 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 05:29:09 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 05:29:09 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 05:29:09 INFO - Downloading docopt-0.6.1.tar.gz 05:29:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:29:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:29:09 INFO - Installing collected packages: blobuploader, requests, docopt 05:29:09 INFO - Running setup.py install for blobuploader 05:29:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:29:09 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:29:09 INFO - Running setup.py install for requests 05:29:10 INFO - Running setup.py install for docopt 05:29:10 INFO - Successfully installed blobuploader requests docopt 05:29:10 INFO - Cleaning up... 05:29:10 INFO - Return code: 0 05:29:10 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:10 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 0x7fbeb9e92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2109600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2198350>, '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 0x2199250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21996e0>, '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': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', '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 05:29:10 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 05:29:10 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 05:29:10 INFO - Using env: {'DISPLAY': ':0', 05:29:10 INFO - 'HOME': '/home/cltbld', 05:29:10 INFO - 'LANG': 'en_US.UTF-8', 05:29:10 INFO - 'LANGUAGE': 'en_US:en', 05:29:10 INFO - 'LOGNAME': 'cltbld', 05:29:10 INFO - 'MAIL': '/var/mail/cltbld', 05:29:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:10 INFO - 'MOZ_NO_REMOTE': '1', 05:29:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:10 INFO - 'NO_EM_RESTART': '1', 05:29:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:10 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:10 INFO - 'SHELL': '/bin/bash', 05:29:10 INFO - 'SHLVL': '1', 05:29:10 INFO - 'TERM': 'linux', 05:29:10 INFO - 'TMOUT': '86400', 05:29:10 INFO - 'USER': 'cltbld', 05:29:10 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', 05:29:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:10 INFO - '_': '/tools/buildbot/bin/python'} 05:29:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:29:10 INFO - Running setup.py (path:/tmp/pip-zc2HtY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:29:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:29:10 INFO - Running setup.py (path:/tmp/pip-1OvUtc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:29:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:29:10 INFO - Running setup.py (path:/tmp/pip-ZLkq0n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:29:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:29:10 INFO - Running setup.py (path:/tmp/pip-PAZ55H-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:29:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:29:10 INFO - Running setup.py (path:/tmp/pip-Mikab9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:29:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:29:10 INFO - Running setup.py (path:/tmp/pip-2wv4of-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:29:11 INFO - Running setup.py (path:/tmp/pip-cPTt7M-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:29:11 INFO - Running setup.py (path:/tmp/pip-DZCvJF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:29:11 INFO - Running setup.py (path:/tmp/pip-P1sSML-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:29:11 INFO - Running setup.py (path:/tmp/pip-ImhsNP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:29:11 INFO - Running setup.py (path:/tmp/pip-98BYDN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:29:11 INFO - Running setup.py (path:/tmp/pip-MR2zNu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:29:11 INFO - Running setup.py (path:/tmp/pip-qGHCAb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:29:11 INFO - Running setup.py (path:/tmp/pip-SYc69q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:29:11 INFO - Running setup.py (path:/tmp/pip-HQwr7G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:29:11 INFO - Running setup.py (path:/tmp/pip-QrwAtv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:29:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:29:11 INFO - Running setup.py (path:/tmp/pip-a04zkD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:29:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:29:11 INFO - Running setup.py install for manifestparser 05:29:11 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:29:11 INFO - Running setup.py install for mozcrash 05:29:12 INFO - Running setup.py install for mozdebug 05:29:12 INFO - Running setup.py install for mozdevice 05:29:12 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:29:12 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:29:12 INFO - Running setup.py install for mozfile 05:29:12 INFO - Running setup.py install for mozhttpd 05:29:12 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:29:12 INFO - Running setup.py install for mozinfo 05:29:12 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:29:12 INFO - Running setup.py install for mozInstall 05:29:12 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:29:12 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:29:12 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:29:12 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:29:12 INFO - Running setup.py install for mozleak 05:29:12 INFO - Running setup.py install for mozlog 05:29:12 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:29:12 INFO - Running setup.py install for moznetwork 05:29:12 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:29:12 INFO - Running setup.py install for mozprocess 05:29:13 INFO - Running setup.py install for mozprofile 05:29:13 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:29:13 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:29:13 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:29:13 INFO - Running setup.py install for mozrunner 05:29:13 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:29:13 INFO - Running setup.py install for mozscreenshot 05:29:13 INFO - Running setup.py install for moztest 05:29:13 INFO - Running setup.py install for mozversion 05:29:13 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:29:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:29:13 INFO - Cleaning up... 05:29:13 INFO - Return code: 0 05:29:13 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:29:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:13 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 0x7fbeb9e92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2109600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2198350>, '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 0x2199250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21996e0>, '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': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', '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 05:29:13 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 05:29:13 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 05:29:13 INFO - Using env: {'DISPLAY': ':0', 05:29:13 INFO - 'HOME': '/home/cltbld', 05:29:13 INFO - 'LANG': 'en_US.UTF-8', 05:29:13 INFO - 'LANGUAGE': 'en_US:en', 05:29:13 INFO - 'LOGNAME': 'cltbld', 05:29:13 INFO - 'MAIL': '/var/mail/cltbld', 05:29:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:13 INFO - 'MOZ_NO_REMOTE': '1', 05:29:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:13 INFO - 'NO_EM_RESTART': '1', 05:29:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:13 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:13 INFO - 'SHELL': '/bin/bash', 05:29:13 INFO - 'SHLVL': '1', 05:29:13 INFO - 'TERM': 'linux', 05:29:13 INFO - 'TMOUT': '86400', 05:29:13 INFO - 'USER': 'cltbld', 05:29:13 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', 05:29:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:13 INFO - '_': '/tools/buildbot/bin/python'} 05:29:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:29:13 INFO - Running setup.py (path:/tmp/pip-LLsdqz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:29:13 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)) 05:29:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:29:13 INFO - Running setup.py (path:/tmp/pip-F4ePh4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:29:13 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)) 05:29:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:29:13 INFO - Running setup.py (path:/tmp/pip-73f9dW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:29:14 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)) 05:29:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:29:14 INFO - Running setup.py (path:/tmp/pip-s5bcZY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:29:14 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)) 05:29:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:29:14 INFO - Running setup.py (path:/tmp/pip-IBI142-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:29:14 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)) 05:29:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:29:14 INFO - Running setup.py (path:/tmp/pip-__oanU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:29:14 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)) 05:29:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:29:14 INFO - Running setup.py (path:/tmp/pip-j_rmuR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:29:14 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)) 05:29:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:29:14 INFO - Running setup.py (path:/tmp/pip-y7OOsu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:29:14 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)) 05:29:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:29:14 INFO - Running setup.py (path:/tmp/pip-aiK8JO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:29:14 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)) 05:29:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:29:14 INFO - Running setup.py (path:/tmp/pip-_p7thi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:29:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:29:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:29:14 INFO - Running setup.py (path:/tmp/pip-yya2_e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:29:14 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)) 05:29:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:29:14 INFO - Running setup.py (path:/tmp/pip-VudlME-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:29:14 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)) 05:29:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:29:14 INFO - Running setup.py (path:/tmp/pip-BU6FVg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:29:14 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)) 05:29:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:29:14 INFO - Running setup.py (path:/tmp/pip-k6Voen-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:29:14 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)) 05:29:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:29:14 INFO - Running setup.py (path:/tmp/pip-MPilCs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:29:14 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)) 05:29:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:29:14 INFO - Running setup.py (path:/tmp/pip-kxuxxS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:29:14 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)) 05:29:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:29:14 INFO - Running setup.py (path:/tmp/pip-_5oxaV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:29:15 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)) 05:29:15 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)) 05:29:15 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)) 05:29:15 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)) 05:29:15 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)) 05:29:15 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)) 05:29:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:29:15 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 05:29:15 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 05:29:15 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 05:29:15 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 05:29:15 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 05:29:15 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 05:29:16 INFO - Downloading blessings-1.5.1.tar.gz 05:29:16 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 05:29:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:29:16 INFO - Installing collected packages: blessings 05:29:16 INFO - Running setup.py install for blessings 05:29:16 INFO - Successfully installed blessings 05:29:16 INFO - Cleaning up... 05:29:16 INFO - Return code: 0 05:29:16 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:29:16 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:29:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:29:17 INFO - Reading from file tmpfile_stdout 05:29:17 INFO - Current package versions: 05:29:17 INFO - argparse == 1.2.1 05:29:17 INFO - blessings == 1.5.1 05:29:17 INFO - blobuploader == 1.2.4 05:29:17 INFO - docopt == 0.6.1 05:29:17 INFO - manifestparser == 1.1 05:29:17 INFO - mozInstall == 1.12 05:29:17 INFO - mozcrash == 0.16 05:29:17 INFO - mozdebug == 0.1 05:29:17 INFO - mozdevice == 0.47 05:29:17 INFO - mozfile == 1.2 05:29:17 INFO - mozhttpd == 0.7 05:29:17 INFO - mozinfo == 0.9 05:29:17 INFO - mozleak == 0.1 05:29:17 INFO - mozlog == 3.1 05:29:17 INFO - moznetwork == 0.27 05:29:17 INFO - mozprocess == 0.22 05:29:17 INFO - mozprofile == 0.27 05:29:17 INFO - mozrunner == 6.11 05:29:17 INFO - mozscreenshot == 0.1 05:29:17 INFO - mozsystemmonitor == 0.0 05:29:17 INFO - moztest == 0.7 05:29:17 INFO - mozversion == 1.4 05:29:17 INFO - psutil == 3.1.1 05:29:17 INFO - requests == 1.2.3 05:29:17 INFO - wsgiref == 0.1.2 05:29:17 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:29:17 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')]} 05:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:17 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')]} 05:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:17 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 0x7fbeb9e92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2109600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2198350>, '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 0x2199250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21996e0>, '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': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', '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 05:29:17 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 05:29:17 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 05:29:17 INFO - Using env: {'DISPLAY': ':0', 05:29:17 INFO - 'HOME': '/home/cltbld', 05:29:17 INFO - 'LANG': 'en_US.UTF-8', 05:29:17 INFO - 'LANGUAGE': 'en_US:en', 05:29:17 INFO - 'LOGNAME': 'cltbld', 05:29:17 INFO - 'MAIL': '/var/mail/cltbld', 05:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:17 INFO - 'MOZ_NO_REMOTE': '1', 05:29:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:17 INFO - 'NO_EM_RESTART': '1', 05:29:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:17 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:17 INFO - 'SHELL': '/bin/bash', 05:29:17 INFO - 'SHLVL': '1', 05:29:17 INFO - 'TERM': 'linux', 05:29:17 INFO - 'TMOUT': '86400', 05:29:17 INFO - 'USER': 'cltbld', 05:29:17 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', 05:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:17 INFO - '_': '/tools/buildbot/bin/python'} 05:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:17 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)) 05:29:17 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)) 05:29:17 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)) 05:29:17 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)) 05:29:17 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)) 05:29:17 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)) 05:29:17 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)) 05:29:17 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)) 05:29:17 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)) 05:29:17 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)) 05:29:17 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)) 05:29:17 INFO - Cleaning up... 05:29:17 INFO - Return code: 0 05:29:17 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:29:17 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')]} 05:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:17 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')]} 05:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:17 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbeb9e92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2109600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2198350>, '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 0x2199250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21996e0>, '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': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', '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 05:29:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 05:29:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:29:17 INFO - Using env: {'DISPLAY': ':0', 05:29:17 INFO - 'HOME': '/home/cltbld', 05:29:17 INFO - 'LANG': 'en_US.UTF-8', 05:29:17 INFO - 'LANGUAGE': 'en_US:en', 05:29:17 INFO - 'LOGNAME': 'cltbld', 05:29:17 INFO - 'MAIL': '/var/mail/cltbld', 05:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:17 INFO - 'MOZ_NO_REMOTE': '1', 05:29:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:17 INFO - 'NO_EM_RESTART': '1', 05:29:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:17 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:17 INFO - 'SHELL': '/bin/bash', 05:29:17 INFO - 'SHLVL': '1', 05:29:17 INFO - 'TERM': 'linux', 05:29:17 INFO - 'TMOUT': '86400', 05:29:17 INFO - 'USER': 'cltbld', 05:29:17 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', 05:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:17 INFO - '_': '/tools/buildbot/bin/python'} 05:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:17 INFO - Downloading/unpacking jsonschema 05:29:17 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 05:29:17 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 05:29:17 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 05:29:17 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 05:29:17 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 05:29:17 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 05:29:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:29:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 05:29:19 INFO - Downloading/unpacking functools32 (from jsonschema) 05:29:19 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 05:29:19 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 05:29:19 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 05:29:19 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 05:29:19 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 05:29:19 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 05:29:19 INFO - Downloading functools32-3.2.3-2.tar.gz 05:29:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:29:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:29:19 INFO - warning: no files found matching '*.txt' 05:29:19 INFO - no previously-included directories found matching 'build' 05:29:19 INFO - no previously-included directories found matching 'dist' 05:29:19 INFO - no previously-included directories found matching '.git*' 05:29:19 INFO - Installing collected packages: jsonschema, functools32 05:29:19 INFO - Running setup.py install for functools32 05:29:19 INFO - warning: no files found matching '*.txt' 05:29:19 INFO - no previously-included directories found matching 'build' 05:29:19 INFO - no previously-included directories found matching 'dist' 05:29:19 INFO - no previously-included directories found matching '.git*' 05:29:19 INFO - Successfully installed jsonschema functools32 05:29:19 INFO - Cleaning up... 05:29:19 INFO - Return code: 0 05:29:19 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 05:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:19 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbeb9e92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2109600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2198350>, '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 0x2199250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21996e0>, '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': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', '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 05:29:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 05:29:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:29:19 INFO - Using env: {'DISPLAY': ':0', 05:29:19 INFO - 'HOME': '/home/cltbld', 05:29:19 INFO - 'LANG': 'en_US.UTF-8', 05:29:19 INFO - 'LANGUAGE': 'en_US:en', 05:29:19 INFO - 'LOGNAME': 'cltbld', 05:29:19 INFO - 'MAIL': '/var/mail/cltbld', 05:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:19 INFO - 'MOZ_NO_REMOTE': '1', 05:29:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:19 INFO - 'NO_EM_RESTART': '1', 05:29:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:19 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:19 INFO - 'SHELL': '/bin/bash', 05:29:19 INFO - 'SHLVL': '1', 05:29:19 INFO - 'TERM': 'linux', 05:29:19 INFO - 'TMOUT': '86400', 05:29:19 INFO - 'USER': 'cltbld', 05:29:19 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', 05:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:19 INFO - '_': '/tools/buildbot/bin/python'} 05:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:19 INFO - Downloading/unpacking flake8 05:29:19 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 05:29:19 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 05:29:19 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 05:29:19 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 05:29:19 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 05:29:19 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 05:29:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:29:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 05:29:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:29:21 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 05:29:21 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 05:29:21 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 05:29:21 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 05:29:21 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 05:29:21 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 05:29:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:29:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 05:29:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:29:21 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 05:29:21 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 05:29:21 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 05:29:21 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 05:29:21 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 05:29:21 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 05:29:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 05:29:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:29:22 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 05:29:22 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 05:29:22 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 05:29:22 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 05:29:22 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 05:29:22 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 05:29:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 05:29:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 05:29:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:29:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:29:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:29:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:29:22 INFO - no previously-included directories found matching 'docs/_build' 05:29:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:29:22 INFO - Running setup.py install for pep8 05:29:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:29:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:29:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:29:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:29:22 INFO - no previously-included directories found matching 'docs/_build' 05:29:22 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 05:29:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:29:22 INFO - Cleaning up... 05:29:22 INFO - Return code: 0 05:29:22 INFO - Running post-action listener: _resource_record_post_action 05:29:22 INFO - Running post-action listener: _start_resource_monitoring 05:29:22 INFO - Starting resource monitoring. 05:29:22 INFO - ##### 05:29:22 INFO - ##### Running install step. 05:29:22 INFO - ##### 05:29:22 INFO - Running pre-action listener: _resource_record_pre_action 05:29:22 INFO - Running main action method: install 05:29:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:29:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:29:22 INFO - Reading from file tmpfile_stdout 05:29:22 INFO - Detecting whether we're running mozinstall >=1.0... 05:29:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:29:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:29:22 INFO - Reading from file tmpfile_stdout 05:29:22 INFO - Output received: 05:29:22 INFO - Usage: mozinstall [options] installer 05:29:22 INFO - Options: 05:29:22 INFO - -h, --help show this help message and exit 05:29:22 INFO - -d DEST, --destination=DEST 05:29:22 INFO - Directory to install application into. [default: 05:29:22 INFO - "/builds/slave/test-pgo"] 05:29:22 INFO - --app=APP Application being installed. [default: firefox] 05:29:22 INFO - mkdir: /builds/slave/test-pgo/build/application 05:29:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 05:29:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 05:29:38 INFO - Reading from file tmpfile_stdout 05:29:38 INFO - Output received: 05:29:38 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:29:38 INFO - Running post-action listener: _resource_record_post_action 05:29:38 INFO - ##### 05:29:38 INFO - ##### Running run-tests step. 05:29:38 INFO - ##### 05:29:38 INFO - Running pre-action listener: _resource_record_pre_action 05:29:38 INFO - Running main action method: run_tests 05:29:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:29:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:29:38 INFO - Python 2.7.3 05:29:38 INFO - Return code: 0 05:29:38 INFO - grabbing minidump binary from tooltool 05:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:38 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 0x2198350>, '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 0x2199250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21996e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:29:38 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 05:29:38 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 05:29:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:29:38 INFO - Return code: 0 05:29:38 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:29:38 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:29:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:29:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:29:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:29:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 05:29:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 05:29:39 INFO - Return code: 0 05:29:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:29:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --webServer localhost 05:29:39 INFO - Using env: {'DISPLAY': ':0', 05:29:39 INFO - 'HOME': '/home/cltbld', 05:29:39 INFO - 'LANG': 'en_US.UTF-8', 05:29:39 INFO - 'LANGUAGE': 'en_US:en', 05:29:39 INFO - 'LOGNAME': 'cltbld', 05:29:39 INFO - 'MAIL': '/var/mail/cltbld', 05:29:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:29:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:29:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:39 INFO - 'MOZ_NO_REMOTE': '1', 05:29:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:29:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:39 INFO - 'NO_EM_RESTART': '1', 05:29:39 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:39 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:39 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:29:39 INFO - 'SHELL': '/bin/bash', 05:29:39 INFO - 'SHLVL': '1', 05:29:39 INFO - 'TERM': 'linux', 05:29:39 INFO - 'TMOUT': '86400', 05:29:39 INFO - 'USER': 'cltbld', 05:29:39 INFO - 'XDG_SESSION_COOKIE': '3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083', 05:29:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:39 INFO - '_': '/tools/buildbot/bin/python'} 05:29:39 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--webServer', 'localhost'] with output_timeout 3600 05:29:39 INFO - mozversion INFO | application_buildid: 20151218030232 05:29:39 INFO - mozversion INFO | application_changeset: 66fb852962c0d5f6f5fe0604204da4f5d17763c9 05:29:39 INFO - mozversion INFO | application_display_name: Nightly 05:29:39 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:29:39 INFO - mozversion INFO | application_name: Firefox 05:29:39 INFO - mozversion INFO | application_remotingname: firefox 05:29:39 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 05:29:39 INFO - mozversion INFO | application_vendor: Mozilla 05:29:39 INFO - mozversion INFO | application_version: 46.0a1 05:29:39 INFO - mozversion INFO | platform_buildid: 20151218030232 05:29:39 INFO - mozversion INFO | platform_changeset: 66fb852962c0d5f6f5fe0604204da4f5d17763c9 05:29:39 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 05:29:39 INFO - mozversion INFO | platform_version: 46.0a1 05:29:39 INFO - 2015-12-18 05:29:39,819 DEBUG : using testdate: 1450445379 05:29:39 INFO - 2015-12-18 05:29:39,820 DEBUG : actual date: 1450445379 05:29:39 INFO - 2015-12-18 05:29:39,828 INFO : starting webserver on 'localhost:60407' 05:29:39 INFO - 2015-12-18 05:29:39,829 INFO : Starting test suite talos-linux64-ix-067 05:29:39 INFO - 2015-12-18 05:29:39,829 INFO : Starting test dromaeo_css 05:29:39 INFO - 2015-12-18 05:29:39,830 DEBUG : operating with platform_type : linux_ 05:29:39 INFO - 2015-12-18 05:29:39,830 INFO : Initialising browser for dromaeo_css test... 05:29:39 INFO - 2015-12-18 05:29:39,846 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplCP4oW/profile http://localhost:60407/getInfo.html 05:29:44 INFO - 2015-12-18 05:29:44,085 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:29:44 INFO - 2015-12-18 05:29:44,086 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:29:44 INFO - 2015-12-18 05:29:44,086 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:29:44 INFO - 2015-12-18 05:29:44,086 DEBUG : BROWSER_OUTPUT: __metrics 05:29:44 INFO - 2015-12-18 05:29:44,187 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:29:44 INFO - 2015-12-18 05:29:44,254 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:29:44 ERROR - 2015-12-18 05:29:44,262 DEBUG : BROWSER_OUTPUT: JavaScript error: chrome://browser/content/tab-content.js, line 408: TypeError: content is null 05:29:45 INFO - 2015-12-18 05:29:45,065 INFO : Browser initialized. 05:29:45 INFO - 2015-12-18 05:29:45,065 INFO : Running cycle 1/1 for dromaeo_css test... 05:29:45 INFO - 2015-12-18 05:29:45,066 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplCP4oW/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:29:56 INFO - 2015-12-18 05:29:56,838 DEBUG : BROWSER_OUTPUT: RSS: Main: 149753856 05:29:56 INFO - 2015-12-18 05:29:56,838 DEBUG : BROWSER_OUTPUT: 05:30:48 INFO - 2015-12-18 05:30:48,426 DEBUG : BROWSER_OUTPUT: 1450445448422 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] 05:32:57 INFO - 2015-12-18 05:32:57,151 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60407/tests/dromaeo/cssquery-ext.html) 05:32:58 INFO - 2015-12-18 05:32:58,302 DEBUG : BROWSER_OUTPUT: RSS: Main: 276799488 05:32:58 INFO - 2015-12-18 05:32:58,303 DEBUG : BROWSER_OUTPUT: 05:36:00 INFO - 2015-12-18 05:36:00,670 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/dromaeo/cssquery-ext.html (next: http://localhost:60407/tests/dromaeo/cssquery-jquery.html) 05:36:01 INFO - 2015-12-18 05:36:01,255 DEBUG : BROWSER_OUTPUT: RSS: Main: 228700160 05:36:01 INFO - 2015-12-18 05:36:01,255 DEBUG : BROWSER_OUTPUT: 05:39:03 INFO - 2015-12-18 05:39:03,106 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60407/tests/dromaeo/cssquery-mootools.html) 05:39:03 INFO - 2015-12-18 05:39:03,679 DEBUG : BROWSER_OUTPUT: RSS: Main: 228999168 05:39:03 INFO - 2015-12-18 05:39:03,680 DEBUG : BROWSER_OUTPUT: 05:42:05 INFO - 2015-12-18 05:42:05,500 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60407/tests/dromaeo/cssquery-prototype.html) 05:42:06 INFO - 2015-12-18 05:42:06,102 DEBUG : BROWSER_OUTPUT: RSS: Main: 212054016 05:42:06 INFO - 2015-12-18 05:42:06,102 DEBUG : BROWSER_OUTPUT: 05:45:08 INFO - 2015-12-18 05:45:08,663 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60407/tests/dromaeo/cssquery-yui.html) 05:45:09 INFO - 2015-12-18 05:45:09,236 DEBUG : BROWSER_OUTPUT: RSS: Main: 216707072 05:45:09 INFO - 2015-12-18 05:45:09,237 DEBUG : BROWSER_OUTPUT: 05:48:11 INFO - 2015-12-18 05:48:11,801 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/dromaeo/cssquery-yui.html (next: http://localhost:60407/tests/dromaeo/cssquery-dojo.html) 05:48:12 INFO - 2015-12-18 05:48:12,059 DEBUG : BROWSER_OUTPUT: RSS: Main: 266768384 05:48:12 INFO - 2015-12-18 05:48:12,059 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-12-18 05:48:12,059 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:48:12 INFO - 2015-12-18 05:48:12,059 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:48:12 INFO - 2015-12-18 05:48:12,060 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:48:12 INFO - 2015-12-18 05:48:12,060 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:48:12 INFO - 2015-12-18 05:48:12,060 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3177.714005739483;3188.8246146461947;3226.257960669046;3228.9838550807244;3256.3487302539497;5213.687178769276;5300.09898317286;5335.893282134355;5347.839564813057;5385.084535628947;5428.240046393508;5446.2919820423385;5457.890842183154;5475.890482190353;5574.916376254359;4928.97535512322;4965.1559234930055;4973.552380285773;5007.899842003158;5081.364829396325;4998.77505512251;5027.321311622939;5058.342415485981;5077.441481437039;5090.847274581768;4796.712197268175;4825.034993001396;4906.656534042619;4927.630427717935;4932.8273510427325;4958.603311735089;4996.050750357421;5000.74996250186;5016.2224855147515;5052.46949070346;4826.758662066883;4970.204767237225;4970.403551573798;4981.526754958273;5051.292819005343;5125.974370128163;5152.793888244503;5223.320968274138;5224.320838291036;5262.2632831403635;5851.4148585141575;5858;5859.794907178229;5868.061110222344;5877.38287479817;5640.0693885508845;5864.7067646617925;5874.265716785402;5878.088896221093;5888.941110588925;5383.892322153535;5465.863353416156;5498.890022199614;5519.034169020405;5555.2778138842095;5962.552808539377;5966;5975.223220981332;5979.730912108966;5981.850453738604;5548.389677135512;5655.151727240865;5799.2750906136735;5807.332156801937;5807.970960145172;5717.571182161271;5770;5835.183074369592;5836.737346819318;5892.499137573269;5855.180274761537;5882.058870580687;5904.5571582131515;5904.586678932434;5904.793332233351;5619.887602247933;5729.083346664514;5827;5834.649921004753;5842.386549412336;4438.822447102144;4472.172648060055;4482.8206871725415;4517.5256598057385;4519.88700282489;4012.7592344459426;4049.4735684360844;4050.6354428101613;4091.3249313863475;4093.2222877653153;5364.490373414519;5576.693281869536;5582;5582.7766889323975;5582.916256256078;5680.005998950167;5775.653460792366;5811.099279611667;5940.108983652487;5944.197533332945;5516.972415137979;5598.692071936001;5602.13166959122;5608.887822243532;5609.887802243932;5102.617303702237;5120.02719483297;5146.76839542214;5147.202183661539;5184.948150518447;5732;5851.1515830205235;5852.7658893643775;6002.369751176064;6007.609505382224;5979.970100149471;5986.790462333797;5993.910091348546;5996.010658241341;5999.640021598718;5922.170896074639;6004.0093384591055;6034.909476357946;6042.123892035718;6072.848178795565;5551.195076713773;5644.689542075225;5652.971735141298;5700.2304688868135;5899.115132730125;5881.794137205177;5889.469947704727;5894.2926848778425;5903.380145084704;5908.3500814911185;5729.684867332337;5743.4543718346695;5744.425557444222;5751.568632352674;5752;5523.116301391758;5616.8876622466505;5623.044082505902;5632.380438151882;5697.287839020122;5637.802676906289;5648.85877853057;5655.406182350794;5656.8020119295625;5660.773569057192;5714.114312281603;5744.827655170351;5750.769969201352;5761.279840019997;5771.220885180447;5466.01611709896;5527.668339899458;5605.719714014364;5630.943690563206;5677.261955945866 05:48:12 INFO - 2015-12-18 05:48:12,061 DEBUG : BROWSER_OUTPUT: |1;ext.html;550.158257865466;597.9073243647235;606.7451670298476;614.2935624032359;620.3982137326791;29381;30384.39231215377;39102.60897391025;39372.60627393725;39408.80295598518;42628.78685606568;43205.78397108018;43314.783426082824;43355.56644433555;43389.56610433895;7486.812829679247;7619.123800762928;8701.60842762074;8827.73516794494;8832.735017949472;10341.172706183524;10603;10666.360018398882;10699.465026748632;10700.839487407695;10336.896631033705;10435.113015393663;10438.68683939483;10443;10457.111145552599;7711.383089352838;7928.405369597245;10308.433036183007;10330.380177189392;10653.893461065367;10099.848502272473;10221.540030698636;10253.743656408575;10255.33340332876;10256.38461692301;40903.590964090276;41090;41327;41364.58635413637;41535;3055.541668749683;3059.2046068022473;3099.0547882895708;3173.428782819092;3584.9424419796223;29561.852190739122;30059;30240;30413.847930760425;31182.37635247305;3943;3963.286608410485;3991.940120898189;4032.717709760318;4068.1863627274665;310064;313983;314057;314486;314584;59525;60344.69827650922;60529.69735151384;60634;60707.69646151741;57611.42388576145;58537.70731146402;65517.34482655208;65818.34181658218;67255.66372168108;13474.19154850712;13538.323083845768;13613;13625.86374136246;13629.454821807214;8805.42764720291;8901.287896968357;8908.198262156307;8913.153250441197;9007.234385077341;20141.697874531892;20193.697094543593;20354.592908141753;20404.28584999518;20434.182632694527;8026.357891368676;8257;8281.8343633127;8289.963754530683;8303;954.2795189631784;1001.3040936549174;1028.9382637041801;1043.4261156363968;1094.3543309447305;28158.43683126367;28639.71360286412;28714.42571148607;29052;29057.27356816054;1002.9849552256723;1012.8075665623508;1018.0837246478229;1062.7608788022633;1093.568040623965;14702.63243418923;14862.925685371503;14933.25333733309;15192.772108418383;15205.467808626645;314514;316765;318511;319138;319890;52103;52394.21408678873;52668.73665631724;52718.47281527212;52767.73616131895;1306.0596370613143;1315.421214665544;1336.0447280194708;1336.4386957477882;1338.8728070833256;27333.316667083483;28308.586668265132;28357.291067723472;28375;28522.714772852836;45867;45976.31035534403;46137.76931115323;46224.53775462337;46354.76822615865;285768;288260;289213;289340;290034;27878.721212788423;31310.530342045342;31662;31843.522347164348;32046.19884502906;39725.03437070366;40643;40781.59218407778;41397;41751.373729393476;33997;34158;34259.31481370309;34577;34679.82660086784;29200.707992919797;33261.35628638299;35325.29349412946;36322.455163172046;41331.58668413277;35179.472307915916;35641.64358356383;36080.09799754922;36188.09529761777;36293.27413451769;31394.686053139176;33277.575323096906;33446.83276583699;33981.83009084939;33988.32023359469;25429;25496;25570.488590228462;25589.48821023532;25815 05:48:12 INFO - 2015-12-18 05:48:12,061 DEBUG : BROWSER_OUTPUT: |2;jquery.html;361.31530749230194;364.18604419123255;367.4855202716198;367.961364056774;368.1807977250616;14084.577462676136;14304.570862874105;16011.679766404664;16242.83757162428;16923.746143807854;12294.56969006085;12641.747165056693;14554.781678274834;14556.563303100898;15018.399264029425;5440.075187218173;5600.859978500529;5602.691851948141;5606.467385598362;5625.6905870177125;4055.634992850643;4121.649659778923;5444.891102177954;5550.5282051025715;5590.580706447013;5932.555058370618;6066.241719785027;6074.0281554951;6091;6104.328523862371;5912.408759124118;6050.6974651267265;6055.27336719592;6076.301225359096;6078.39216078393;5348.331458567679;5358.678479291255;5365;5369.275147855029;5384.1923711443205;21005.47486312839;21122.36632901;21411.892940535352;21476.570468590697;21738.89130554337;2281.1103669568884;2311.780380863815;2322.1640209524558;2331.8484298520543;2340.8127349812144;12860.035497337738;13357.265350405723;13403.597892063253;13503.797443038362;13530.661733456644;1716.0733204069788;1733.6619359224956;1795.7126859702385;1846.9907650461796;1864.0772817455447;57906.71046644782;60923.69538152281;61033.77932441371;61236.387636123516;61520.38479615191;9113.407628504126;9372.224606081163;9927.602895884229;9973.551190196458;10107.69676909694;12897;12976.740465190644;13002.934985325013;13006.739865202642;13024.804627930589;7162.964185179145;7177.605231712201;7217.3504384604585;7248.0939882514685;7392;6927.307269273133;6957.339052789976;6983.650817459208;7006.369426751617;7011.92988070123;5695.03184458643;5757.568182386254;5766.279215098113;5768.278965129359;5770.394108618619;1642.3020216408217;1647;1678.9496315110323;1681.5796050987253;1777.795553511337;898.573177740568;906.4063038709655;947.7918043885128;991.973307626603;1020.4591566469783;17271.13644317779;17565.121743912754;17664.381746638806;17756.822431775774;17867.73198402025;884.5046773806689;886.663068034143;928.9767755806159;950.695777351241;957.0333962697587;8875.600597973107;9108.908910910939;9116.544172791333;9269.814603707888;9809;391373;573384;584332;585502;596418;76952;77628.61185694035;79806.6009669948;79970.60014700006;81033.59483202665;1125.358545629;1146.977060458803;1148.684111869226;1165.8484397028333;1179.0567545963195;15968.600784980004;15973.440929567409;16019.919900400424;16025.599360016093;16261.59346016359;36463.453048203766;37286;37442.812785935894;37545.2490950174;38085.4287185698;49835;50096.49903501064;51042.48957510526;54041.729791350794;54296.45703543072;8635.525046122273;9134.58894349769;9199.724008279762;9208.769780755534;9233.630654773733;17290.481285561455;17593.20830562602;17765.555861103578;17807.46577602674;17989.370372036916;14503;14564.19896905638;14677.48628797987;14886.181260030802;14890.702185956437;13785.793213101611;13821.86178138246;14068.296585170905;14655.48705795292;14680.3393847275;14170.149791012573;14374.281285935453;14562.563123106323;14564;14940.850591493945;9260.49067301305;9475.526223688927;9669.371490853073;9679.467629280458;9695;9816.754581135528;12383.380830958597;14469.421223150955;14645.92677036608;15263.847361526243 05:48:12 INFO - 2015-12-18 05:48:12,061 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1318.3013003108344;1488.717143742689;1547.1645311531774;1639.762234476001;1731.9826801731988;6570.474362051036;7341.632918354081;7432.368248698854;7434.628268586576;7480.0649918760155;7359.816004599874;7552.622368881562;7586;7600.847983040336;7603.201663825302;2245.360072379372;2281.8744969026698;2283.7830406111393;2284.4745708487058;2285.5885940530698;2218.256883943881;2228.476308067603;2302.3208153594646;2304.873231972241;2305.561943230781;2264.705588273522;2265.546890621874;2267.1724820440504;2268.625676763332;2298.333483289844;2253.019936327703;2257.142285931344;2295.2425699519117;2298.678185054077;2299.310206937912;2167.4581354661336;2187.6937228787983;2192.440927563461;2193.57225341057;2194.188150384352;7508;7553.244675532458;7580.355669768076;7589.127250366223;7589.848203035908;4516.390287311204;4557.270836666117;4589.632829373642;4593.173228818811;4595.149897269016;2945.0870230228697;3031.77261705373;3093.8607762650736;3130.8434578270994;3145.874165033396;937.2080591899902;966.3911735606594;979.8824141103042;997.8503224516309;1002.1631937332336;10248.23138264633;10250;10262.897371026269;10265.897341026568;10267.845982310271;4093.362654937989;4094.1811637672586;4150.045489537423;4160.563140870165;4176.018635620627;8935.776605584782;8940.374173807772;8947.686830961014;8952.507612081268;8955.865662014945;4530.433695788027;4563.041761230113;4585.12882552314;4594.862154135381;4604.1252162089095;3634.618365071682;3690.7970061646865;4133.132042271157;4182.205380977665;4182.351735481006;3680.1351682354625;3682.2451397463456;3695.8336874840697;3716;3729.0490924814;1162.84301619282;1163.0637336943842;1178.5816035307341;1183.1776915044134;1183.1954271095565;1316.3615646411483;1316.8419789625307;1340.336533415946;1344.7176093020576;1346.1653774659774;6977.546459480118;7002.334778196165;7098.432125430057;7121.145462544528;7125.1093613298335;440.7245471580262;451.3680846814486;452.7555120235102;454.3049134823725;456.40211323166756;5524.751386187631;5559.527440167631;5559.777608895599;5561.972190139023;5564;8806.471611703319;8942.642294308285;9112.681056163003;9238.445693258425;9242.907570924339;8981.865272020925;9110.180083792491;9118.224950879117;9122.817543649222;9130.086991300817;6947.027416161742;7087.929120708727;7094.574325540484;7177.4616903731385;7219.350258476762;7089.82275443118;7135.322144396274;7140.07179066718;7157;7163.820904477429;7974;8139.430239883151;8225.95887020561;8315.875261870955;8370.288525475402;8352.749417517483;8448.577571121297;8555;8615.267702245128;8625.137486251324;5764.308283006066;6148.354422785722;6151.108089326933;6606.075149479173;6638.83402914931;6617.073609694743;6946.791596251918;6994.160700715946;7041;7054.047703559953;6859.348361905611;6931.44548436134;6953;7066.964665176642;7073.752418665322;6649.2685804562425;6729.629870357177;6793;6804.829879253059;6994;6618.933810661832;6698.933010669831;6777.1189745332795;6817.5568588041615;6826.624535650587;6313.936860631335;6329.7468101275435;6335.746570137143;6336.429721325103;6348.968255158695;6185.41238582352;6213.068039794067;6233.594816336924;6254.6247225166635;6261.968690156702 05:48:12 INFO - 2015-12-18 05:48:12,065 DEBUG : BROWSER_OUTPUT: |4;prototype.html;326.1080943619201;333.1238841845945;334.2496096263887;341.53380635432643;345.4748781851584;5819.563532735051;5893.233879595652;5956;5991.161237426763;6011.669358185298;5711.8000869969555;5870.501007414364;5870.677112758807;5886.470217680408;5898.88202235955;4566.04113136241;4570.085982803453;4572.382728331666;4578.771061446931;4582.08358328334;4349.847755328564;4394.516603173648;4476.574725401098;4530.569595888385;4576.1762882681105;4524.095180963804;4528.592426681598;4537.954620453803;4547.340635607835;4554;4537.183307004738;4544.318352247156;4553.635709143294;4557.384753058328;4560.452745670507;3998.300297447935;4022.557518672944;4026.416169655413;4029.9194016119804;4031.657309128728;5468.562514998791;5603.131514615241;5603.7478313475995;5622.465865742748;5661.60368774186;5262.131748261532;5271.789128434858;5273.815416460444;5277.762500687479;5285.286486324336;5981.641101533922;6308.558400911938;6317.368263173692;6324;6324.083007963865;4249.192653395845;4282.057947251599;4378.584034516716;4382.9342559861625;4393.868183954486;6407;6499.837504062389;6516.185476815351;6526.934730652681;6537.5423720339595;4456.264716321771;4488.528704486047;4491.640668746492;4492.932606010912;4499.6400287976885;4455.576720211575;4514.232580461329;4537.2059889519205;4550.704204226715;4557.612602928788;5235.554977826851;5247.842564723064;5253.684778913277;5254.6847189168775;5258.421573626898;6327.177466929362;6333.20834895638;6334.081558174039;6337.049442583557;6342.587731797418;1578.921053947298;1600.303867817503;1600.487843889944;1600.8479194476506;1601.3514526616507;1059.6291298045621;1062.9202809789297;1064.297563608015;1070.6306324318098;1097.8408130820985;6108.877822443526;6113.510919126459;6114.266288045463;6120.908186377208;6121.173641558422;6538.967305163444;6545.574537655132;6546.247181574085;6547.985062315349;6556.934430655728;5946.316173640001;6047.697615119315;6103.786367477117;6108.602940808833;6113.84715382119;3508.1755787389943;3533.2403533239963;3533.858645654145;3536.681698647134;3540.6636369544854;6547.279799222082;6627.635480048643;6634.436072933758;6635.170603674541;6637.1703537057865;6645.66771661415;6676.766313179112;6680.498962577729;6692.59844409337;6693.765718199839;6501.902471462932;6606.8017959461295;6625.337466253336;6626.304238055031;6678.799636010927;6390.456811170917;6527.934720652733;6549.377809108127;6623.602583844792;6623.966880165568;6387.5209359297305;6514.3811337922825;6529.151210342625;6541.934580654132;6543.509236807164;6702.458434560176;6709.798706038827;6713.63075030878;6717.05961165447;6717.126773519607;4464.285714285698;4511.3458548510935;4535.886602834956;4536.115457485758;4600.2869555218995;6500.674966251763;6500.772472963424;6518.934810652023;6519.706613202512;6522.34776522344;6343.397377249154;6346.3019067903415;6347.71435285404;6348.111264423077;6348.269948955929;6187.474064704551;6324.430801227912;6328.6519241442165;6330.810075697552;6340.873182536416;6226;6232.220972378453;6233.283172435046;6233.625982441068;6240.656763877849;6296.150019747275;6351.047342898602;6365.267994180544;6368.96815515938;6386.521010924106;6020.668863212565;6240.438360547572;6240.594361366497;6241.750329986931;6249.34381889895 05:48:12 INFO - 2015-12-18 05:48:12,066 DEBUG : BROWSER_OUTPUT: |5;yui.html;807.565190949288;818.3412353055792;835.5154010673809;846.2087947768837;851.4337965253105;13093.803592946113;13402.061855670107;13422.39599218035;13433.731325373512;13532;14906.40374385026;15098.547043588682;15119.24403779812;15120.546383608482;15137.924310378432;685.1914740606082;838.7399906029135;843.569779412498;850.0479463001448;850.2220468271524;670.8389986403253;814.5723495165026;836.9414141010132;837.0875745437472;837.417994493827;864.0495454999482;877.4910551879894;877.7717793373737;878.89892662344;879.5030807593721;706.131458306281;706.6148948822878;840.4915026408995;871.4422769427572;872.3195907192369;638.581728967527;641.240130445419;642.3640595810115;644.4039263681076;645.6545748024785;13333.266670333127;13813;13814.516491922835;13829.239391833446;13830.654233644138;11737.823932641017;11814.17300788945;11902.821457678141;12621.242725436503;12621.74756504874;9947.403155810674;10297.639582614573;10442.2168337375;10448.52981615829;10545.156387488982;3527.0829584308008;3576.409892367756;3583.982080089609;3613.8735144269826;3700.111973126457;47862.521374786156;48012.75993620045;48062.03875922497;48096.27855582169;48242.27636585419;8788.121187881288;9176.495292758962;9250.814983700422;9358.812823743487;9371.344005919656;16792;16825;16861.241244144043;16865.494035178963;16866.32534698623;7127.60798156096;7559;7814.53112813233;7921.801954951173;7959.204079591995;4410.338449232577;4487.439070116236;4493.213687604722;4515.419603138875;4517.683762136685;2994.191568276553;3018.215264031324;3030.4090702312837;3031.030070377502;3039.012320995685;556.9108942569251;558.5726918907039;564.3791828988162;564.9265595472644;566.7223060700227;655.3479288108363;655.4985436141355;663.5998044126985;685.9314068593201;732.8973943647893;7853.450258481851;8195.139510351328;8261.967254093239;8369.707060252862;8524.786880327918;467.5581575411272;579.8956187886221;625.1841347042154;644.8558542845059;665.5873358517654;3988.4615576897327;4053.128577355824;4159.334506479009;4251.29853574163;4298.63461605767;53685.73157134267;54258.72870635622;54416;54593.454065458835;64044;49596.75201623969;52289.215661765105;52437.7378113107;52720.73639631777;53563;48292.5170748288;48766.26850597244;50237;50677.746611267445;50892.49107508877;8571.914280857112;8768.95615521944;8775.780605484913;8814.955925220333;8861.26265788185;34946.82526587351;35241.82379088088;35501.822490888415;36026.45960310545;36127;40797.59202407938;43561.346579800695;44484.33273500965;44511.10977780491;45216.54783452123;11406.657800266004;11748.882511175121;11801.232919860182;12183.390830458618;12226.266424014588;12488.313142777235;12660.493580256687;12852.678683032998;13184.27486488214;13407.463701451832;11580.36308003068;11633.47649355764;12131.09016823724;12298.938505307417;12350.44423000949;11578.884211157781;11979.401029948642;12001.279923204636;12281.140320177501;12351.69120771988;10976.114800640435;11008.559657613605;11048.116150707763;11219.55121795119;11847.881521184678;8639.913600864162;8658.177473140042;8781.385303028726;8854.513001784964;9203.171714545722;8828.470291782514;9340.859887101564;9933.857606375359;9985.60057597688;10004.649837255662 05:48:12 INFO - 2015-12-18 05:48:12,066 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:48:12 INFO - 2015-12-18 05:48:12,066 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:48:12 INFO - 2015-12-18 05:48:12,066 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,977 05:48:12 INFO - 2015-12-18 05:48:12,066 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:48:12 INFO - 2015-12-18 05:48:12,067 DEBUG : BROWSER_OUTPUT: __startTimestamp1450446492052__endTimestamp 05:48:12 INFO - 2015-12-18 05:48:12,067 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-12-18 05:48:12,067 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:48:12 INFO - 2015-12-18 05:48:12,067 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:48:12 INFO - 2015-12-18 05:48:12,067 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-12-18 05:48:12,067 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5421.29 Median:5619.89 stddev:600.85 (10.7%) stddev-sans-first:576.34 05:48:12 INFO - 2015-12-18 05:48:12,067 DEBUG : BROWSER_OUTPUT: Values: 3177.7 3188.8 3226.3 3229.0 3256.3 5213.7 5300.1 5335.9 5347.8 5385.1 5428.2 5446.3 5457.9 5475.9 5574.9 4929.0 4965.2 4973.6 5007.9 5081.4 4998.8 5027.3 5058.3 5077.4 5090.8 4796.7 4825.0 4906.7 4927.6 4932.8 4958.6 4996.1 5000.7 5016.2 5052.5 4826.8 4970.2 4970.4 4981.5 5051.3 5126.0 5152.8 5223.3 5224.3 5262.3 5851.4 5858.0 5859.8 5868.1 5877.4 5640.1 5864.7 5874.3 5878.1 5888.9 5383.9 5465.9 5498.9 5519.0 5555.3 5962.6 5966.0 5975.2 5979.7 5981.9 5548.4 5655.2 5799.3 5807.3 5808.0 5717.6 5770.0 5835.2 5836.7 5892.5 5855.2 5882.1 5904.6 5904.6 5904.8 5619.9 5729.1 5827.0 5834.6 5842.4 4438.8 4472.2 4482.8 4517.5 4519.9 4012.8 4049.5 4050.6 4091.3 4093.2 5364.5 5576.7 5582.0 5582.8 5582.9 5680.0 5775.7 5811.1 5940.1 5944.2 5517.0 5598.7 5602.1 5608.9 5609.9 5102.6 5120.0 5146.8 5147.2 5184.9 5732.0 5851.2 5852.8 6002.4 6007.6 5980.0 5986.8 5993.9 5996.0 5999.6 5922.2 6004.0 6034.9 6042.1 6072.8 5551.2 5644.7 5653.0 5700.2 5899.1 5881.8 5889.5 5894.3 5903.4 5908.4 5729.7 5743.5 5744.4 5751.6 5752.0 5523.1 5616.9 5623.0 5632.4 5697.3 5637.8 5648.9 5655.4 5656.8 5660.8 5714.1 5744.8 5750.8 5761.3 5771.2 5466.0 5527.7 5605.7 5630.9 5677.3 05:48:12 INFO - 2015-12-18 05:48:12,067 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-12-18 05:48:12,067 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:47522.66 Median:175177.26 stddev:80265.84 (45.8%) stddev-sans-first:80413.52 05:48:12 INFO - 2015-12-18 05:48:12,067 DEBUG : BROWSER_OUTPUT: Values: 550.2 597.9 606.7 614.3 620.4 29381.0 30384.4 39102.6 39372.6 39408.8 42628.8 43205.8 43314.8 43355.6 43389.6 7486.8 7619.1 8701.6 8827.7 8832.7 10341.2 10603.0 10666.4 10699.5 10700.8 10336.9 10435.1 10438.7 10443.0 10457.1 7711.4 7928.4 10308.4 10330.4 10653.9 10099.8 10221.5 10253.7 10255.3 10256.4 40903.6 41090.0 41327.0 41364.6 41535.0 3055.5 3059.2 3099.1 3173.4 3584.9 29561.9 30059.0 30240.0 30413.8 31182.4 3943.0 3963.3 3991.9 4032.7 4068.2 310064.0 313983.0 314057.0 314486.0 314584.0 59525.0 60344.7 60529.7 60634.0 60707.7 57611.4 58537.7 65517.3 65818.3 67255.7 13474.2 13538.3 13613.0 13625.9 13629.5 8805.4 8901.3 8908.2 8913.2 9007.2 20141.7 20193.7 20354.6 20404.3 20434.2 8026.4 8257.0 8281.8 8290.0 8303.0 954.3 1001.3 1028.9 1043.4 1094.4 28158.4 28639.7 28714.4 29052.0 29057.3 1003.0 1012.8 1018.1 1062.8 1093.6 14702.6 14862.9 14933.3 15192.8 15205.5 314514.0 316765.0 318511.0 319138.0 319890.0 52103.0 52394.2 52668.7 52718.5 52767.7 1306.1 1315.4 1336.0 1336.4 1338.9 27333.3 28308.6 28357.3 28375.0 28522.7 45867.0 45976.3 46137.8 46224.5 46354.8 285768.0 288260.0 289213.0 289340.0 290034.0 27878.7 31310.5 31662.0 31843.5 32046.2 39725.0 40643.0 40781.6 41397.0 41751.4 33997.0 34158.0 34259.3 34577.0 34679.8 29200.7 33261.4 35325.3 36322.5 41331.6 35179.5 35641.6 36080.1 36188.1 36293.3 31394.7 33277.6 33446.8 33981.8 33988.3 25429.0 25496.0 25570.5 25589.5 25815.0 05:48:12 INFO - 2015-12-18 05:48:12,068 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-12-18 05:48:12,068 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29301.16 Median:214729.21 stddev:90169.48 (42.0%) stddev-sans-first:90396.24 05:48:12 INFO - 2015-12-18 05:48:12,069 DEBUG : BROWSER_OUTPUT: Values: 361.3 364.2 367.5 368.0 368.2 14084.6 14304.6 16011.7 16242.8 16923.7 12294.6 12641.7 14554.8 14556.6 15018.4 5440.1 5600.9 5602.7 5606.5 5625.7 4055.6 4121.6 5444.9 5550.5 5590.6 5932.6 6066.2 6074.0 6091.0 6104.3 5912.4 6050.7 6055.3 6076.3 6078.4 5348.3 5358.7 5365.0 5369.3 5384.2 21005.5 21122.4 21411.9 21476.6 21738.9 2281.1 2311.8 2322.2 2331.8 2340.8 12860.0 13357.3 13403.6 13503.8 13530.7 1716.1 1733.7 1795.7 1847.0 1864.1 57906.7 60923.7 61033.8 61236.4 61520.4 9113.4 9372.2 9927.6 9973.6 10107.7 12897.0 12976.7 13002.9 13006.7 13024.8 7163.0 7177.6 7217.4 7248.1 7392.0 6927.3 6957.3 6983.7 7006.4 7011.9 5695.0 5757.6 5766.3 5768.3 5770.4 1642.3 1647.0 1678.9 1681.6 1777.8 898.6 906.4 947.8 992.0 1020.5 17271.1 17565.1 17664.4 17756.8 17867.7 884.5 886.7 929.0 950.7 957.0 8875.6 9108.9 9116.5 9269.8 9809.0 391373.0 573384.0 584332.0 585502.0 596418.0 76952.0 77628.6 79806.6 79970.6 81033.6 1125.4 1147.0 1148.7 1165.8 1179.1 15968.6 15973.4 16019.9 16025.6 16261.6 36463.5 37286.0 37442.8 37545.2 38085.4 49835.0 50096.5 51042.5 54041.7 54296.5 8635.5 9134.6 9199.7 9208.8 9233.6 17290.5 17593.2 17765.6 17807.5 17989.4 14503.0 14564.2 14677.5 14886.2 14890.7 13785.8 13821.9 14068.3 14655.5 14680.3 14170.1 14374.3 14562.6 14564.0 14940.9 9260.5 9475.5 9669.4 9679.5 9695.0 9816.8 12383.4 14469.4 14645.9 15263.8 05:48:12 INFO - 2015-12-18 05:48:12,069 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-12-18 05:48:12,069 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5287.98 Median:6223.33 stddev:2742.34 (44.1%) stddev-sans-first:2733.80 05:48:12 INFO - 2015-12-18 05:48:12,069 DEBUG : BROWSER_OUTPUT: Values: 1318.3 1488.7 1547.2 1639.8 1732.0 6570.5 7341.6 7432.4 7434.6 7480.1 7359.8 7552.6 7586.0 7600.8 7603.2 2245.4 2281.9 2283.8 2284.5 2285.6 2218.3 2228.5 2302.3 2304.9 2305.6 2264.7 2265.5 2267.2 2268.6 2298.3 2253.0 2257.1 2295.2 2298.7 2299.3 2167.5 2187.7 2192.4 2193.6 2194.2 7508.0 7553.2 7580.4 7589.1 7589.8 4516.4 4557.3 4589.6 4593.2 4595.1 2945.1 3031.8 3093.9 3130.8 3145.9 937.2 966.4 979.9 997.9 1002.2 10248.2 10250.0 10262.9 10265.9 10267.8 4093.4 4094.2 4150.0 4160.6 4176.0 8935.8 8940.4 8947.7 8952.5 8955.9 4530.4 4563.0 4585.1 4594.9 4604.1 3634.6 3690.8 4133.1 4182.2 4182.4 3680.1 3682.2 3695.8 3716.0 3729.0 1162.8 1163.1 1178.6 1183.2 1183.2 1316.4 1316.8 1340.3 1344.7 1346.2 6977.5 7002.3 7098.4 7121.1 7125.1 440.7 451.4 452.8 454.3 456.4 5524.8 5559.5 5559.8 5562.0 5564.0 8806.5 8942.6 9112.7 9238.4 9242.9 8981.9 9110.2 9118.2 9122.8 9130.1 6947.0 7087.9 7094.6 7177.5 7219.4 7089.8 7135.3 7140.1 7157.0 7163.8 7974.0 8139.4 8226.0 8315.9 8370.3 8352.7 8448.6 8555.0 8615.3 8625.1 5764.3 6148.4 6151.1 6606.1 6638.8 6617.1 6946.8 6994.2 7041.0 7054.0 6859.3 6931.4 6953.0 7067.0 7073.8 6649.3 6729.6 6793.0 6804.8 6994.0 6618.9 6698.9 6777.1 6817.6 6826.6 6313.9 6329.7 6335.7 6336.4 6349.0 6185.4 6213.1 6233.6 6254.6 6262.0 05:48:12 INFO - 2015-12-18 05:48:12,069 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-12-18 05:48:12,070 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5274.33 Median:6001.42 stddev:1584.74 (26.4%) stddev-sans-first:1545.05 05:48:12 INFO - 2015-12-18 05:48:12,070 DEBUG : BROWSER_OUTPUT: Values: 326.1 333.1 334.2 341.5 345.5 5819.6 5893.2 5956.0 5991.2 6011.7 5711.8 5870.5 5870.7 5886.5 5898.9 4566.0 4570.1 4572.4 4578.8 4582.1 4349.8 4394.5 4476.6 4530.6 4576.2 4524.1 4528.6 4538.0 4547.3 4554.0 4537.2 4544.3 4553.6 4557.4 4560.5 3998.3 4022.6 4026.4 4029.9 4031.7 5468.6 5603.1 5603.7 5622.5 5661.6 5262.1 5271.8 5273.8 5277.8 5285.3 5981.6 6308.6 6317.4 6324.0 6324.1 4249.2 4282.1 4378.6 4382.9 4393.9 6407.0 6499.8 6516.2 6526.9 6537.5 4456.3 4488.5 4491.6 4492.9 4499.6 4455.6 4514.2 4537.2 4550.7 4557.6 5235.6 5247.8 5253.7 5254.7 5258.4 6327.2 6333.2 6334.1 6337.0 6342.6 1578.9 1600.3 1600.5 1600.8 1601.4 1059.6 1062.9 1064.3 1070.6 1097.8 6108.9 6113.5 6114.3 6120.9 6121.2 6539.0 6545.6 6546.2 6548.0 6556.9 5946.3 6047.7 6103.8 6108.6 6113.8 3508.2 3533.2 3533.9 3536.7 3540.7 6547.3 6627.6 6634.4 6635.2 6637.2 6645.7 6676.8 6680.5 6692.6 6693.8 6501.9 6606.8 6625.3 6626.3 6678.8 6390.5 6527.9 6549.4 6623.6 6624.0 6387.5 6514.4 6529.2 6541.9 6543.5 6702.5 6709.8 6713.6 6717.1 6717.1 4464.3 4511.3 4535.9 4536.1 4600.3 6500.7 6500.8 6518.9 6519.7 6522.3 6343.4 6346.3 6347.7 6348.1 6348.3 6187.5 6324.4 6328.7 6330.8 6340.9 6226.0 6232.2 6233.3 6233.6 6240.7 6296.2 6351.0 6365.3 6369.0 6386.5 6020.7 6240.4 6240.6 6241.8 6249.3 05:48:12 INFO - 2015-12-18 05:48:12,070 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-12-18 05:48:12,070 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13920.72 Median:48267.40 stddev:16084.31 (33.3%) stddev-sans-first:16099.29 05:48:12 INFO - 2015-12-18 05:48:12,071 DEBUG : BROWSER_OUTPUT: Values: 807.6 818.3 835.5 846.2 851.4 13093.8 13402.1 13422.4 13433.7 13532.0 14906.4 15098.5 15119.2 15120.5 15137.9 685.2 838.7 843.6 850.0 850.2 670.8 814.6 836.9 837.1 837.4 864.0 877.5 877.8 878.9 879.5 706.1 706.6 840.5 871.4 872.3 638.6 641.2 642.4 644.4 645.7 13333.3 13813.0 13814.5 13829.2 13830.7 11737.8 11814.2 11902.8 12621.2 12621.7 9947.4 10297.6 10442.2 10448.5 10545.2 3527.1 3576.4 3584.0 3613.9 3700.1 47862.5 48012.8 48062.0 48096.3 48242.3 8788.1 9176.5 9250.8 9358.8 9371.3 16792.0 16825.0 16861.2 16865.5 16866.3 7127.6 7559.0 7814.5 7921.8 7959.2 4410.3 4487.4 4493.2 4515.4 4517.7 2994.2 3018.2 3030.4 3031.0 3039.0 556.9 558.6 564.4 564.9 566.7 655.3 655.5 663.6 685.9 732.9 7853.5 8195.1 8262.0 8369.7 8524.8 467.6 579.9 625.2 644.9 665.6 3988.5 4053.1 4159.3 4251.3 4298.6 53685.7 54258.7 54416.0 54593.5 64044.0 49596.8 52289.2 52437.7 52720.7 53563.0 48292.5 48766.3 50237.0 50677.7 50892.5 8571.9 8769.0 8775.8 8815.0 8861.3 34946.8 35241.8 35501.8 36026.5 36127.0 40797.6 43561.3 44484.3 44511.1 45216.5 11406.7 11748.9 11801.2 12183.4 12226.3 12488.3 12660.5 12852.7 13184.3 13407.5 11580.4 11633.5 12131.1 12298.9 12350.4 11578.9 11979.4 12001.3 12281.1 12351.7 10976.1 11008.6 11048.1 11219.6 11847.9 8639.9 8658.2 8781.4 8854.5 9203.2 8828.5 9340.9 9933.9 9985.6 10004.6 05:48:12 INFO - 2015-12-18 05:48:12,071 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:48:12 INFO - 2015-12-18 05:48:12,071 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-12-18 05:48:12,725 INFO : Browser exited with error code: 0 05:48:12 INFO - 2015-12-18 05:48:12,744 INFO : Completed test dromaeo_css (00:18:32) 05:48:12 INFO - 2015-12-18 05:48:12,744 INFO : Starting test kraken 05:48:12 INFO - 2015-12-18 05:48:12,744 DEBUG : operating with platform_type : linux_ 05:48:12 INFO - 2015-12-18 05:48:12,744 INFO : Initialising browser for kraken test... 05:48:12 INFO - 2015-12-18 05:48:12,750 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfxZxxC/profile http://localhost:60407/getInfo.html 05:48:16 INFO - 2015-12-18 05:48:16,594 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:48:16 INFO - 2015-12-18 05:48:16,594 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:48:16 INFO - 2015-12-18 05:48:16,595 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:48:16 INFO - 2015-12-18 05:48:16,595 DEBUG : BROWSER_OUTPUT: __metrics 05:48:16 INFO - 2015-12-18 05:48:16,683 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:48:16 INFO - 2015-12-18 05:48:16,739 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:48:17 INFO - 2015-12-18 05:48:17,869 INFO : Browser initialized. 05:48:17 INFO - 2015-12-18 05:48:17,870 INFO : Running cycle 1/1 for kraken test... 05:48:17 INFO - 2015-12-18 05:48:17,870 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfxZxxC/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:48:29 INFO - 2015-12-18 05:48:29,700 DEBUG : BROWSER_OUTPUT: RSS: Main: 149577728 05:48:29 INFO - 2015-12-18 05:48:29,700 DEBUG : BROWSER_OUTPUT: 05:48:32 INFO - 2015-12-18 05:48:32,138 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60407/tests/kraken/driver.html?testName=audio-beat-detection) 05:48:32 INFO - 2015-12-18 05:48:32,739 DEBUG : BROWSER_OUTPUT: RSS: Main: 185831424 05:48:32 INFO - 2015-12-18 05:48:32,739 DEBUG : BROWSER_OUTPUT: 05:48:35 INFO - 2015-12-18 05:48:35,320 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60407/tests/kraken/driver.html?testName=audio-dft) 05:48:35 INFO - 2015-12-18 05:48:35,886 DEBUG : BROWSER_OUTPUT: RSS: Main: 175722496 05:48:35 INFO - 2015-12-18 05:48:35,886 DEBUG : BROWSER_OUTPUT: 05:48:38 INFO - 2015-12-18 05:48:38,737 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60407/tests/kraken/driver.html?testName=audio-fft) 05:48:39 INFO - 2015-12-18 05:48:39,299 DEBUG : BROWSER_OUTPUT: RSS: Main: 160276480 05:48:39 INFO - 2015-12-18 05:48:39,299 DEBUG : BROWSER_OUTPUT: 05:48:41 INFO - 2015-12-18 05:48:41,296 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60407/tests/kraken/driver.html?testName=audio-oscillator) 05:48:41 INFO - 2015-12-18 05:48:41,865 DEBUG : BROWSER_OUTPUT: RSS: Main: 163373056 05:48:41 INFO - 2015-12-18 05:48:41,865 DEBUG : BROWSER_OUTPUT: 05:48:44 INFO - 2015-12-18 05:48:44,064 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60407/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:48:44 INFO - 2015-12-18 05:48:44,630 DEBUG : BROWSER_OUTPUT: RSS: Main: 165212160 05:48:44 INFO - 2015-12-18 05:48:44,630 DEBUG : BROWSER_OUTPUT: 05:48:47 INFO - 2015-12-18 05:48:47,495 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60407/tests/kraken/driver.html?testName=imaging-darkroom) 05:48:48 INFO - 2015-12-18 05:48:48,064 DEBUG : BROWSER_OUTPUT: RSS: Main: 164487168 05:48:48 INFO - 2015-12-18 05:48:48,065 DEBUG : BROWSER_OUTPUT: 05:48:51 INFO - 2015-12-18 05:48:51,398 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60407/tests/kraken/driver.html?testName=imaging-desaturate) 05:48:51 INFO - 2015-12-18 05:48:51,971 DEBUG : BROWSER_OUTPUT: RSS: Main: 165998592 05:48:51 INFO - 2015-12-18 05:48:51,972 DEBUG : BROWSER_OUTPUT: 05:48:54 INFO - 2015-12-18 05:48:54,701 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60407/tests/kraken/driver.html?testName=json-parse-financial) 05:48:55 INFO - 2015-12-18 05:48:55,282 DEBUG : BROWSER_OUTPUT: RSS: Main: 166420480 05:48:55 INFO - 2015-12-18 05:48:55,282 DEBUG : BROWSER_OUTPUT: 05:48:56 INFO - 2015-12-18 05:48:56,859 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60407/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:48:57 INFO - 2015-12-18 05:48:57,425 DEBUG : BROWSER_OUTPUT: RSS: Main: 174706688 05:48:57 INFO - 2015-12-18 05:48:57,425 DEBUG : BROWSER_OUTPUT: 05:48:59 INFO - 2015-12-18 05:48:59,241 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60407/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:48:59 INFO - 2015-12-18 05:48:59,834 DEBUG : BROWSER_OUTPUT: RSS: Main: 172482560 05:48:59 INFO - 2015-12-18 05:48:59,835 DEBUG : BROWSER_OUTPUT: 05:49:01 INFO - 2015-12-18 05:49:01,868 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60407/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:49:02 INFO - 2015-12-18 05:49:02,441 DEBUG : BROWSER_OUTPUT: RSS: Main: 185921536 05:49:02 INFO - 2015-12-18 05:49:02,442 DEBUG : BROWSER_OUTPUT: 05:49:04 INFO - 2015-12-18 05:49:04,842 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60407/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:49:05 INFO - 2015-12-18 05:49:05,415 DEBUG : BROWSER_OUTPUT: RSS: Main: 190570496 05:49:05 INFO - 2015-12-18 05:49:05,415 DEBUG : BROWSER_OUTPUT: 05:49:08 INFO - 2015-12-18 05:49:08,002 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60407/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:49:08 INFO - 2015-12-18 05:49:08,583 DEBUG : BROWSER_OUTPUT: RSS: Main: 189583360 05:49:08 INFO - 2015-12-18 05:49:08,583 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-12-18 05:49:10,265 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60407/tests/kraken/driver.html?testName=ai-astar) 05:49:10 INFO - 2015-12-18 05:49:10,515 DEBUG : BROWSER_OUTPUT: RSS: Main: 247152640 05:49:10 INFO - 2015-12-18 05:49:10,516 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-12-18 05:49:10,517 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:49:10 INFO - 2015-12-18 05:49:10,517 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:49:10 INFO - 2015-12-18 05:49:10,517 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:49:10 INFO - 2015-12-18 05:49:10,517 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:49:10 INFO - 2015-12-18 05:49:10,517 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;104;110;110;110;100;109;109;109 05:49:10 INFO - 2015-12-18 05:49:10,518 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;152;123;150;160;125;150;150;124;163;122 05:49:10 INFO - 2015-12-18 05:49:10,518 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;161;161;161;162;159;162;165;162;163 05:49:10 INFO - 2015-12-18 05:49:10,518 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;94;92;71;71;73;109;92;73 05:49:10 INFO - 2015-12-18 05:49:10,518 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;93;142;93;93;93;138;93;93;131 05:49:10 INFO - 2015-12-18 05:49:10,518 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;109;108;111;110;109;109;110;109 05:49:10 INFO - 2015-12-18 05:49:10,519 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;145;145;145;146;146;145;145;145 05:49:10 INFO - 2015-12-18 05:49:10,519 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;95;96;95;95;95;95;96 05:49:10 INFO - 2015-12-18 05:49:10,519 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;61;59;59;58;59;58;59;67;57;56 05:49:10 INFO - 2015-12-18 05:49:10,519 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;67;67;68;68;68;68;67;67;67 05:49:10 INFO - 2015-12-18 05:49:10,519 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;85;84;85;84;84;84;82;86;84 05:49:10 INFO - 2015-12-18 05:49:10,520 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;119;112;124;112;122;117;123;116;131;116 05:49:10 INFO - 2015-12-18 05:49:10,520 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;145;149;150;166;151;140;154;149;150;150 05:49:10 INFO - 2015-12-18 05:49:10,520 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;67;66;67;67;68;66;68;68;66 05:49:10 INFO - 2015-12-18 05:49:10,520 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:49:10 INFO - 2015-12-18 05:49:10,520 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:49:10 INFO - 2015-12-18 05:49:10,521 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,929 05:49:10 INFO - 2015-12-18 05:49:10,521 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:49:10 INFO - 2015-12-18 05:49:10,521 DEBUG : BROWSER_OUTPUT: __startTimestamp1450446550516__endTimestamp 05:49:10 INFO - 2015-12-18 05:49:10,521 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-12-18 05:49:10,525 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:49:10 INFO - 2015-12-18 05:49:10,525 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:49:10 INFO - 2015-12-18 05:49:10,525 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-12-18 05:49:10,525 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.90 Median:109.50 stddev:3.31 (3.0%) stddev-sans-first:3.52 05:49:10 INFO - 2015-12-18 05:49:10,526 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 104.0 110.0 110.0 110.0 100.0 109.0 109.0 109.0 05:49:10 INFO - 2015-12-18 05:49:10,526 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-12-18 05:49:10,526 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.90 Median:150.00 stddev:16.43 (11.0%) stddev-sans-first:17.02 05:49:10 INFO - 2015-12-18 05:49:10,526 DEBUG : BROWSER_OUTPUT: Values: 152.0 123.0 150.0 160.0 125.0 150.0 150.0 124.0 163.0 122.0 05:49:10 INFO - 2015-12-18 05:49:10,527 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-12-18 05:49:10,527 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.57 (1.0%) stddev-sans-first:1.64 05:49:10 INFO - 2015-12-18 05:49:10,527 DEBUG : BROWSER_OUTPUT: Values: 161.0 161.0 161.0 161.0 162.0 159.0 162.0 165.0 162.0 163.0 05:49:10 INFO - 2015-12-18 05:49:10,527 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-12-18 05:49:10,527 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.70 Median:73.00 stddev:13.82 (18.9%) stddev-sans-first:14.10 05:49:10 INFO - 2015-12-18 05:49:10,528 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 94.0 92.0 71.0 71.0 73.0 109.0 92.0 73.0 05:49:10 INFO - 2015-12-18 05:49:10,528 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-12-18 05:49:10,528 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.30 Median:93.00 stddev:21.35 (23.0%) stddev-sans-first:22.18 05:49:10 INFO - 2015-12-18 05:49:10,528 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 142.0 93.0 93.0 93.0 138.0 93.0 93.0 131.0 05:49:10 INFO - 2015-12-18 05:49:10,529 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-12-18 05:49:10,529 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.40 Median:109.50 stddev:0.84 (0.8%) stddev-sans-first:0.87 05:49:10 INFO - 2015-12-18 05:49:10,529 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 109.0 108.0 111.0 110.0 109.0 109.0 110.0 109.0 05:49:10 INFO - 2015-12-18 05:49:10,529 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-12-18 05:49:10,529 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 05:49:10 INFO - 2015-12-18 05:49:10,529 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 145.0 145.0 145.0 146.0 146.0 145.0 145.0 145.0 05:49:10 INFO - 2015-12-18 05:49:10,529 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-12-18 05:49:10,529 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 05:49:10 INFO - 2015-12-18 05:49:10,529 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 05:49:10 INFO - 2015-12-18 05:49:10,529 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-12-18 05:49:10,530 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:59.30 Median:59.00 stddev:3.02 (5.1%) stddev-sans-first:3.14 05:49:10 INFO - 2015-12-18 05:49:10,530 DEBUG : BROWSER_OUTPUT: Values: 61.0 59.0 59.0 58.0 59.0 58.0 59.0 67.0 57.0 56.0 05:49:10 INFO - 2015-12-18 05:49:10,530 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-12-18 05:49:10,530 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:67.50 Median:68.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 05:49:10 INFO - 2015-12-18 05:49:10,530 DEBUG : BROWSER_OUTPUT: Values: 68.0 67.0 67.0 68.0 68.0 68.0 68.0 67.0 67.0 67.0 05:49:10 INFO - 2015-12-18 05:49:10,530 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-12-18 05:49:10,530 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:84.00 stddev:1.25 (1.5%) stddev-sans-first:1.09 05:49:10 INFO - 2015-12-18 05:49:10,530 DEBUG : BROWSER_OUTPUT: Values: 82.0 85.0 84.0 85.0 84.0 84.0 84.0 82.0 86.0 84.0 05:49:10 INFO - 2015-12-18 05:49:10,530 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-12-18 05:49:10,530 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:119.20 Median:120.50 stddev:5.90 (4.9%) stddev-sans-first:6.26 05:49:10 INFO - 2015-12-18 05:49:10,531 DEBUG : BROWSER_OUTPUT: Values: 119.0 112.0 124.0 112.0 122.0 117.0 123.0 116.0 131.0 116.0 05:49:10 INFO - 2015-12-18 05:49:10,531 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-12-18 05:49:10,531 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.40 Median:150.00 stddev:6.65 (4.4%) stddev-sans-first:6.76 05:49:10 INFO - 2015-12-18 05:49:10,531 DEBUG : BROWSER_OUTPUT: Values: 145.0 149.0 150.0 166.0 151.0 140.0 154.0 149.0 150.0 150.0 05:49:10 INFO - 2015-12-18 05:49:10,531 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-12-18 05:49:10,531 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.50 stddev:0.88 (1.3%) stddev-sans-first:0.87 05:49:10 INFO - 2015-12-18 05:49:10,531 DEBUG : BROWSER_OUTPUT: Values: 68.0 67.0 66.0 67.0 67.0 68.0 66.0 68.0 68.0 66.0 05:49:10 INFO - 2015-12-18 05:49:10,531 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:49:10 INFO - 2015-12-18 05:49:10,531 DEBUG : BROWSER_OUTPUT: 05:49:11 INFO - 2015-12-18 05:49:11,381 INFO : Browser exited with error code: 0 05:49:11 INFO - 2015-12-18 05:49:11,435 INFO : Completed test kraken (00:00:58) 05:49:11 INFO - 2015-12-18 05:49:11,435 INFO : Starting test v8_7 05:49:11 INFO - 2015-12-18 05:49:11,435 DEBUG : operating with platform_type : linux_ 05:49:11 INFO - 2015-12-18 05:49:11,435 INFO : Initialising browser for v8_7 test... 05:49:11 INFO - 2015-12-18 05:49:11,441 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzzcWlb/profile http://localhost:60407/getInfo.html 05:49:15 INFO - 2015-12-18 05:49:15,174 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:49:15 INFO - 2015-12-18 05:49:15,174 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:49:15 INFO - 2015-12-18 05:49:15,174 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:49:15 INFO - 2015-12-18 05:49:15,174 DEBUG : BROWSER_OUTPUT: __metrics 05:49:15 INFO - 2015-12-18 05:49:15,271 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:49:15 INFO - 2015-12-18 05:49:15,327 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:49:16 INFO - 2015-12-18 05:49:16,412 INFO : Browser initialized. 05:49:16 INFO - 2015-12-18 05:49:16,412 INFO : Running cycle 1/1 for v8_7 test... 05:49:16 INFO - 2015-12-18 05:49:16,413 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzzcWlb/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:49:28 INFO - 2015-12-18 05:49:28,210 DEBUG : BROWSER_OUTPUT: RSS: Main: 149659648 05:49:28 INFO - 2015-12-18 05:49:28,210 DEBUG : BROWSER_OUTPUT: 05:49:49 INFO - 2015-12-18 05:49:49,601 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60407/tests/v8_7/run.html (next: http://localhost:60407/tests/v8_7/run.html) 05:49:49 INFO - 2015-12-18 05:49:49,852 DEBUG : BROWSER_OUTPUT: RSS: Main: 624029696 05:49:49 INFO - 2015-12-18 05:49:49,852 DEBUG : BROWSER_OUTPUT: 05:49:49 INFO - 2015-12-18 05:49:49,852 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:49:49 INFO - 2015-12-18 05:49:49,852 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:49:49 INFO - 2015-12-18 05:49:49,852 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:49:49 INFO - 2015-12-18 05:49:49,853 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:49:49 INFO - 2015-12-18 05:49:49,853 DEBUG : BROWSER_OUTPUT: |0;Richards;168.57720836142954 05:49:49 INFO - 2015-12-18 05:49:49,853 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;188.28845791752966 05:49:49 INFO - 2015-12-18 05:49:49,853 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.58361391694723 05:49:49 INFO - 2015-12-18 05:49:49,853 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5122.448979591837 05:49:49 INFO - 2015-12-18 05:49:49,853 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1992.03187250996 05:49:49 INFO - 2015-12-18 05:49:49,853 DEBUG : BROWSER_OUTPUT: |5;Earley;783.0853563038371 05:49:49 INFO - 2015-12-18 05:49:49,853 DEBUG : BROWSER_OUTPUT: |6;Boyer;12204.819277108434 05:49:49 INFO - 2015-12-18 05:49:49,853 DEBUG : BROWSER_OUTPUT: |7;RegExp;28333.333333333332 05:49:49 INFO - 2015-12-18 05:49:49,854 DEBUG : BROWSER_OUTPUT: |8;Splay;627.3525721455458 05:49:49 INFO - 2015-12-18 05:49:49,854 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 05:49:49 INFO - 2015-12-18 05:49:49,854 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:49:49 INFO - 2015-12-18 05:49:49,854 DEBUG : BROWSER_OUTPUT: __startTimestamp1450446589849__endTimestamp 05:49:49 INFO - 2015-12-18 05:49:49,854 DEBUG : BROWSER_OUTPUT: 05:49:49 INFO - 2015-12-18 05:49:49,854 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:49:49 INFO - 2015-12-18 05:49:49,854 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:49:49 INFO - 2015-12-18 05:49:49,854 DEBUG : BROWSER_OUTPUT: 05:49:49 INFO - 2015-12-18 05:49:49,854 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:168.58 Median:168.58 stddev:0.00 (0.0%) 05:49:49 INFO - 2015-12-18 05:49:49,854 DEBUG : BROWSER_OUTPUT: Values: 168.6 05:49:49 INFO - 2015-12-18 05:49:49,854 DEBUG : BROWSER_OUTPUT: 05:49:49 INFO - 2015-12-18 05:49:49,855 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:188.29 Median:188.29 stddev:0.00 (0.0%) 05:49:49 INFO - 2015-12-18 05:49:49,855 DEBUG : BROWSER_OUTPUT: Values: 188.3 05:49:49 INFO - 2015-12-18 05:49:49,855 DEBUG : BROWSER_OUTPUT: 05:49:49 INFO - 2015-12-18 05:49:49,855 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.58 Median:280.58 stddev:0.00 (0.0%) 05:49:49 INFO - 2015-12-18 05:49:49,855 DEBUG : BROWSER_OUTPUT: Values: 280.6 05:49:49 INFO - 2015-12-18 05:49:49,855 DEBUG : BROWSER_OUTPUT: 05:49:49 INFO - 2015-12-18 05:49:49,855 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5122.45 Median:5122.45 stddev:0.00 (0.0%) 05:49:49 INFO - 2015-12-18 05:49:49,855 DEBUG : BROWSER_OUTPUT: Values: 5122.4 05:49:49 INFO - 2015-12-18 05:49:49,856 DEBUG : BROWSER_OUTPUT: 05:49:49 INFO - 2015-12-18 05:49:49,856 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1992.03 Median:1992.03 stddev:0.00 (0.0%) 05:49:49 INFO - 2015-12-18 05:49:49,856 DEBUG : BROWSER_OUTPUT: Values: 1992.0 05:49:49 INFO - 2015-12-18 05:49:49,856 DEBUG : BROWSER_OUTPUT: 05:49:49 INFO - 2015-12-18 05:49:49,856 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:783.09 Median:783.09 stddev:0.00 (0.0%) 05:49:49 INFO - 2015-12-18 05:49:49,856 DEBUG : BROWSER_OUTPUT: Values: 783.1 05:49:49 INFO - 2015-12-18 05:49:49,857 DEBUG : BROWSER_OUTPUT: 05:49:49 INFO - 2015-12-18 05:49:49,857 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12204.82 Median:12204.82 stddev:0.00 (0.0%) 05:49:49 INFO - 2015-12-18 05:49:49,857 DEBUG : BROWSER_OUTPUT: Values: 12204.8 05:49:49 INFO - 2015-12-18 05:49:49,857 DEBUG : BROWSER_OUTPUT: 05:49:49 INFO - 2015-12-18 05:49:49,857 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:28333.33 Median:28333.33 stddev:0.00 (0.0%) 05:49:49 INFO - 2015-12-18 05:49:49,857 DEBUG : BROWSER_OUTPUT: Values: 28333.3 05:49:49 INFO - 2015-12-18 05:49:49,857 DEBUG : BROWSER_OUTPUT: 05:49:49 INFO - 2015-12-18 05:49:49,858 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:627.35 Median:627.35 stddev:0.00 (0.0%) 05:49:49 INFO - 2015-12-18 05:49:49,858 DEBUG : BROWSER_OUTPUT: Values: 627.4 05:49:49 INFO - 2015-12-18 05:49:49,858 DEBUG : BROWSER_OUTPUT: 05:49:49 INFO - 2015-12-18 05:49:49,858 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 05:49:49 INFO - 2015-12-18 05:49:49,858 DEBUG : BROWSER_OUTPUT: Values: 4835.7 05:49:49 INFO - 2015-12-18 05:49:49,858 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:49:49 INFO - 2015-12-18 05:49:49,858 DEBUG : BROWSER_OUTPUT: 05:49:50 INFO - 2015-12-18 05:49:50,693 INFO : Browser exited with error code: 0 05:49:50 INFO - 2015-12-18 05:49:50,710 INFO : Completed test v8_7 (00:00:39) 05:49:50 INFO - 2015-12-18 05:49:50,872 INFO : Completed test suite (00:20:11) 05:49:50 INFO - 2015-12-18 05:49:50,872 DEBUG : Working with test: dromaeo_css 05:49:50 INFO - 2015-12-18 05:49:50,872 DEBUG : Generating results file: dromaeo_css 05:49:50 INFO - 2015-12-18 05:49:50,873 DEBUG : Working with test: kraken 05:49:50 INFO - 2015-12-18 05:49:50,873 DEBUG : Generating results file: kraken 05:49:50 INFO - 2015-12-18 05:49:50,874 INFO : javascript benchmark 05:49:50 INFO - 2015-12-18 05:49:50,874 DEBUG : Working with test: v8_7 05:49:50 INFO - 2015-12-18 05:49:50,874 DEBUG : Generating results file: v8_7 05:49:50 INFO - 2015-12-18 05:49:50,874 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:49:50 INFO - 2015-12-18 05:49:50,929 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 05:49:50 INFO - 2015-12-18 05:49:50,929 DEBUG : process_Request line: dromaeo_css 6997.95 graph.html#tests=[[72,1,35]] 05:49:50 INFO - 2015-12-18 05:49:50,929 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:49:50 INFO - 2015-12-18 05:49:50,961 DEBUG : process_Request line: kraken 1497.30 graph.html#tests=[[232,1,35]] 05:49:50 INFO - 2015-12-18 05:49:50,961 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:49:50 INFO - 2015-12-18 05:49:50,997 DEBUG : process_Request line: v8_7 19596.44 graph.html#tests=[[230,1,35]] 05:49:50 INFO - 2015-12-18 05:49:50,998 INFO : javascript benchmark 05:49:51 INFO - 2015-12-18 05:49:51,002 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3177.714005739483, 3188.8246146461947, 3226.257960669046, 3228.9838550807244, 3256.3487302539497, 5213.687178769276, 5300.09898317286, 5335.893282134355, 5347.839564813057, 5385.084535628947, 5428.240046393508, 5446.2919820423385, 5457.890842183154, 5475.890482190353, 5574.916376254359, 4928.97535512322, 4965.1559234930055, 4973.552380285773, 5007.899842003158, 5081.364829396325, 4998.77505512251, 5027.321311622939, 5058.342415485981, 5077.441481437039, 5090.847274581768, 4796.712197268175, 4825.034993001396, 4906.656534042619, 4927.630427717935, 4932.8273510427325, 4958.603311735089, 4996.050750357421, 5000.74996250186, 5016.2224855147515, 5052.46949070346, 4826.758662066883, 4970.204767237225, 4970.403551573798, 4981.526754958273, 5051.292819005343, 5125.974370128163, 5152.793888244503, 5223.320968274138, 5224.320838291036, 5262.2632831403635, 5851.4148585141575, 5858.0, 5859.794907178229, 5868.061110222344, 5877.38287479817, 5640.0693885508845, 5864.7067646617925, 5874.265716785402, 5878.088896221093, 5888.941110588925, 5383.892322153535, 5465.863353416156, 5498.890022199614, 5519.034169020405, 5555.2778138842095, 5962.552808539377, 5966.0, 5975.223220981332, 5979.730912108966, 5981.850453738604, 5548.389677135512, 5655.151727240865, 5799.2750906136735, 5807.332156801937, 5807.970960145172, 5717.571182161271, 5770.0, 5835.183074369592, 5836.737346819318, 5892.499137573269, 5855.180274761537, 5882.058870580687, 5904.5571582131515, 5904.586678932434, 5904.793332233351, 5619.887602247933, 5729.083346664514, 5827.0, 5834.649921004753, 5842.386549412336, 4438.822447102144, 4472.172648060055, 4482.8206871725415, 4517.5256598057385, 4519.88700282489, 4012.7592344459426, 4049.4735684360844, 4050.6354428101613, 4091.3249313863475, 4093.2222877653153, 5364.490373414519, 5576.693281869536, 5582.0, 5582.7766889323975, 5582.916256256078, 5680.005998950167, 5775.653460792366, 5811.099279611667, 5940.108983652487, 5944.197533332945, 5516.972415137979, 5598.692071936001, 5602.13166959122, 5608.887822243532, 5609.887802243932, 5102.617303702237, 5120.02719483297, 5146.76839542214, 5147.202183661539, 5184.948150518447, 5732.0, 5851.1515830205235, 5852.7658893643775, 6002.369751176064, 6007.609505382224, 5979.970100149471, 5986.790462333797, 5993.910091348546, 5996.010658241341, 5999.640021598718, 5922.170896074639, 6004.0093384591055, 6034.909476357946, 6042.123892035718, 6072.848178795565, 5551.195076713773, 5644.689542075225, 5652.971735141298, 5700.2304688868135, 5899.115132730125, 5881.794137205177, 5889.469947704727, 5894.2926848778425, 5903.380145084704, 5908.3500814911185, 5729.684867332337, 5743.4543718346695, 5744.425557444222, 5751.568632352674, 5752.0, 5523.116301391758, 5616.8876622466505, 5623.044082505902, 5632.380438151882, 5697.287839020122, 5637.802676906289, 5648.85877853057, 5655.406182350794, 5656.8020119295625, 5660.773569057192, 5714.114312281603, 5744.827655170351, 5750.769969201352, 5761.279840019997, 5771.220885180447, 5466.01611709896, 5527.668339899458, 5605.719714014364, 5630.943690563206, 5677.261955945866], "unit": "score", "name": "dojo.html", "value": 5382.151428591922}, {"lowerIsBetter": false, "replicates": [550.158257865466, 597.9073243647235, 606.7451670298476, 614.2935624032359, 620.3982137326791, 29381.0, 30384.39231215377, 39102.60897391025, 39372.60627393725, 39408.80295598518, 42628.78685606568, 43205.78397108018, 43314.783426082824, 43355.56644433555, 43389.56610433895, 7486.812829679247, 7619.123800762928, 8701.60842762074, 8827.73516794494, 8832.735017949472, 10341.172706183524, 10603.0, 10666.360018398882, 10699.465026748632, 10700.839487407695, 10336.896631033705, 10435.113015393663, 10438.68683939483, 10443.0, 10457.111145552599, 7711.383089352838, 7928.405369597245, 10308.433036183007, 10330.380177189392, 10653.893461065367, 10099.848502272473, 10221.540030698636, 10253.743656408575, 10255.33340332876, 10256.38461692301, 40903.590964090276, 41090.0, 41327.0, 41364.58635413637, 41535.0, 3055.541668749683, 3059.2046068022473, 3099.0547882895708, 3173.428782819092, 3584.9424419796223, 29561.852190739122, 30059.0, 30240.0, 30413.847930760425, 31182.37635247305, 3943.0, 3963.286608410485, 3991.940120898189, 4032.717709760318, 4068.1863627274665, 310064.0, 313983.0, 314057.0, 314486.0, 314584.0, 59525.0, 60344.69827650922, 60529.69735151384, 60634.0, 60707.69646151741, 57611.42388576145, 58537.70731146402, 65517.34482655208, 65818.34181658218, 67255.66372168108, 13474.19154850712, 13538.323083845768, 13613.0, 13625.86374136246, 13629.454821807214, 8805.42764720291, 8901.287896968357, 8908.198262156307, 8913.153250441197, 9007.234385077341, 20141.697874531892, 20193.697094543593, 20354.592908141753, 20404.28584999518, 20434.182632694527, 8026.357891368676, 8257.0, 8281.8343633127, 8289.963754530683, 8303.0, 954.2795189631784, 1001.3040936549174, 1028.9382637041801, 1043.4261156363968, 1094.3543309447305, 28158.43683126367, 28639.71360286412, 28714.42571148607, 29052.0, 29057.27356816054, 1002.9849552256723, 1012.8075665623508, 1018.0837246478229, 1062.7608788022633, 1093.568040623965, 14702.63243418923, 14862.925685371503, 14933.25333733309, 15192.772108418383, 15205.467808626645, 314514.0, 316765.0, 318511.0, 319138.0, 319890.0, 52103.0, 52394.21408678873, 52668.73665631724, 52718.47281527212, 52767.73616131895, 1306.0596370613143, 1315.421214665544, 1336.0447280194708, 1336.4386957477882, 1338.8728070833256, 27333.316667083483, 28308.586668265132, 28357.291067723472, 28375.0, 28522.714772852836, 45867.0, 45976.31035534403, 46137.76931115323, 46224.53775462337, 46354.76822615865, 285768.0, 288260.0, 289213.0, 289340.0, 290034.0, 27878.721212788423, 31310.530342045342, 31662.0, 31843.522347164348, 32046.19884502906, 39725.03437070366, 40643.0, 40781.59218407778, 41397.0, 41751.373729393476, 33997.0, 34158.0, 34259.31481370309, 34577.0, 34679.82660086784, 29200.707992919797, 33261.35628638299, 35325.29349412946, 36322.455163172046, 41331.58668413277, 35179.472307915916, 35641.64358356383, 36080.09799754922, 36188.09529761777, 36293.27413451769, 31394.686053139176, 33277.575323096906, 33446.83276583699, 33981.83009084939, 33988.32023359469, 25429.0, 25496.0, 25570.488590228462, 25589.48821023532, 25815.0], "unit": "score", "name": "ext.html", "value": 18643.990091033793}, {"lowerIsBetter": false, "replicates": [361.31530749230194, 364.18604419123255, 367.4855202716198, 367.961364056774, 368.1807977250616, 14084.577462676136, 14304.570862874105, 16011.679766404664, 16242.83757162428, 16923.746143807854, 12294.56969006085, 12641.747165056693, 14554.781678274834, 14556.563303100898, 15018.399264029425, 5440.075187218173, 5600.859978500529, 5602.691851948141, 5606.467385598362, 5625.6905870177125, 4055.634992850643, 4121.649659778923, 5444.891102177954, 5550.5282051025715, 5590.580706447013, 5932.555058370618, 6066.241719785027, 6074.0281554951, 6091.0, 6104.328523862371, 5912.408759124118, 6050.6974651267265, 6055.27336719592, 6076.301225359096, 6078.39216078393, 5348.331458567679, 5358.678479291255, 5365.0, 5369.275147855029, 5384.1923711443205, 21005.47486312839, 21122.36632901, 21411.892940535352, 21476.570468590697, 21738.89130554337, 2281.1103669568884, 2311.780380863815, 2322.1640209524558, 2331.8484298520543, 2340.8127349812144, 12860.035497337738, 13357.265350405723, 13403.597892063253, 13503.797443038362, 13530.661733456644, 1716.0733204069788, 1733.6619359224956, 1795.7126859702385, 1846.9907650461796, 1864.0772817455447, 57906.71046644782, 60923.69538152281, 61033.77932441371, 61236.387636123516, 61520.38479615191, 9113.407628504126, 9372.224606081163, 9927.602895884229, 9973.551190196458, 10107.69676909694, 12897.0, 12976.740465190644, 13002.934985325013, 13006.739865202642, 13024.804627930589, 7162.964185179145, 7177.605231712201, 7217.3504384604585, 7248.0939882514685, 7392.0, 6927.307269273133, 6957.339052789976, 6983.650817459208, 7006.369426751617, 7011.92988070123, 5695.03184458643, 5757.568182386254, 5766.279215098113, 5768.278965129359, 5770.394108618619, 1642.3020216408217, 1647.0, 1678.9496315110323, 1681.5796050987253, 1777.795553511337, 898.573177740568, 906.4063038709655, 947.7918043885128, 991.973307626603, 1020.4591566469783, 17271.13644317779, 17565.121743912754, 17664.381746638806, 17756.822431775774, 17867.73198402025, 884.5046773806689, 886.663068034143, 928.9767755806159, 950.695777351241, 957.0333962697587, 8875.600597973107, 9108.908910910939, 9116.544172791333, 9269.814603707888, 9809.0, 391373.0, 573384.0, 584332.0, 585502.0, 596418.0, 76952.0, 77628.61185694035, 79806.6009669948, 79970.60014700006, 81033.59483202665, 1125.358545629, 1146.977060458803, 1148.684111869226, 1165.8484397028333, 1179.0567545963195, 15968.600784980004, 15973.440929567409, 16019.919900400424, 16025.599360016093, 16261.59346016359, 36463.453048203766, 37286.0, 37442.812785935894, 37545.2490950174, 38085.4287185698, 49835.0, 50096.49903501064, 51042.48957510526, 54041.729791350794, 54296.45703543072, 8635.525046122273, 9134.58894349769, 9199.724008279762, 9208.769780755534, 9233.630654773733, 17290.481285561455, 17593.20830562602, 17765.555861103578, 17807.46577602674, 17989.370372036916, 14503.0, 14564.19896905638, 14677.48628797987, 14886.181260030802, 14890.702185956437, 13785.793213101611, 13821.86178138246, 14068.296585170905, 14655.48705795292, 14680.3393847275, 14170.149791012573, 14374.281285935453, 14562.563123106323, 14564.0, 14940.850591493945, 9260.49067301305, 9475.526223688927, 9669.371490853073, 9679.467629280458, 9695.0, 9816.754581135528, 12383.380830958597, 14469.421223150955, 14645.92677036608, 15263.847361526243], "unit": "score", "name": "jquery.html", "value": 9034.380521693352}, {"lowerIsBetter": false, "replicates": [1318.3013003108344, 1488.717143742689, 1547.1645311531774, 1639.762234476001, 1731.9826801731988, 6570.474362051036, 7341.632918354081, 7432.368248698854, 7434.628268586576, 7480.0649918760155, 7359.816004599874, 7552.622368881562, 7586.0, 7600.847983040336, 7603.201663825302, 2245.360072379372, 2281.8744969026698, 2283.7830406111393, 2284.4745708487058, 2285.5885940530698, 2218.256883943881, 2228.476308067603, 2302.3208153594646, 2304.873231972241, 2305.561943230781, 2264.705588273522, 2265.546890621874, 2267.1724820440504, 2268.625676763332, 2298.333483289844, 2253.019936327703, 2257.142285931344, 2295.2425699519117, 2298.678185054077, 2299.310206937912, 2167.4581354661336, 2187.6937228787983, 2192.440927563461, 2193.57225341057, 2194.188150384352, 7508.0, 7553.244675532458, 7580.355669768076, 7589.127250366223, 7589.848203035908, 4516.390287311204, 4557.270836666117, 4589.632829373642, 4593.173228818811, 4595.149897269016, 2945.0870230228697, 3031.77261705373, 3093.8607762650736, 3130.8434578270994, 3145.874165033396, 937.2080591899902, 966.3911735606594, 979.8824141103042, 997.8503224516309, 1002.1631937332336, 10248.23138264633, 10250.0, 10262.897371026269, 10265.897341026568, 10267.845982310271, 4093.362654937989, 4094.1811637672586, 4150.045489537423, 4160.563140870165, 4176.018635620627, 8935.776605584782, 8940.374173807772, 8947.686830961014, 8952.507612081268, 8955.865662014945, 4530.433695788027, 4563.041761230113, 4585.12882552314, 4594.862154135381, 4604.1252162089095, 3634.618365071682, 3690.7970061646865, 4133.132042271157, 4182.205380977665, 4182.351735481006, 3680.1351682354625, 3682.2451397463456, 3695.8336874840697, 3716.0, 3729.0490924814, 1162.84301619282, 1163.0637336943842, 1178.5816035307341, 1183.1776915044134, 1183.1954271095565, 1316.3615646411483, 1316.8419789625307, 1340.336533415946, 1344.7176093020576, 1346.1653774659774, 6977.546459480118, 7002.334778196165, 7098.432125430057, 7121.145462544528, 7125.1093613298335, 440.7245471580262, 451.3680846814486, 452.7555120235102, 454.3049134823725, 456.40211323166756, 5524.751386187631, 5559.527440167631, 5559.777608895599, 5561.972190139023, 5564.0, 8806.471611703319, 8942.642294308285, 9112.681056163003, 9238.445693258425, 9242.907570924339, 8981.865272020925, 9110.180083792491, 9118.224950879117, 9122.817543649222, 9130.086991300817, 6947.027416161742, 7087.929120708727, 7094.574325540484, 7177.4616903731385, 7219.350258476762, 7089.82275443118, 7135.322144396274, 7140.07179066718, 7157.0, 7163.820904477429, 7974.0, 8139.430239883151, 8225.95887020561, 8315.875261870955, 8370.288525475402, 8352.749417517483, 8448.577571121297, 8555.0, 8615.267702245128, 8625.137486251324, 5764.308283006066, 6148.354422785722, 6151.108089326933, 6606.075149479173, 6638.83402914931, 6617.073609694743, 6946.791596251918, 6994.160700715946, 7041.0, 7054.047703559953, 6859.348361905611, 6931.44548436134, 6953.0, 7066.964665176642, 7073.752418665322, 6649.2685804562425, 6729.629870357177, 6793.0, 6804.829879253059, 6994.0, 6618.933810661832, 6698.933010669831, 6777.1189745332795, 6817.5568588041615, 6826.624535650587, 6313.936860631335, 6329.7468101275435, 6335.746570137143, 6336.429721325103, 6348.968255158695, 6185.41238582352, 6213.068039794067, 6233.594816336924, 6254.6247225166635, 6261.968690156702], "unit": "score", "name": "mootools.html", "value": 4289.051510693384}, {"lowerIsBetter": false, "replicates": [326.1080943619201, 333.1238841845945, 334.2496096263887, 341.53380635432643, 345.4748781851584, 5819.563532735051, 5893.233879595652, 5956.0, 5991.161237426763, 6011.669358185298, 5711.8000869969555, 5870.501007414364, 5870.677112758807, 5886.470217680408, 5898.88202235955, 4566.04113136241, 4570.085982803453, 4572.382728331666, 4578.771061446931, 4582.08358328334, 4349.847755328564, 4394.516603173648, 4476.574725401098, 4530.569595888385, 4576.1762882681105, 4524.095180963804, 4528.592426681598, 4537.954620453803, 4547.340635607835, 4554.0, 4537.183307004738, 4544.318352247156, 4553.635709143294, 4557.384753058328, 4560.452745670507, 3998.300297447935, 4022.557518672944, 4026.416169655413, 4029.9194016119804, 4031.657309128728, 5468.562514998791, 5603.131514615241, 5603.7478313475995, 5622.465865742748, 5661.60368774186, 5262.131748261532, 5271.789128434858, 5273.815416460444, 5277.762500687479, 5285.286486324336, 5981.641101533922, 6308.558400911938, 6317.368263173692, 6324.0, 6324.083007963865, 4249.192653395845, 4282.057947251599, 4378.584034516716, 4382.9342559861625, 4393.868183954486, 6407.0, 6499.837504062389, 6516.185476815351, 6526.934730652681, 6537.5423720339595, 4456.264716321771, 4488.528704486047, 4491.640668746492, 4492.932606010912, 4499.6400287976885, 4455.576720211575, 4514.232580461329, 4537.2059889519205, 4550.704204226715, 4557.612602928788, 5235.554977826851, 5247.842564723064, 5253.684778913277, 5254.6847189168775, 5258.421573626898, 6327.177466929362, 6333.20834895638, 6334.081558174039, 6337.049442583557, 6342.587731797418, 1578.921053947298, 1600.303867817503, 1600.487843889944, 1600.8479194476506, 1601.3514526616507, 1059.6291298045621, 1062.9202809789297, 1064.297563608015, 1070.6306324318098, 1097.8408130820985, 6108.877822443526, 6113.510919126459, 6114.266288045463, 6120.908186377208, 6121.173641558422, 6538.967305163444, 6545.574537655132, 6546.247181574085, 6547.985062315349, 6556.934430655728, 5946.316173640001, 6047.697615119315, 6103.786367477117, 6108.602940808833, 6113.84715382119, 3508.1755787389943, 3533.2403533239963, 3533.858645654145, 3536.681698647134, 3540.6636369544854, 6547.279799222082, 6627.635480048643, 6634.436072933758, 6635.170603674541, 6637.1703537057865, 6645.66771661415, 6676.766313179112, 6680.498962577729, 6692.59844409337, 6693.765718199839, 6501.902471462932, 6606.8017959461295, 6625.337466253336, 6626.304238055031, 6678.799636010927, 6390.456811170917, 6527.934720652733, 6549.377809108127, 6623.602583844792, 6623.966880165568, 6387.5209359297305, 6514.3811337922825, 6529.151210342625, 6541.934580654132, 6543.509236807164, 6702.458434560176, 6709.798706038827, 6713.63075030878, 6717.05961165447, 6717.126773519607, 4464.285714285698, 4511.3458548510935, 4535.886602834956, 4536.115457485758, 4600.2869555218995, 6500.674966251763, 6500.772472963424, 6518.934810652023, 6519.706613202512, 6522.34776522344, 6343.397377249154, 6346.3019067903415, 6347.71435285404, 6348.111264423077, 6348.269948955929, 6187.474064704551, 6324.430801227912, 6328.6519241442165, 6330.810075697552, 6340.873182536416, 6226.0, 6232.220972378453, 6233.283172435046, 6233.625982441068, 6240.656763877849, 6296.150019747275, 6351.047342898602, 6365.267994180544, 6368.96815515938, 6386.521010924106, 6020.668863212565, 6240.438360547572, 6240.594361366497, 6241.750329986931, 6249.34381889895], "unit": "score", "name": "prototype.html", "value": 4759.282376209647}, {"lowerIsBetter": false, "replicates": [807.565190949288, 818.3412353055792, 835.5154010673809, 846.2087947768837, 851.4337965253105, 13093.803592946113, 13402.061855670107, 13422.39599218035, 13433.731325373512, 13532.0, 14906.40374385026, 15098.547043588682, 15119.24403779812, 15120.546383608482, 15137.924310378432, 685.1914740606082, 838.7399906029135, 843.569779412498, 850.0479463001448, 850.2220468271524, 670.8389986403253, 814.5723495165026, 836.9414141010132, 837.0875745437472, 837.417994493827, 864.0495454999482, 877.4910551879894, 877.7717793373737, 878.89892662344, 879.5030807593721, 706.131458306281, 706.6148948822878, 840.4915026408995, 871.4422769427572, 872.3195907192369, 638.581728967527, 641.240130445419, 642.3640595810115, 644.4039263681076, 645.6545748024785, 13333.266670333127, 13813.0, 13814.516491922835, 13829.239391833446, 13830.654233644138, 11737.823932641017, 11814.17300788945, 11902.821457678141, 12621.242725436503, 12621.74756504874, 9947.403155810674, 10297.639582614573, 10442.2168337375, 10448.52981615829, 10545.156387488982, 3527.0829584308008, 3576.409892367756, 3583.982080089609, 3613.8735144269826, 3700.111973126457, 47862.521374786156, 48012.75993620045, 48062.03875922497, 48096.27855582169, 48242.27636585419, 8788.121187881288, 9176.495292758962, 9250.814983700422, 9358.812823743487, 9371.344005919656, 16792.0, 16825.0, 16861.241244144043, 16865.494035178963, 16866.32534698623, 7127.60798156096, 7559.0, 7814.53112813233, 7921.801954951173, 7959.204079591995, 4410.338449232577, 4487.439070116236, 4493.213687604722, 4515.419603138875, 4517.683762136685, 2994.191568276553, 3018.215264031324, 3030.4090702312837, 3031.030070377502, 3039.012320995685, 556.9108942569251, 558.5726918907039, 564.3791828988162, 564.9265595472644, 566.7223060700227, 655.3479288108363, 655.4985436141355, 663.5998044126985, 685.9314068593201, 732.8973943647893, 7853.450258481851, 8195.139510351328, 8261.967254093239, 8369.707060252862, 8524.786880327918, 467.5581575411272, 579.8956187886221, 625.1841347042154, 644.8558542845059, 665.5873358517654, 3988.4615576897327, 4053.128577355824, 4159.334506479009, 4251.29853574163, 4298.63461605767, 53685.73157134267, 54258.72870635622, 54416.0, 54593.454065458835, 64044.0, 49596.75201623969, 52289.215661765105, 52437.7378113107, 52720.73639631777, 53563.0, 48292.5170748288, 48766.26850597244, 50237.0, 50677.746611267445, 50892.49107508877, 8571.914280857112, 8768.95615521944, 8775.780605484913, 8814.955925220333, 8861.26265788185, 34946.82526587351, 35241.82379088088, 35501.822490888415, 36026.45960310545, 36127.0, 40797.59202407938, 43561.346579800695, 44484.33273500965, 44511.10977780491, 45216.54783452123, 11406.657800266004, 11748.882511175121, 11801.232919860182, 12183.390830458618, 12226.266424014588, 12488.313142777235, 12660.493580256687, 12852.678683032998, 13184.27486488214, 13407.463701451832, 11580.36308003068, 11633.47649355764, 12131.09016823724, 12298.938505307417, 12350.44423000949, 11578.884211157781, 11979.401029948642, 12001.279923204636, 12281.140320177501, 12351.69120771988, 10976.114800640435, 11008.559657613605, 11048.116150707763, 11219.55121795119, 11847.881521184678, 8639.913600864162, 8658.177473140042, 8781.385303028726, 8854.513001784964, 9203.171714545722, 8828.470291782514, 9340.859887101564, 9933.857606375359, 9985.60057597688, 10004.649837255662], "unit": "score", "name": "yui.html", "value": 6345.849609654628}], "name": "dromaeo_css", "value": 6997.953452297726}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 104.0, 110.0, 110.0, 110.0, 100.0, 109.0, 109.0, 109.0], "unit": "score", "name": "ai-astar", "value": 107.9}, {"lowerIsBetter": true, "replicates": [152.0, 123.0, 150.0, 160.0, 125.0, 150.0, 150.0, 124.0, 163.0, 122.0], "unit": "score", "name": "audio-beat-detection", "value": 141.9}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 161.0, 161.0, 162.0, 159.0, 162.0, 165.0, 162.0, 163.0], "unit": "score", "name": "audio-dft", "value": 161.7}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 94.0, 92.0, 71.0, 71.0, 73.0, 109.0, 92.0, 73.0], "unit": "score", "name": "audio-fft", "value": 81.7}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 142.0, 93.0, 93.0, 93.0, 138.0, 93.0, 93.0, 131.0], "unit": "score", "name": "audio-oscillator", "value": 106.3}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 108.0, 111.0, 110.0, 109.0, 109.0, 110.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.4}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0], "unit": "score", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [61.0, 59.0, 59.0, 58.0, 59.0, 58.0, 59.0, 67.0, 57.0, 56.0], "unit": "score", "name": "json-parse-financial", "value": 59.3}, {"lowerIsBetter": true, "replicates": [68.0, 67.0, 67.0, 68.0, 68.0, 68.0, 68.0, 67.0, 67.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.5}, {"lowerIsBetter": true, "replicates": [82.0, 85.0, 84.0, 85.0, 84.0, 84.0, 84.0, 82.0, 86.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 84.0}, {"lowerIsBetter": true, "replicates": [119.0, 112.0, 124.0, 112.0, 122.0, 117.0, 123.0, 116.0, 131.0, 116.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 119.2}, {"lowerIsBetter": true, "replicates": [145.0, 149.0, 150.0, 166.0, 151.0, 140.0, 154.0, 149.0, 150.0, 150.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 150.4}, {"lowerIsBetter": true, "replicates": [68.0, 67.0, 66.0, 67.0, 67.0, 68.0, 66.0, 68.0, 68.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.1}], "name": "kraken", "value": 1497.3}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.57720836142954], "unit": "score", "name": "Richards", "value": 209.41146399999997}, {"lowerIsBetter": false, "replicates": [188.28845791752966], "unit": "score", "name": "DeltaBlue", "value": 351.152698}, {"lowerIsBetter": false, "replicates": [280.58361391694723], "unit": "score", "name": "Encrypt", "value": 948.6690839999999}, {"lowerIsBetter": false, "replicates": [5122.448979591837], "unit": "score", "name": "Decrypt", "value": 51.96362151394425}, {"lowerIsBetter": false, "replicates": [1992.03187250996], "unit": "score", "name": "RayTrace", "value": 371.474478}, {"lowerIsBetter": false, "replicates": [783.0853563038371], "unit": "score", "name": "Earley", "value": 851.0732510000003}, {"lowerIsBetter": false, "replicates": [12204.819277108434], "unit": "score", "name": "Boyer", "value": 54.606543928923976}, {"lowerIsBetter": false, "replicates": [28333.333333333332], "unit": "score", "name": "RegExp", "value": 32.15241176470589}, {"lowerIsBetter": false, "replicates": [627.3525721455458], "unit": "score", "name": "Splay", "value": 129.89665399999998}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 19596.4359273228}]} 05:49:51 INFO - RETURN: dromaeo_css: 6997.95 05:49:51 INFO - RETURN: kraken: 1497.30 05:49:51 INFO - RETURN: v8_7: 19596.44 05:49:51 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "19596.44"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1497.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6997.95"}}} 05:49:51 INFO - Return code: 0 05:49:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:49:51 ERROR - # TBPL SUCCESS # 05:49:51 INFO - Running post-action listener: _resource_record_post_action 05:49:51 INFO - Running post-run listener: _resource_record_post_run 05:49:52 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 0; Write bytes: 543989760; Read time: 0; Write time: 508760 05:49:52 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234262528; Read time: 0; Write time: 292704 05:49:52 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 0; Write bytes: 309538816; Read time: 0; Write time: 216032 05:49:52 INFO - Running post-run listener: _upload_blobber_files 05:49:52 INFO - Blob upload gear active. 05:49:52 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:49:52 INFO - Copying logs to upload dir... 05:49:52 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1272.732191 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2015-12-18 05:49:51.300650) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-18 05:49:51.304472) ========= 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=3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010099 build_url: 'https://queue.taskcluster.net/v1/task/kUrYgA6qTfyZmfbEyQNVFQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-18 05:49:51.357273) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:49:51.357741) ========= 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=3e14eba40af1795cf0357910000001c3-1450444610.943384-463851083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004567 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 05:49:51.409038) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-18 05:49:51.409490) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-18 05:49:51.409902) ========= ========= Total master_lag: 0.24 =========