builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-044 starttime: 1453406473.07 results: success (0) buildid: 20160121092933 builduid: 70011af1483b4c5eaccf5919b26223ee revision: c7ca88c57386a977631f0d873750cea51450dc14 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:13.075248) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:13.075936) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:13.076212) ========= 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=574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006319 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:13.101686) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:13.101987) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:13.125325) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:13.125592) ========= 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=574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004574 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:13.174938) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:13.175199) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:13.175521) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:13.176152) ========= 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=574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-21 12:01:14-- 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% 220M=0s 2016-01-21 12:01:14 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.170092 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:13.357922) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:13.358200) ========= 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=574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020547 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:13.407050) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:13.407365) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c7ca88c57386a977631f0d873750cea51450dc14 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c7ca88c57386a977631f0d873750cea51450dc14 --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=574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-21 12:01:14,779 truncating revision to first 12 chars 2016-01-21 12:01:14,779 Setting DEBUG logging. 2016-01-21 12:01:14,779 attempt 1/10 2016-01-21 12:01:14,779 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c7ca88c57386?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-21 12:01:15,028 unpacking tar archive at: mozilla-aurora-c7ca88c57386/testing/mozharness/ program finished with exit code 0 elapsedTime=0.551938 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:13.971648) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:13.971944) ========= echo c7ca88c57386a977631f0d873750cea51450dc14 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'c7ca88c57386a977631f0d873750cea51450dc14'] 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=574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False c7ca88c57386a977631f0d873750cea51450dc14 program finished with exit code 0 elapsedTime=0.004676 script_repo_revision: 'c7ca88c57386a977631f0d873750cea51450dc14' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:14.024162) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:14.024454) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-21 12:01:14.034434) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-01-21 12:01:14.034793) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:01:15 INFO - MultiFileLogger online at 20160121 12:01:15 in /builds/slave/test-pgo 12:01:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:01:15 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 12:01:15 INFO - {'append_to_log': False, 12:01:15 INFO - 'base_work_dir': '/builds/slave/test-pgo', 12:01:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:01:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 12:01:15 INFO - 'branch': 'Mozilla-Aurora', 12:01:15 INFO - 'buildbot_json_path': 'buildprops.json', 12:01:15 INFO - 'config_files': ('talos/linux_config.py',), 12:01:15 INFO - 'default_actions': ('clobber', 12:01:15 INFO - 'read-buildbot-config', 12:01:15 INFO - 'download-and-extract', 12:01:15 INFO - 'populate-webroot', 12:01:15 INFO - 'create-virtualenv', 12:01:15 INFO - 'install', 12:01:15 INFO - 'run-tests'), 12:01:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:01:15 INFO - 'download_minidump_stackwalk': True, 12:01:15 INFO - 'download_symbols': 'ondemand', 12:01:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:01:15 INFO - 'tooltool.py': '/tools/tooltool.py', 12:01:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:01:15 INFO - '/tools/misc-python/virtualenv.py')}, 12:01:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:01:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:01:15 INFO - 'installer_path': 'installer.exe', 12:01:15 INFO - 'log_level': 'info', 12:01:15 INFO - 'log_name': 'talos', 12:01:15 INFO - 'log_to_console': True, 12:01:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:01:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:01:15 INFO - 'opt_config_files': (), 12:01:15 INFO - 'pip_index': False, 12:01:15 INFO - 'sps_profile': False, 12:01:15 INFO - 'sps_profile_interval': 0, 12:01:15 INFO - 'suite': 'dromaeojs', 12:01:15 INFO - 'system_bits': '32', 12:01:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:01:15 INFO - 'title': 'talos-linux64-ix-044', 12:01:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:01:15 INFO - 'use_talos_json': True, 12:01:15 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 12:01:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:01:15 INFO - 'work_dir': 'build'} 12:01:15 INFO - ##### 12:01:15 INFO - ##### Running clobber step. 12:01:15 INFO - ##### 12:01:15 INFO - Running pre-action listener: _resource_record_pre_action 12:01:15 INFO - Running main action method: clobber 12:01:15 INFO - rmtree: /builds/slave/test-pgo/build 12:01:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 12:01:15 INFO - Running post-action listener: _resource_record_post_action 12:01:15 INFO - ##### 12:01:15 INFO - ##### Running read-buildbot-config step. 12:01:15 INFO - ##### 12:01:15 INFO - Running pre-action listener: _resource_record_pre_action 12:01:15 INFO - Running main action method: read_buildbot_config 12:01:15 INFO - Using buildbot properties: 12:01:15 INFO - { 12:01:15 INFO - "properties": { 12:01:15 INFO - "buildnumber": 103, 12:01:15 INFO - "product": "firefox", 12:01:15 INFO - "script_repo_revision": "production", 12:01:15 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 12:01:15 INFO - "repository": "", 12:01:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 12:01:15 INFO - "buildid": "20160121092933", 12:01:15 INFO - "slavename": "talos-linux64-ix-044", 12:01:15 INFO - "pgo_build": "True", 12:01:15 INFO - "basedir": "/builds/slave/test-pgo", 12:01:15 INFO - "project": "", 12:01:15 INFO - "platform": "ubuntu64_hw", 12:01:15 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:01:15 INFO - "slavebuilddir": "test", 12:01:15 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 12:01:15 INFO - "branch": "mozilla-aurora", 12:01:15 INFO - "repo_path": "releases/mozilla-aurora", 12:01:15 INFO - "stage_platform": "linux64-pgo", 12:01:15 INFO - "builduid": "70011af1483b4c5eaccf5919b26223ee", 12:01:15 INFO - "revision": "c7ca88c57386a977631f0d873750cea51450dc14" 12:01:15 INFO - }, 12:01:15 INFO - "sourcestamp": { 12:01:15 INFO - "repository": "", 12:01:15 INFO - "hasPatch": false, 12:01:15 INFO - "project": "", 12:01:15 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 12:01:15 INFO - "changes": [ 12:01:15 INFO - { 12:01:15 INFO - "category": null, 12:01:15 INFO - "files": [ 12:01:15 INFO - { 12:01:15 INFO - "url": null, 12:01:15 INFO - "name": "https://queue.taskcluster.net/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2" 12:01:15 INFO - } 12:01:15 INFO - ], 12:01:15 INFO - "repository": "", 12:01:15 INFO - "rev": "c7ca88c57386a977631f0d873750cea51450dc14", 12:01:15 INFO - "who": "dteller@mozilla.com", 12:01:15 INFO - "when": 1453406441, 12:01:15 INFO - "number": 7495062, 12:01:15 INFO - "comments": "Bug 1224374 - Profiler labels for the 100 top chrome hangs (Aurora uplift edition);r=benwa,a=sylvestre", 12:01:15 INFO - "project": "", 12:01:15 INFO - "at": "Thu 21 Jan 2016 12:00:41", 12:01:15 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 12:01:15 INFO - "revlink": "", 12:01:15 INFO - "properties": [ 12:01:15 INFO - [ 12:01:15 INFO - "buildid", 12:01:15 INFO - "20160121092933", 12:01:15 INFO - "Change" 12:01:15 INFO - ], 12:01:15 INFO - [ 12:01:15 INFO - "builduid", 12:01:15 INFO - "70011af1483b4c5eaccf5919b26223ee", 12:01:15 INFO - "Change" 12:01:15 INFO - ], 12:01:15 INFO - [ 12:01:15 INFO - "pgo_build", 12:01:15 INFO - "True", 12:01:15 INFO - "Change" 12:01:15 INFO - ] 12:01:15 INFO - ], 12:01:15 INFO - "revision": "c7ca88c57386a977631f0d873750cea51450dc14" 12:01:15 INFO - } 12:01:15 INFO - ], 12:01:15 INFO - "revision": "c7ca88c57386a977631f0d873750cea51450dc14" 12:01:15 INFO - } 12:01:15 INFO - } 12:01:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2. 12:01:15 INFO - Running post-action listener: _resource_record_post_action 12:01:15 INFO - ##### 12:01:15 INFO - ##### Running download-and-extract step. 12:01:15 INFO - ##### 12:01:15 INFO - Running pre-action listener: _resource_record_pre_action 12:01:15 INFO - Running main action method: download_and_extract 12:01:15 INFO - mkdir: /builds/slave/test-pgo/build/tests 12:01:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:15 INFO - https://queue.taskcluster.net/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 12:01:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/test_packages.json 12:01:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/test_packages.json 12:01:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 12:01:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 12:01:25 INFO - Downloaded 1302 bytes. 12:01:25 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 12:01:25 INFO - Using the following test package requirements: 12:01:25 INFO - {u'common': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 12:01:25 INFO - u'cppunittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 12:01:25 INFO - u'firefox-45.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 12:01:25 INFO - u'jittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 12:01:25 INFO - u'jsshell-linux-x86_64.zip'], 12:01:25 INFO - u'mochitest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 12:01:25 INFO - u'firefox-45.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 12:01:25 INFO - u'mozbase': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 12:01:25 INFO - u'reftest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 12:01:25 INFO - u'firefox-45.0a2.en-US.linux-x86_64.reftest.tests.zip'], 12:01:25 INFO - u'talos': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 12:01:25 INFO - u'firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'], 12:01:25 INFO - u'web-platform': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 12:01:25 INFO - u'firefox-45.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 12:01:25 INFO - u'webapprt': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 12:01:25 INFO - u'xpcshell': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 12:01:25 INFO - u'firefox-45.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 12:01:25 INFO - Downloading packages: [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:25 INFO - https://queue.taskcluster.net/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:01:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 12:01:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 12:01:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 12:01:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:01:30 INFO - Downloaded 23159405 bytes. 12:01:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 12:01:30 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 12:01:30 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 12:01:31 INFO - Return code: 0 12:01:31 INFO - Downloading packages: [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:01:31 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')]} 12:01:31 INFO - https://queue.taskcluster.net/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:01:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 12:01:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 12:01:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 12:01:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:01:35 INFO - Downloaded 23159405 bytes. 12:01:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 12:01:35 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 12:01:35 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 12:01:36 INFO - Return code: 0 12:01:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:36 INFO - https://queue.taskcluster.net/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:01:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 12:01:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 12:01:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 12:01:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:01:37 INFO - Downloaded 11132038 bytes. 12:01:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 12:01:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 12:01:37 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 12:01:38 INFO - Return code: 0 12:01: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')]} 12:01:38 INFO - https://queue.taskcluster.net/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:01:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 12:01:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 12:01:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 12:01:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:01:39 INFO - Downloaded 58143188 bytes. 12:01:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 12:01:39 INFO - mkdir: /builds/slave/test-pgo/properties 12:01:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 12:01:39 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 12:01:39 INFO - Contents: 12:01:39 INFO - build_url:https://queue.taskcluster.net/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 12:01:39 INFO - Running post-action listener: _resource_record_post_action 12:01:39 INFO - Running post-action listener: set_extra_try_arguments 12:01:39 INFO - ##### 12:01:39 INFO - ##### Running populate-webroot step. 12:01:39 INFO - ##### 12:01:39 INFO - Running pre-action listener: _resource_record_pre_action 12:01:39 INFO - Running main action method: populate_webroot 12:01:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:01:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:01:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:01:39 INFO - '--tppagecycles', 12:01:39 INFO - '7'], 12:01:39 INFO - 'tests': ['tsvgm']}}, 12:01:39 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 12:01:39 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 12:01:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:01:39 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 12:01:39 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 12:01:39 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 12:01:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:01:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:39 INFO - 'talos_options': ['--e10s'], 12:01:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:01:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:39 INFO - 'tests': ['damp', 'tps']}, 12:01:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:39 INFO - 'talos_options': ['--e10s'], 12:01:39 INFO - 'tests': ['damp', 'tps']}, 12:01:39 INFO - 'g3': {'tests': ['dromaeo_dom']}, 12:01:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:01:39 INFO - 'other': {'tests': ['a11yr', 12:01:39 INFO - 'ts_paint', 12:01:39 INFO - 'tpaint', 12:01:39 INFO - 'sessionrestore', 12:01:39 INFO - 'sessionrestore_no_auto_restore']}, 12:01:39 INFO - 'other-e10s': {'talos_options': ['--e10s'], 12:01:39 INFO - 'tests': ['a11yr', 12:01:39 INFO - 'ts_paint', 12:01:39 INFO - 'tpaint', 12:01:39 INFO - 'sessionrestore', 12:01:39 INFO - 'sessionrestore_no_auto_restore']}, 12:01:39 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 12:01:39 INFO - 'tests': ['a11yr', 12:01:39 INFO - 'ts_paint', 12:01:39 INFO - 'tpaint', 12:01:39 INFO - 'sessionrestore', 12:01:39 INFO - 'sessionrestore_no_auto_restore']}, 12:01:39 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 12:01:39 INFO - 'tests': ['a11yr', 12:01:39 INFO - 'ts_paint', 12:01:39 INFO - 'tpaint', 12:01:39 INFO - 'sessionrestore', 12:01:39 INFO - 'sessionrestore_no_auto_restore']}, 12:01:39 INFO - 'other_l64': {'tests': ['a11yr', 12:01:39 INFO - 'ts_paint', 12:01:39 INFO - 'tpaint', 12:01:39 INFO - 'sessionrestore', 12:01:39 INFO - 'sessionrestore_no_auto_restore']}, 12:01:39 INFO - 'other_nol64': {'tests': ['a11yr', 12:01:39 INFO - 'ts_paint', 12:01:39 INFO - 'tpaint', 12:01:39 INFO - 'sessionrestore', 12:01:39 INFO - 'sessionrestore_no_auto_restore']}, 12:01:39 INFO - 'svgr': {'tests': ['tsvgx', 12:01:39 INFO - 'tsvgr_opacity', 12:01:39 INFO - 'tart', 12:01:39 INFO - 'tscrollx', 12:01:39 INFO - 'cart']}, 12:01:39 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 12:01:39 INFO - 'tests': ['tsvgx', 12:01:39 INFO - 'tsvgr_opacity', 12:01:39 INFO - 'tart', 12:01:39 INFO - 'tscrollx', 12:01:39 INFO - 'cart']}, 12:01:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:39 INFO - 'tests': ['tp5o']}, 12:01:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:39 INFO - 'talos_options': ['--e10s'], 12:01:39 INFO - 'tests': ['tp5o']}, 12:01:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:39 INFO - 'talos_options': ['--xperf_path', 12:01:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:01:39 INFO - 'tests': ['tp5n']}, 12:01:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:39 INFO - 'talos_options': ['--e10s', 12:01:39 INFO - '--xperf_path', 12:01:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:01:39 INFO - 'tests': ['tp5n']}}, 12:01:39 INFO - 'talos.zip': {'path': '', 12:01:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:01:39 INFO - Running post-action listener: _resource_record_post_action 12:01:39 INFO - ##### 12:01:39 INFO - ##### Running create-virtualenv step. 12:01:39 INFO - ##### 12:01:39 INFO - Running pre-action listener: _resource_record_pre_action 12:01:39 INFO - Running main action method: create_virtualenv 12:01:39 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 12:01:39 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 12:01:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 12:01:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:01:39 INFO - Using real prefix '/usr' 12:01:39 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 12:01:40 INFO - Installing distribute.............................................................................................................................................................................................done. 12:01:42 INFO - Installing pip.................done. 12:01:42 INFO - Return code: 0 12:01:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 12:01:42 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')]} 12:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:42 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')]} 12:01:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:42 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac534f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1282600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1311350>, '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 0x1312250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13126e0>, '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': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', '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 12:01:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 12:01:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:01:42 INFO - Using env: {'DISPLAY': ':0', 12:01:42 INFO - 'HOME': '/home/cltbld', 12:01:42 INFO - 'LANG': 'en_US.UTF-8', 12:01:42 INFO - 'LANGUAGE': 'en_US:en', 12:01:42 INFO - 'LOGNAME': 'cltbld', 12:01:42 INFO - 'MAIL': '/var/mail/cltbld', 12:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:42 INFO - 'MOZ_NO_REMOTE': '1', 12:01:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:42 INFO - 'NO_EM_RESTART': '1', 12:01:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:01:42 INFO - 'PWD': '/builds/slave/test-pgo', 12:01:42 INFO - 'SHELL': '/bin/bash', 12:01:42 INFO - 'SHLVL': '1', 12:01:42 INFO - 'TERM': 'linux', 12:01:42 INFO - 'TMOUT': '86400', 12:01:42 INFO - 'USER': 'cltbld', 12:01:42 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', 12:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:42 INFO - '_': '/tools/buildbot/bin/python'} 12:01:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:42 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 12:01:42 INFO - Cleaning up... 12:01:42 INFO - Return code: 0 12:01:42 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 12:01:42 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')]} 12:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:42 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')]} 12:01:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:42 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac534f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1282600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1311350>, '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 0x1312250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13126e0>, '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': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', '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 12:01:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 12:01:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 12:01:42 INFO - Using env: {'DISPLAY': ':0', 12:01:42 INFO - 'HOME': '/home/cltbld', 12:01:42 INFO - 'LANG': 'en_US.UTF-8', 12:01:42 INFO - 'LANGUAGE': 'en_US:en', 12:01:42 INFO - 'LOGNAME': 'cltbld', 12:01:42 INFO - 'MAIL': '/var/mail/cltbld', 12:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:42 INFO - 'MOZ_NO_REMOTE': '1', 12:01:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:42 INFO - 'NO_EM_RESTART': '1', 12:01:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:01:42 INFO - 'PWD': '/builds/slave/test-pgo', 12:01:42 INFO - 'SHELL': '/bin/bash', 12:01:42 INFO - 'SHLVL': '1', 12:01:42 INFO - 'TERM': 'linux', 12:01:42 INFO - 'TMOUT': '86400', 12:01:42 INFO - 'USER': 'cltbld', 12:01:42 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', 12:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:42 INFO - '_': '/tools/buildbot/bin/python'} 12:01:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:42 INFO - Downloading/unpacking psutil>=0.7.1 12:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 12:01:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:01:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:01:44 INFO - Installing collected packages: psutil 12:01:44 INFO - Running setup.py install for psutil 12:01:44 INFO - building 'psutil._psutil_linux' extension 12:01:44 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 12:01:44 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 12:01:44 INFO - building 'psutil._psutil_posix' extension 12:01:44 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 12:01:44 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 12:01:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:01:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:01:45 INFO - Successfully installed psutil 12:01:45 INFO - Cleaning up... 12:01:45 INFO - Return code: 0 12:01:45 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 12:01: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')]} 12:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01: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')]} 12:01:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fac534f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1282600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1311350>, '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 0x1312250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13126e0>, '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': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', '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 12:01:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 12:01:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 12:01:45 INFO - Using env: {'DISPLAY': ':0', 12:01:45 INFO - 'HOME': '/home/cltbld', 12:01:45 INFO - 'LANG': 'en_US.UTF-8', 12:01:45 INFO - 'LANGUAGE': 'en_US:en', 12:01:45 INFO - 'LOGNAME': 'cltbld', 12:01:45 INFO - 'MAIL': '/var/mail/cltbld', 12:01:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:45 INFO - 'MOZ_NO_REMOTE': '1', 12:01:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:45 INFO - 'NO_EM_RESTART': '1', 12:01:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:01:45 INFO - 'PWD': '/builds/slave/test-pgo', 12:01:45 INFO - 'SHELL': '/bin/bash', 12:01:45 INFO - 'SHLVL': '1', 12:01:45 INFO - 'TERM': 'linux', 12:01:45 INFO - 'TMOUT': '86400', 12:01:45 INFO - 'USER': 'cltbld', 12:01:45 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', 12:01:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:45 INFO - '_': '/tools/buildbot/bin/python'} 12:01:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:45 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:47 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:01:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:01:47 INFO - Installing collected packages: mozsystemmonitor 12:01:47 INFO - Running setup.py install for mozsystemmonitor 12:01:47 INFO - Successfully installed mozsystemmonitor 12:01:47 INFO - Cleaning up... 12:01:47 INFO - Return code: 0 12:01:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 12:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:47 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fac534f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1282600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1311350>, '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 0x1312250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13126e0>, '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': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', '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 12:01:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 12:01:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:01:47 INFO - Using env: {'DISPLAY': ':0', 12:01:47 INFO - 'HOME': '/home/cltbld', 12:01:47 INFO - 'LANG': 'en_US.UTF-8', 12:01:47 INFO - 'LANGUAGE': 'en_US:en', 12:01:47 INFO - 'LOGNAME': 'cltbld', 12:01:47 INFO - 'MAIL': '/var/mail/cltbld', 12:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:47 INFO - 'MOZ_NO_REMOTE': '1', 12:01:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:47 INFO - 'NO_EM_RESTART': '1', 12:01:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:01:47 INFO - 'PWD': '/builds/slave/test-pgo', 12:01:47 INFO - 'SHELL': '/bin/bash', 12:01:47 INFO - 'SHLVL': '1', 12:01:47 INFO - 'TERM': 'linux', 12:01:47 INFO - 'TMOUT': '86400', 12:01:47 INFO - 'USER': 'cltbld', 12:01:47 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', 12:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:47 INFO - '_': '/tools/buildbot/bin/python'} 12:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:47 INFO - Downloading/unpacking blobuploader==1.2.4 12:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:49 INFO - Downloading blobuploader-1.2.4.tar.gz 12:01:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:01:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:01:49 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 12:01:49 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 12:01:49 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 12:01:49 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 12:01:49 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 12:01:49 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 12:01:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 12:01:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:01:49 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 12:01:49 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 12:01:49 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 12:01:49 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 12:01:49 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 12:01:49 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 12:01:49 INFO - Downloading docopt-0.6.1.tar.gz 12:01:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 12:01:50 INFO - Installing collected packages: blobuploader, requests, docopt 12:01:50 INFO - Running setup.py install for blobuploader 12:01:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:01:50 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 12:01:50 INFO - Running setup.py install for requests 12:01:50 INFO - Running setup.py install for docopt 12:01:50 INFO - Successfully installed blobuploader requests docopt 12:01:50 INFO - Cleaning up... 12:01:50 INFO - Return code: 0 12:01:50 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 12:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:50 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac534f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1282600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1311350>, '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 0x1312250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13126e0>, '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': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', '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 12:01:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 12:01:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:01:50 INFO - Using env: {'DISPLAY': ':0', 12:01:50 INFO - 'HOME': '/home/cltbld', 12:01:50 INFO - 'LANG': 'en_US.UTF-8', 12:01:50 INFO - 'LANGUAGE': 'en_US:en', 12:01:50 INFO - 'LOGNAME': 'cltbld', 12:01:50 INFO - 'MAIL': '/var/mail/cltbld', 12:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:50 INFO - 'MOZ_NO_REMOTE': '1', 12:01:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:50 INFO - 'NO_EM_RESTART': '1', 12:01:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:01:50 INFO - 'PWD': '/builds/slave/test-pgo', 12:01:50 INFO - 'SHELL': '/bin/bash', 12:01:50 INFO - 'SHLVL': '1', 12:01:50 INFO - 'TERM': 'linux', 12:01:50 INFO - 'TMOUT': '86400', 12:01:50 INFO - 'USER': 'cltbld', 12:01:50 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', 12:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:50 INFO - '_': '/tools/buildbot/bin/python'} 12:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 12:01:50 INFO - Running setup.py (path:/tmp/pip-GKItBX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 12:01:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 12:01:50 INFO - Running setup.py (path:/tmp/pip-lHEcY8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 12:01:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 12:01:51 INFO - Running setup.py (path:/tmp/pip-J7RJ2D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 12:01:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 12:01:51 INFO - Running setup.py (path:/tmp/pip-01FEHE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 12:01:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 12:01:51 INFO - Running setup.py (path:/tmp/pip-6ihQvd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 12:01:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 12:01:51 INFO - Running setup.py (path:/tmp/pip-csNquD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 12:01:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 12:01:51 INFO - Running setup.py (path:/tmp/pip-GpZpTm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 12:01:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 12:01:51 INFO - Running setup.py (path:/tmp/pip-TWpfwB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 12:01:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 12:01:51 INFO - Running setup.py (path:/tmp/pip-aulD0n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 12:01:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 12:01:51 INFO - Running setup.py (path:/tmp/pip-WVaOiu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 12:01:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 12:01:51 INFO - Running setup.py (path:/tmp/pip-9IyoJF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 12:01:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 12:01:51 INFO - Running setup.py (path:/tmp/pip-5xHtQn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 12:01:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 12:01:51 INFO - Running setup.py (path:/tmp/pip-fGvkDN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 12:01:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 12:01:51 INFO - Running setup.py (path:/tmp/pip-BJ86ii-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 12:01:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 12:01:51 INFO - Running setup.py (path:/tmp/pip-0nblgL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 12:01:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 12:01:51 INFO - Running setup.py (path:/tmp/pip-oTDX81-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 12:01:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 12:01:52 INFO - Running setup.py (path:/tmp/pip-cpsObC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 12:01:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:01:52 INFO - Running setup.py install for manifestparser 12:01:52 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 12:01:52 INFO - Running setup.py install for mozcrash 12:01:52 INFO - Running setup.py install for mozdebug 12:01:52 INFO - Running setup.py install for mozdevice 12:01:52 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 12:01:52 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 12:01:52 INFO - Running setup.py install for mozfile 12:01:52 INFO - Running setup.py install for mozhttpd 12:01:52 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 12:01:52 INFO - Running setup.py install for mozinfo 12:01:52 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 12:01:52 INFO - Running setup.py install for mozInstall 12:01:52 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 12:01:52 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 12:01:52 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 12:01:52 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 12:01:52 INFO - Running setup.py install for mozleak 12:01:53 INFO - Running setup.py install for mozlog 12:01:53 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 12:01:53 INFO - Running setup.py install for moznetwork 12:01:53 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 12:01:53 INFO - Running setup.py install for mozprocess 12:01:53 INFO - Running setup.py install for mozprofile 12:01:53 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 12:01:53 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 12:01:53 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 12:01:53 INFO - Running setup.py install for mozrunner 12:01:53 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 12:01:53 INFO - Running setup.py install for mozscreenshot 12:01:53 INFO - Running setup.py install for moztest 12:01:53 INFO - Running setup.py install for mozversion 12:01:53 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 12:01:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:01:53 INFO - Cleaning up... 12:01:53 INFO - Return code: 0 12:01:53 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 12:01:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac534f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1282600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1311350>, '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 0x1312250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13126e0>, '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': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', '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 12:01:53 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 12:01:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:01:53 INFO - Using env: {'DISPLAY': ':0', 12:01:53 INFO - 'HOME': '/home/cltbld', 12:01:53 INFO - 'LANG': 'en_US.UTF-8', 12:01:53 INFO - 'LANGUAGE': 'en_US:en', 12:01:53 INFO - 'LOGNAME': 'cltbld', 12:01:53 INFO - 'MAIL': '/var/mail/cltbld', 12:01:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:53 INFO - 'MOZ_NO_REMOTE': '1', 12:01:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:53 INFO - 'NO_EM_RESTART': '1', 12:01:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:01:53 INFO - 'PWD': '/builds/slave/test-pgo', 12:01:53 INFO - 'SHELL': '/bin/bash', 12:01:53 INFO - 'SHLVL': '1', 12:01:53 INFO - 'TERM': 'linux', 12:01:53 INFO - 'TMOUT': '86400', 12:01:53 INFO - 'USER': 'cltbld', 12:01:53 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', 12:01:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:53 INFO - '_': '/tools/buildbot/bin/python'} 12:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 12:01:54 INFO - Running setup.py (path:/tmp/pip-DNmOrN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 12:01:54 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)) 12:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 12:01:54 INFO - Running setup.py (path:/tmp/pip-8wQXAQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 12:01:54 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)) 12:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 12:01:54 INFO - Running setup.py (path:/tmp/pip-wKEi5s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 12:01:54 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)) 12:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 12:01:54 INFO - Running setup.py (path:/tmp/pip-p2jsHR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 12:01:54 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)) 12:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 12:01:54 INFO - Running setup.py (path:/tmp/pip-ESLooW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 12:01:54 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)) 12:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 12:01:54 INFO - Running setup.py (path:/tmp/pip-lQK76G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 12:01:54 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)) 12:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 12:01:54 INFO - Running setup.py (path:/tmp/pip-ATAxFW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 12:01:54 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)) 12:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 12:01:54 INFO - Running setup.py (path:/tmp/pip-IiqltB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 12:01:54 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)) 12:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 12:01:54 INFO - Running setup.py (path:/tmp/pip-pSMB0G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 12:01:54 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)) 12:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 12:01:54 INFO - Running setup.py (path:/tmp/pip-bvyYzN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 12:01:54 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)) 12:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 12:01:54 INFO - Running setup.py (path:/tmp/pip-HnKESM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 12:01:54 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)) 12:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 12:01:54 INFO - Running setup.py (path:/tmp/pip-ej_8AT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 12:01:54 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)) 12:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 12:01:55 INFO - Running setup.py (path:/tmp/pip-mMFDOF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 12:01:55 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)) 12:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 12:01:55 INFO - Running setup.py (path:/tmp/pip-nUeYjh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 12:01:55 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)) 12:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 12:01:55 INFO - Running setup.py (path:/tmp/pip-WJjRXl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 12:01:55 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)) 12:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 12:01:55 INFO - Running setup.py (path:/tmp/pip-EzyF27-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 12:01:55 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)) 12:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 12:01:55 INFO - Running setup.py (path:/tmp/pip-zOXRPm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 12:01:55 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)) 12:01:55 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)) 12:01:55 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)) 12:01:55 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)) 12:01:55 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)) 12:01:55 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)) 12:01:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 12:01:55 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 12:01:55 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 12:01:55 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 12:01:55 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 12:01:55 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 12:01:55 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 12:01:57 INFO - Downloading blessings-1.6.tar.gz 12:01:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 12:01:57 INFO - Installing collected packages: blessings 12:01:57 INFO - Running setup.py install for blessings 12:01:57 INFO - Successfully installed blessings 12:01:57 INFO - Cleaning up... 12:01:57 INFO - Return code: 0 12:01:57 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 12:01:57 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 12:01:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 12:01:57 INFO - Reading from file tmpfile_stdout 12:01:57 INFO - Current package versions: 12:01:57 INFO - argparse == 1.2.1 12:01:57 INFO - blessings == 1.6 12:01:57 INFO - blobuploader == 1.2.4 12:01:57 INFO - docopt == 0.6.1 12:01:57 INFO - manifestparser == 1.1 12:01:57 INFO - mozInstall == 1.12 12:01:57 INFO - mozcrash == 0.16 12:01:57 INFO - mozdebug == 0.1 12:01:57 INFO - mozdevice == 0.47 12:01:57 INFO - mozfile == 1.2 12:01:57 INFO - mozhttpd == 0.7 12:01:57 INFO - mozinfo == 0.9 12:01:57 INFO - mozleak == 0.1 12:01:57 INFO - mozlog == 3.1 12:01:57 INFO - moznetwork == 0.27 12:01:57 INFO - mozprocess == 0.22 12:01:57 INFO - mozprofile == 0.27 12:01:57 INFO - mozrunner == 6.11 12:01:57 INFO - mozscreenshot == 0.1 12:01:57 INFO - mozsystemmonitor == 0.0 12:01:57 INFO - moztest == 0.7 12:01:57 INFO - mozversion == 1.4 12:01:57 INFO - psutil == 3.1.1 12:01:57 INFO - requests == 1.2.3 12:01:57 INFO - wsgiref == 0.1.2 12:01:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 12:01: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')]} 12:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01: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')]} 12:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac534f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1282600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1311350>, '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 0x1312250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13126e0>, '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': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', '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 12:01:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 12:01:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:01:57 INFO - Using env: {'DISPLAY': ':0', 12:01:57 INFO - 'HOME': '/home/cltbld', 12:01:57 INFO - 'LANG': 'en_US.UTF-8', 12:01:57 INFO - 'LANGUAGE': 'en_US:en', 12:01:57 INFO - 'LOGNAME': 'cltbld', 12:01:57 INFO - 'MAIL': '/var/mail/cltbld', 12:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:57 INFO - 'MOZ_NO_REMOTE': '1', 12:01:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:57 INFO - 'NO_EM_RESTART': '1', 12:01:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:01:57 INFO - 'PWD': '/builds/slave/test-pgo', 12:01:57 INFO - 'SHELL': '/bin/bash', 12:01:57 INFO - 'SHLVL': '1', 12:01:57 INFO - 'TERM': 'linux', 12:01:57 INFO - 'TMOUT': '86400', 12:01:57 INFO - 'USER': 'cltbld', 12:01:57 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', 12:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:57 INFO - '_': '/tools/buildbot/bin/python'} 12:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:57 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)) 12:01:57 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)) 12:01:57 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)) 12:01:57 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)) 12:01:57 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)) 12:01:57 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)) 12:01:57 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)) 12:01:57 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)) 12:01:57 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)) 12:01:57 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)) 12:01:57 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)) 12:01:57 INFO - Cleaning up... 12:01:57 INFO - Return code: 0 12:01:57 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 12:01: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')]} 12:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01: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')]} 12:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac534f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1282600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1311350>, '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 0x1312250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13126e0>, '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': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', '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 12:01:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 12:01:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:01:57 INFO - Using env: {'DISPLAY': ':0', 12:01:57 INFO - 'HOME': '/home/cltbld', 12:01:57 INFO - 'LANG': 'en_US.UTF-8', 12:01:57 INFO - 'LANGUAGE': 'en_US:en', 12:01:57 INFO - 'LOGNAME': 'cltbld', 12:01:57 INFO - 'MAIL': '/var/mail/cltbld', 12:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:57 INFO - 'MOZ_NO_REMOTE': '1', 12:01:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:57 INFO - 'NO_EM_RESTART': '1', 12:01:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:01:57 INFO - 'PWD': '/builds/slave/test-pgo', 12:01:57 INFO - 'SHELL': '/bin/bash', 12:01:57 INFO - 'SHLVL': '1', 12:01:57 INFO - 'TERM': 'linux', 12:01:57 INFO - 'TMOUT': '86400', 12:01:57 INFO - 'USER': 'cltbld', 12:01:57 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', 12:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:57 INFO - '_': '/tools/buildbot/bin/python'} 12:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:57 INFO - Downloading/unpacking jsonschema 12:01:57 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 12:01:57 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 12:01:57 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 12:01:57 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 12:01:57 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 12:01:57 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 12:01:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:01:59 INFO - Downloading/unpacking functools32 (from jsonschema) 12:01:59 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 12:01:59 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 12:01:59 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 12:01:59 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 12:01:59 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 12:01:59 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 12:01:59 INFO - Downloading functools32-3.2.3-2.tar.gz 12:01:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 12:02:00 INFO - warning: no files found matching '*.txt' 12:02:00 INFO - no previously-included directories found matching 'build' 12:02:00 INFO - no previously-included directories found matching 'dist' 12:02:00 INFO - no previously-included directories found matching '.git*' 12:02:00 INFO - Installing collected packages: jsonschema, functools32 12:02:00 INFO - Running setup.py install for functools32 12:02:00 INFO - warning: no files found matching '*.txt' 12:02:00 INFO - no previously-included directories found matching 'build' 12:02:00 INFO - no previously-included directories found matching 'dist' 12:02:00 INFO - no previously-included directories found matching '.git*' 12:02:00 INFO - Successfully installed jsonschema functools32 12:02:00 INFO - Cleaning up... 12:02:00 INFO - Return code: 0 12:02:00 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 12:02:00 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')]} 12:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:00 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')]} 12:02:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:00 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac534f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1282600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1311350>, '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 0x1312250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13126e0>, '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': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', '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 12:02:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 12:02:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 12:02:00 INFO - Using env: {'DISPLAY': ':0', 12:02:00 INFO - 'HOME': '/home/cltbld', 12:02:00 INFO - 'LANG': 'en_US.UTF-8', 12:02:00 INFO - 'LANGUAGE': 'en_US:en', 12:02:00 INFO - 'LOGNAME': 'cltbld', 12:02:00 INFO - 'MAIL': '/var/mail/cltbld', 12:02:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:00 INFO - 'MOZ_NO_REMOTE': '1', 12:02:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:00 INFO - 'NO_EM_RESTART': '1', 12:02:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:02:00 INFO - 'PWD': '/builds/slave/test-pgo', 12:02:00 INFO - 'SHELL': '/bin/bash', 12:02:00 INFO - 'SHLVL': '1', 12:02:00 INFO - 'TERM': 'linux', 12:02:00 INFO - 'TMOUT': '86400', 12:02:00 INFO - 'USER': 'cltbld', 12:02:00 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', 12:02:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:00 INFO - '_': '/tools/buildbot/bin/python'} 12:02:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:00 INFO - Downloading/unpacking flake8 12:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:02 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:02:02 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02:02 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:02:02 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 12:02:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:02:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:02:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:02:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:02:02 INFO - no previously-included directories found matching 'docs/_build' 12:02:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:02:03 INFO - Running setup.py install for pep8 12:02:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:02:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:02:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:02:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:02:03 INFO - no previously-included directories found matching 'docs/_build' 12:02:03 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 12:02:03 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:02:03 INFO - Cleaning up... 12:02:03 INFO - Return code: 0 12:02:03 INFO - Running post-action listener: _resource_record_post_action 12:02:03 INFO - Running post-action listener: _start_resource_monitoring 12:02:03 INFO - Starting resource monitoring. 12:02:03 INFO - ##### 12:02:03 INFO - ##### Running install step. 12:02:03 INFO - ##### 12:02:03 INFO - Running pre-action listener: _resource_record_pre_action 12:02:03 INFO - Running main action method: install 12:02:03 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 12:02:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 12:02:03 INFO - Reading from file tmpfile_stdout 12:02:03 INFO - Detecting whether we're running mozinstall >=1.0... 12:02:03 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 12:02:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 12:02:03 INFO - Reading from file tmpfile_stdout 12:02:03 INFO - Output received: 12:02:03 INFO - Usage: mozinstall [options] installer 12:02:03 INFO - Options: 12:02:03 INFO - -h, --help show this help message and exit 12:02:03 INFO - -d DEST, --destination=DEST 12:02:03 INFO - Directory to install application into. [default: 12:02:03 INFO - "/builds/slave/test-pgo"] 12:02:03 INFO - --app=APP Application being installed. [default: firefox] 12:02:03 INFO - mkdir: /builds/slave/test-pgo/build/application 12:02:03 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 12:02:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 12:02:18 INFO - Reading from file tmpfile_stdout 12:02:18 INFO - Output received: 12:02:18 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 12:02:18 INFO - Running post-action listener: _resource_record_post_action 12:02:18 INFO - ##### 12:02:18 INFO - ##### Running run-tests step. 12:02:18 INFO - ##### 12:02:18 INFO - Running pre-action listener: _resource_record_pre_action 12:02:18 INFO - Running main action method: run_tests 12:02:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 12:02:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 12:02:18 INFO - Python 2.7.3 12:02:18 INFO - Return code: 0 12:02:18 INFO - grabbing minidump binary from tooltool 12:02:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:02:18 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 0x1311350>, '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 0x1312250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13126e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 12:02:18 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 12:02:18 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 12:02:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:02:18 INFO - Return code: 0 12:02:18 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 12:02:18 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 12:02:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 12:02:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 12:02:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 12:02:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 12:02:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 12:02:19 INFO - Return code: 0 12:02:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 12:02:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-044 --webServer localhost 12:02:19 INFO - Using env: {'DISPLAY': ':0', 12:02:19 INFO - 'HOME': '/home/cltbld', 12:02:19 INFO - 'LANG': 'en_US.UTF-8', 12:02:19 INFO - 'LANGUAGE': 'en_US:en', 12:02:19 INFO - 'LOGNAME': 'cltbld', 12:02:19 INFO - 'MAIL': '/var/mail/cltbld', 12:02:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 12:02:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 12:02:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:19 INFO - 'MOZ_NO_REMOTE': '1', 12:02:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 12:02:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:19 INFO - 'NO_EM_RESTART': '1', 12:02:19 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 12:02:19 INFO - 'PWD': '/builds/slave/test-pgo', 12:02:19 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 12:02:19 INFO - 'SHELL': '/bin/bash', 12:02:19 INFO - 'SHLVL': '1', 12:02:19 INFO - 'TERM': 'linux', 12:02:19 INFO - 'TMOUT': '86400', 12:02:19 INFO - 'USER': 'cltbld', 12:02:19 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989', 12:02:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:19 INFO - '_': '/tools/buildbot/bin/python'} 12:02:19 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost'] with output_timeout 3600 12:02:19 INFO - mozversion INFO | application_buildid: 20160121092933 12:02:19 INFO - mozversion INFO | application_changeset: c7ca88c57386a977631f0d873750cea51450dc14 12:02:19 INFO - mozversion INFO | application_display_name: FirefoxDeveloperEdition 12:02:19 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:02:19 INFO - mozversion INFO | application_name: Firefox 12:02:19 INFO - mozversion INFO | application_remotingname: firefox-dev 12:02:19 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:02:19 INFO - mozversion INFO | application_vendor: Mozilla 12:02:19 INFO - mozversion INFO | application_version: 45.0a2 12:02:19 INFO - mozversion INFO | platform_buildid: 20160121092933 12:02:19 INFO - mozversion INFO | platform_changeset: c7ca88c57386a977631f0d873750cea51450dc14 12:02:19 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:02:19 INFO - mozversion INFO | platform_version: 45.0a2 12:02:19 INFO - 2016-01-21 12:02:19,465 DEBUG : using testdate: 1453406539 12:02:19 INFO - 2016-01-21 12:02:19,465 DEBUG : actual date: 1453406539 12:02:19 INFO - 2016-01-21 12:02:19,512 INFO : starting webserver on 'localhost:57421' 12:02:19 INFO - 2016-01-21 12:02:19,513 INFO : Starting test suite talos-linux64-ix-044 12:02:19 INFO - 2016-01-21 12:02:19,514 INFO : Starting test dromaeo_css 12:02:19 INFO - 2016-01-21 12:02:19,514 DEBUG : operating with platform_type : linux_ 12:02:19 INFO - 2016-01-21 12:02:19,514 INFO : Initialising browser for dromaeo_css test... 12:02:19 INFO - 2016-01-21 12:02:19,525 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpeoFJmR/profile http://localhost:57421/getInfo.html 12:02:23 INFO - 2016-01-21 12:02:23,767 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:02:23 INFO - 2016-01-21 12:02:23,767 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:02:23 INFO - 2016-01-21 12:02:23,767 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 12:02:23 INFO - 2016-01-21 12:02:23,767 DEBUG : BROWSER_OUTPUT: __metrics 12:02:23 INFO - 2016-01-21 12:02:23,932 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] 12:02:24 INFO - 2016-01-21 12:02:24,744 INFO : Browser initialized. 12:02:24 INFO - 2016-01-21 12:02:24,745 INFO : Running cycle 1/1 for dromaeo_css test... 12:02:24 INFO - 2016-01-21 12:02:24,745 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpeoFJmR/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:02:36 INFO - 2016-01-21 12:02:36,478 DEBUG : BROWSER_OUTPUT: RSS: Main: 137637888 12:02:36 INFO - 2016-01-21 12:02:36,478 DEBUG : BROWSER_OUTPUT: 12:03:27 INFO - 2016-01-21 12:03:27,644 DEBUG : BROWSER_OUTPUT: 1453406607643 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] 12:05:36 INFO - 2016-01-21 12:05:36,193 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57421/tests/dromaeo/cssquery-ext.html) 12:05:37 INFO - 2016-01-21 12:05:37,366 DEBUG : BROWSER_OUTPUT: RSS: Main: 255348736 12:05:37 INFO - 2016-01-21 12:05:37,366 DEBUG : BROWSER_OUTPUT: 12:08:39 INFO - 2016-01-21 12:08:39,600 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/dromaeo/cssquery-ext.html (next: http://localhost:57421/tests/dromaeo/cssquery-jquery.html) 12:08:40 INFO - 2016-01-21 12:08:40,162 DEBUG : BROWSER_OUTPUT: RSS: Main: 211243008 12:08:40 INFO - 2016-01-21 12:08:40,162 DEBUG : BROWSER_OUTPUT: 12:11:41 INFO - 2016-01-21 12:11:41,966 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57421/tests/dromaeo/cssquery-mootools.html) 12:11:42 INFO - 2016-01-21 12:11:42,532 DEBUG : BROWSER_OUTPUT: RSS: Main: 220950528 12:11:42 INFO - 2016-01-21 12:11:42,532 DEBUG : BROWSER_OUTPUT: 12:14:44 INFO - 2016-01-21 12:14:44,303 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57421/tests/dromaeo/cssquery-prototype.html) 12:14:44 INFO - 2016-01-21 12:14:44,865 DEBUG : BROWSER_OUTPUT: RSS: Main: 197111808 12:14:44 INFO - 2016-01-21 12:14:44,866 DEBUG : BROWSER_OUTPUT: 12:17:47 INFO - 2016-01-21 12:17:47,337 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57421/tests/dromaeo/cssquery-yui.html) 12:17:47 INFO - 2016-01-21 12:17:47,904 DEBUG : BROWSER_OUTPUT: RSS: Main: 201314304 12:17:47 INFO - 2016-01-21 12:17:47,904 DEBUG : BROWSER_OUTPUT: 12:20:50 INFO - 2016-01-21 12:20:50,130 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/dromaeo/cssquery-yui.html (next: http://localhost:57421/tests/dromaeo/cssquery-dojo.html) 12:20:50 INFO - 2016-01-21 12:20:50,380 DEBUG : BROWSER_OUTPUT: RSS: Main: 243273728 12:20:50 INFO - 2016-01-21 12:20:50,380 DEBUG : BROWSER_OUTPUT: 12:20:50 INFO - 2016-01-21 12:20:50,381 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:20:50 INFO - 2016-01-21 12:20:50,382 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:20:50 INFO - 2016-01-21 12:20:50,382 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:20:50 INFO - 2016-01-21 12:20:50,382 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:20:50 INFO - 2016-01-21 12:20:50,383 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3311.288073064291;3371.79769213847;3391.1691635549278;3394.7284217262622;3419.179396944734;5483.341998960121;5484.451554844519;5496.065668836298;5515.751791169397;5570.025245582017;5533.501984821365;5575.637583557066;5589;5589.0219211638005;5603.60774745768;4854.538818812225;5067.670601410906;5101.54086132248;5175.301334319856;5186.196139598368;5063.316452278932;5115.079285728568;5129.589632829383;5130.333056702623;5137.537621614054;5080.491950804927;5119.385673719159;5121.718305493197;5126.436092029874;5128.512791284822;4948.1093403187415;5021.297018417406;5094.2103973884105;5101.362329708786;5115.437301896788;5048.848534543933;5052.014857102867;5086.033653605803;5120.564751996085;5145.176771716545;5123.410807757118;5226.7909283628605;5239.842804715865;5401.216823560601;5401.3248343957;5905.763769449217;5909.8227053188475;5930.318013428425;6011.759529618854;6051.304100028509;5952.880942381172;5959.582829201958;5960.374160713106;5961.105834124916;5963.761449542013;5330.040592693274;5361.678299302054;5441.2654291670515;5499.312585926759;5545.445455454463;6039.516838652898;6080.726367313481;6083.847903802441;6084.391560843968;6112.113743507255;5886.117082437583;5889.352171261158;5913.201717768132;5915.585908986416;5918.082697181943;5750.166225897222;5840.941590584125;5973.342932277533;5985.401459854067;5986.73059712314;5805.129230615357;5856.795012174639;5873.20711703923;5876.412358764175;5915.58590898633;5893.6463812171405;5940.762369505172;5946.494547963472;5948.613340132878;5954.047352423591;4413.3159360299205;4490.50604433512;4505.7296562206375;4522.751248681288;4592.908141837144;4058.6753059755147;4107.712460127762;4111.013356794348;4115.794210289474;4122.711410201317;5614.29821272341;5620.803271885465;5628.0713682242795;5637.520810731134;5657.12314591239;5743.770249190069;5837.970810146007;5839.824805255849;5955.37468565803;5995.670238136944;5631.493165615032;5646.378898321182;5654.264945557051;5662.150677398341;5663.575231857627;5222.477752224823;5232.973835130876;5233.136532472099;5242.213667949762;5267.262583238273;5940.584159108821;6071.787487437919;6076.665783381956;6159.876802464015;6166.383361663798;6068.969655151696;6069.726862291119;6079.148919151234;6079.422454866781;6082.2093127893095;6090.543209259235;6140.631562106288;6150.292716337678;6158.846028849314;6223.937760622336;5706.572007099401;5743.8564035899435;5801.448862358069;5829.183914251923;5838;5938.703064846827;6010.939890601213;6038.456538911519;6054.061620448838;6061;5623.690697011703;5893.70531473416;5909.52723782105;5912.290525137011;5913.46778789911;5721.914171287517;5722.914156287576;5818.272715910512;5824.621399609012;5864.149698293809;5753.971230143822;5778.739956702043;5791.768329266951;5796.594238403271;5797.159411885337;5582.218489411567;5717.399673034438;5851.853703657443;5874.412558744091;5876;5669.574781891292;5752.424757524214;5761.769529218785;5764.48119669215;5789.131630255495 12:20:50 INFO - 2016-01-21 12:20:50,385 DEBUG : BROWSER_OUTPUT: |1;ext.html;578.6354596604139;623.0187454758756;628.7170773152081;671.8891382921818;672.8586996730686;41325.58674413259;41789.164216715726;41935.370969435484;41946.790266048665;41967.16065678689;45846.31230531545;46221.537784622225;46314;46409.53590464094;46502;8003.3197178239625;8107.959460202691;9092.090790920905;9262.536873156349;10408.843867341995;10778.137748980102;10833.891661083406;10880.727981800439;10906.182036347305;10910.890891091107;10842.512086956105;10928;10941.726456838604;10948.507317170746;10950.288231264982;8217.096119426858;8399.160083991614;10911.83632245517;11100.222984391095;11101.777964440747;10649.201309901788;10798;10819.513121909533;10821.837672434842;10823.404712740796;41906.1618767626;42278.78860605708;42387;42407;42452.363214551806;3547.3792086385038;3617.8191090445634;3678.2459595782802;3689.1699367642336;3771.396576547739;32671.346573068404;33018.33963320744;33039.50440743391;33122.50316245258;33134.83432582822;4024.416459613369;4143.937840932388;4184.832606695729;4192.203481338536;4219.261629214875;307165;308046;310524;310584;310643;57215;63923.68038159779;64327;64495.35504644894;64520.354796452375;51027.74486127545;51356;51397.48602514002;74134;74210.1763385389;18043.278268869362;18327.450176494727;18400;18443.907780461013;18470;13762.311884405939;14016.439342426189;14038.508652197124;14089.788653170212;14115;20579.279725209544;20714.896425517774;20865.79134208669;20868;20878.895605521877;7935.523868567789;7940.0868900076075;8033.919660803317;8078.23256790604;8141.755747327471;1042.7966546523355;1049.9002594753485;1104.2711810205387;1120.6413947536873;1155.3241353808116;29718.257043573652;29728;29809;30236.24409389783;30307.848460757996;1153.6193056291404;1188.0970462448429;1204.5302332090491;1265.2408554867006;1326.1380102933172;13999.860001400059;15971.60070998211;16318.020918744676;16546.82680577995;16985;332852;339132;339148;339317;339524;55278.17082743762;55294.72352638291;55613.721931390086;55759;55827;1456.1699831096173;1481.3037872199834;1485.2499487758612;1498.9700205995819;1500.0699566268984;30862.228444289074;31220;31336.216594584406;31557.684423155475;31619.525707113953;50140.49859501358;50215.497845022546;50500.49499504958;50733.23900141575;50800.74599626978;326690;327241;327254;329159;329697;30544.847275763477;31106.066817995496;33634.831825840716;33872.49191262084;33988.32023359568;39741.60258397379;39872.401913971895;39960.40059399053;40771;41105.588944110175;36320;36802;36870.23473239464;37511.24977500489;37941.81029094837;36885.63114368822;37696.811515942245;37701.24597508089;38116.809415952746;38144.237115258096;33654;34252.48621270633;34279.65720342765;40108.197836042535;40661.59338406696;39208.411873821344;41679.1664166721;41733.58266417297;42329;42615;31024.379512410076;34168.14579635529;36051.63948360483;36332.27335453329;36653.45019824758 12:20:50 INFO - 2016-01-21 12:20:50,386 DEBUG : BROWSER_OUTPUT: |2;jquery.html;388.273927755098;391.8491380818384;392.0610138717771;393;393.3823896482523;14340.426382944695;15985;17313;18572.90713546432;18576.442706718786;17804.554886127855;18190.636187276246;18472;19224.231030758787;19240.61518769624;6139.38606139387;6219.906701399483;6247.312795592481;6247.968760156193;6282.717277722513;4552.726836389811;4692.718436893797;6231.127642130105;6736.730530778763;7000.474964377667;5411.675299482024;6880.862382752342;6923.20383155936;6982.057422248007;6985.196702379215;5458.699771512565;5476.58925580583;5486.231927530149;5530.861728456781;5551.3893471718075;5328.92006619901;5444.829361687256;5597.076482380402;5603.60774745768;5604.215409842625;13673.589792306248;13928.442862285496;17105.31578736849;21309.147634094617;21328.36014919555;2114.873107613548;2152.956940861174;2216.5012872103807;2249.887505624712;2274.943126421836;10825.945870270676;12143.514259429612;12740;13900.860991390058;14227.430902763877;1964.3106928041873;2017.5863947890648;2031.1672214392177;2053.5071582820165;2083.197968782015;59784;60523.39476605177;60773.08840367398;61634;62756.05865912015;10929;11009;11090;11113.22207445471;11191.608293709682;14559.199244041467;14581.635459113608;14638.560843174722;14675.046122002035;14675.926620367043;8125.9187408126345;8157.95921020403;8216.917830821734;8405.663773449116;8497.660093596187;7762.417818663623;7830.177831327743;7841.490303130278;7856.489328193649;7866.842663146819;4386.451693538308;4728.38530990969;4748.525147485224;4757.714537127784;4815.783289751969;1753.626016879382;1920.750302460699;1996.2314508914174;2013.6677448221174;2047.9283225087052;926.7822061815468;933.286036182321;1002.684154491333;1057.582255009267;1069.187417562658;15657.843421565638;16464.341426343053;17481.47555573335;17513.824861751218;17627.647447051244;1018.1244130048152;1030.8814486334018;1037.6679462572056;1059.9400599400599;1088.2491081153978;9476.810463790824;9539.427634341962;9758.560864761102;10287.331323464101;10440.843387349196;641715;645064;658352;660236;661259;79720.20279797127;82756;83094.16905830865;84676.57661711652;85566.14433855706;1192.540871764377;1218.8232706257543;1220.6216073017392;1229.59423390281;1230.6369620961839;16129.435469758502;16369;16465.67068658644;16572.254248558602;16725.247363868413;35932;36486.27027459489;36680.08299792527;37283.81358093192;37956;48376;49171.262431062845;49500;50640.49359506358;52641;9307.976122626642;10051.798964020825;10506.842397363893;10543.630972915911;10548.261621686408;12276;12336.81494777561;13373.866261337262;15005.77491337609;17388.82611173923;15125.39498420051;15214.315355809236;15314.463993760164;15412.768808468107;15493;14943.178125203218;14971.550853474413;15008.324625392102;15045.398184072514;15182;15149.394024238907;15311;15397.615059623151;15645.217739112772;15844.366225351318;10357.17142628603;10371.066604005675;10432.426216558162;10467.738306542398;10729.409882456539;15306.693866122838;15433.69132617319;15534.533963981099;15663;16011.599710006876 12:20:50 INFO - 2016-01-21 12:20:50,387 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1507.901986370886;1561.2427972433372;1620.7568864670304;1741.1294352823588;1864.012073600992;6823.385895269431;7789.961050194748;8001.159878212791;8008.839823203532;8018.67925282988;7696.692132314701;7910.604469776517;7912.7626171214815;7916.485428447147;7924.564148971805;2001.7898120697262;2037.6943458481273;2039.9082041308102;2286.6455699366625;2318.246569864792;2355.458244603742;2394.3774618599114;2401.987990060047;2402.8678422686744;2404.507076049406;2304.642780369037;2309.2264091529273;2313.3175713164655;2362.4093976505874;2363.7163540375095;2318.6406107053435;2336.2056900653774;2358.080348664005;2366.9644955325684;2370.0756704885107;2344.15610380263;2348.2250857217077;2379.654950032253;2386.1529157149307;2388.056717596557;7868.25251601097;7881.448298619099;7916.80207994799;7942.722004729864;7953.965984421988;4764.8332308369045;4796.184648609745;4848.10310092634;4852.781624826891;4855.6115510759055;3105.534169874514;3129.640091389474;3177.7457803375905;3235.288236587947;3260.1197676627417;989.9406035637885;993.056596233581;1006.059334522224;1033.7105610429091;1064.9627263045757;10617.73455663607;10619.57521699131;10636.787264254748;10638.095761860252;10654.4140072296;4276.657867370665;4292.720973136736;4294.334378171388;4322.891927701769;4329.696921215547;9151.993280739114;9170.358074934691;9170.86243706345;9172.541372931326;9201.125893040151;4799.352087468217;4807.086653535817;4809.134355815917;4809.711417314972;4829.710217386898;4414.624756895636;4416.33754936762;4420.3590479380955;4430.202563538594;4444.822207111744;3331.466965285591;3421.9486707699402;3469.340825243196;3696.722745794076;3777.9433308500393;1193.7552801675636;1194.2714943884223;1200.0459634590522;1200.4537935239446;1206.252123683322;1157.779763677897;1170.5142365918048;1214.5991822698488;1450.4198320671815;1470.0811992504684;7487.625618719042;7499.100107987077;7502.549847009197;7662.233776622294;7707.92292077072;1172.7888979983513;1183.4319526627096;1188.9227200231958;1190.892819646236;1198.9628970940073;6059.666718330534;6099.451049405575;6116.755329786759;6127.84680382994;6145;8980.730578082668;9151;9152.405093668891;9193.356465047516;9250.76873078165;8085.231902969209;8172.141925097898;8225.506469611842;8233.217844304781;8298.087210406851;7203.567785932861;7342.853142937218;7398.223186565333;7502.962485187648;7516.774496765106;7596.354309883722;7625.084989801259;7690.307872291521;7694.4229182810395;7707.460477766503;8473.83052338962;8495;8524.91475085266;8543.359248056546;8708.69519566812;8563.82872342562;8585.699500517452;8614.78463038404;8649.394542381973;8751.91248087511;7574.5833979129475;7690.423218258541;7705.036870391201;7752.3798096153305;7777.494462859896;6836.145481814773;6968.338007889242;6974.930250697428;7023.367896889305;7802.726904558313;7283.7450689225625;7318.597477138808;7330.890036649348;7403.444741644291;7416.888746668912;7201.855962880818;7227.530210536298;7243.130824301117;7299;7340.1191856975365;7210.026646402668;7231.891521627074;7270.163931147986;7270.309320614533;7341.412686985135;6671.166104236971;6742.5280230383405;6742.7977160683295;6744.763933262312;6751.493637977073;6664.600123992576;6685.364890335411;6698.263191049078;6699.29657385987;6700.765473208415 12:20:50 INFO - 2016-01-21 12:20:50,388 DEBUG : BROWSER_OUTPUT: |4;prototype.html;362.0983750461349;369.1122849546839;369.46612145449825;372.4989888599832;378.51361001113577;5940.851478713023;6005.8198254052395;6006.099085137226;6011.188489553914;6135.938640613593;5876.177335173079;5990.850228744283;6036.275646922375;6050.606710563821;6088.969555152229;4734.526547345264;4742.596879265267;4754.239321708527;4754.500777418356;4757.976210118962;4578.519255478177;4657.930131048037;4661.510541393156;4763;4764.237721964487;4691.5073917238715;4692.272697731857;4696.788644510988;4704.411948506437;4735.360726301939;4717.57541821236;4719.43366795985;4726.314684370781;4726.76366181693;4739.597134243593;4207.978960105211;4213.599708027763;4223.134257477241;4225.154969006211;4237.067845074079;5714.51426628737;5824.679642619654;5837.270341207349;5861.120831875252;5876.3242227144;5477.616566840323;5478.863028424321;5479.150731636602;5488.17677348401;5493.066178749622;6046.2744470663365;6493.870122597569;6497.870042599122;6499.317571654956;6502.93497065028;4479.664025198123;4482.170798402273;4550.249208880531;4562.726236425825;4575.771211439448;6583.506237032242;6650.900236496456;6661.333866613349;6665.066890635315;6670.6331151786635;4686.554777296152;4692.272697731857;4693.9765301173275;4694.671373003857;4700.952990470051;4754.5245475452175;4756.191447453941;4799.496052914464;4867.707937523689;4878.853634390974;5515.7242137892945;5531.2809334787025;5548.55611551075;5549.944500554942;5550.91673624896;6496.35036496356;6502.739890404426;6507.1540699709685;6508.60948343101;6561.179852518435;1710.606560491069;1737.2703464544923;1737.878348515617;1738.1396208876688;1738.8435040846384;1190.3096204201543;1193.0574845871665;1198.844150260478;1203.4283715235192;1219.6584956212275;6144.477719393813;6179.783707570214;6179.980303250003;6201.658908760061;6212.658303793335;6584.440322572635;6627.635480048547;6636.96681516599;6637.568558043712;6677.799666010027;6199.814005579749;6200.193974783249;6201.844953876189;6205.162303089025;6212.534059945522;3482.7562070655317;3574.2136729919375;3586.5158203642172;3586.748927575097;3588.3899737044767;6730.091437655854;6788.389044985975;6800.115984921928;6801.047853300542;6803.65981700913;6709.563878347989;6731.966340168267;6749.662516874137;6751.459883209332;6751.966240168866;6660.100886380276;6745.942487541347;6749.122614060141;6764.391204791593;6791.660416979132;6510.316416776171;6582.3746744059235;6604.405603495708;6643.900341494978;6651.800445986628;6633.800985970429;6649.0691303218555;6650.168728908887;6660.400563949268;6671.899921501278;6951.16586009682;6951.756688515878;6955;6960.79117626472;6970.442364610921;4759.0719809636785;4837.346958160603;4839.975800120977;4858.538438848445;4860.416749990024;6757.493188011017;6758.628275444898;6760.18877734675;6764.255931847394;6773.89839152423;6582.045603387578;6583.868322633616;6584.176977877765;6584.8024559263295;6592.143021407186;6430;6547.901781473377;6559.4752419807255;6560.212774467094;6573;6422.710978005907;6461.127747753993;6462.063000864942;6462.159919210472;6470.676466176578;6574.704138313692;6579.309172537006;6584.242812076673;6589.374009469093;6593.868122637425;6288.245410550763;6415.069814876723;6437.903431448625;6439.67801609927;6440 12:20:50 INFO - 2016-01-21 12:20:50,389 DEBUG : BROWSER_OUTPUT: |5;yui.html;817.3338728935913;840.0591337701775;885.1502557544761;915.1306259053898;917.6100157433092;13799.310034498272;13994.580162595114;14041.578752637437;14053.156810591372;14057.578272651825;13723.725525489484;13771.380287887045;13803.447862085504;13826.17042977422;13846.653833654138;734.258399016994;772.0966469230985;772.7102336623766;772.8531578999972;774.3069952392606;854.7905763088052;934.0799312677008;935.5976929920132;935.8455854783953;936.0405584276103;960.5773459677754;978.1538968792015;978.5449765858874;978.7112801723473;978.9021097890189;758.5031804168278;796.928276455116;903.4443817052531;958.6788425877339;968.7287559483356;720.3948683106164;723.0925188887965;723.1611330856182;724.9669221359542;726.0162479839846;14082.43670253199;14177.43290268388;14195;14200.573982780534;14219.715605687828;12504.874951250462;12599.748005039848;12676.112672112955;12792.808107878389;12885.226886386847;10890.727731806768;11111.277766945122;11128.443577821077;11128.721781955435;11163.944180279128;3841.42378643203;3853.6531712146043;3871.1483473635753;3884.3784994401044;3899.590542992973;46933.29600056002;48047.51952480465;48237.75881120607;48448;48865.26702099471;10214.693559193236;10219.123804200759;10248.64129755455;10845;10888.510017049251;18559;18632.068396580118;18659.720104198448;18681.626367472847;18717.15772790228;7627.093226693327;7812.765617031499;7939.880901786478;7971.960140199262;8074.515529068275;4580.083983203373;4598.1033698429155;4645.883852903705;4649.651276154302;4705.788239529229;3299.637039925606;3304.801711897294;3318.303156337196;3322.302316513511;3323.401787678241;592.5911121326271;605.9060845568944;613.7575657615217;617.9196704428485;622.5548732656173;722.9349358557755;738.1806195123409;746.7746116572536;754.4492520460095;770.9730159444392;9384.436933783858;9609.275177862148;9684.854727178958;9745.415275083376;9986.850197247048;635.6662752055115;743.5278598090251;753.852998665255;773.1109224391994;790.1071788878645;4312.676549258818;4440.400545926259;4461.955380446219;4462.843800466968;4654.069186162781;54907.45092549024;63747.36252637414;64210;65108.348916511175;65131.34868651348;49717.25424118641;50496.747516262185;51104;51405;51738;46698.76650616725;46759.29861052087;47415.52584474111;47669.76165119222;48112.5188748115;9273.953630231807;9508.904910951078;9675.984021677623;9724.854127188239;9830.901690983284;35871.46192807058;36381.63618363783;36530.8173459131;37528.437073443376;37548.436773448964;40982.38526422166;41269;43422.34866476942;45880.541194588965;46311.53688463207;11881.5841445549;11890.16768826174;12084.516619335129;12261.080418968435;12361.876381236072;13362.532311369056;13594.456221751414;13605;13670.589882303548;13789.86210137926;11204;11383.829242561535;11829.704257393634;12368.876311236772;12427.813582796447;11770.588029419272;11970.521179152736;12081;12217.389130543259;12685.175463594836;11094.334339939629;12497.750044999231;12625.179363341353;12703.491860325854;12962.805557916827;10763.51564179598;10816.891831081588;10834.729131771768;11099.223054386439;11405.315681059163;10527.631532896312;10893.618723344645;10948.397838118979;11055.613053543088;11087.778244434905 12:20:50 INFO - 2016-01-21 12:20:50,389 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:20:50 INFO - 2016-01-21 12:20:50,389 DEBUG : BROWSER_OUTPUT: __start_cc_report 12:20:50 INFO - 2016-01-21 12:20:50,389 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,927 12:20:50 INFO - 2016-01-21 12:20:50,389 DEBUG : BROWSER_OUTPUT: __end_cc_report 12:20:50 INFO - 2016-01-21 12:20:50,389 DEBUG : BROWSER_OUTPUT: __startTimestamp1453407650380__endTimestamp 12:20:50 INFO - 2016-01-21 12:20:50,389 DEBUG : BROWSER_OUTPUT: 12:20:50 INFO - 2016-01-21 12:20:50,389 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:20:50 INFO - 2016-01-21 12:20:50,389 DEBUG : BROWSER_OUTPUT: Number of tests: 6 12:20:50 INFO - 2016-01-21 12:20:50,389 DEBUG : BROWSER_OUTPUT: 12:20:50 INFO - 2016-01-21 12:20:50,389 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5524.95 Median:5743.77 stddev:604.31 (10.5%) stddev-sans-first:580.68 12:20:50 INFO - 2016-01-21 12:20:50,390 DEBUG : BROWSER_OUTPUT: Values: 3311.3 3371.8 3391.2 3394.7 3419.2 5483.3 5484.5 5496.1 5515.8 5570.0 5533.5 5575.6 5589.0 5589.0 5603.6 4854.5 5067.7 5101.5 5175.3 5186.2 5063.3 5115.1 5129.6 5130.3 5137.5 5080.5 5119.4 5121.7 5126.4 5128.5 4948.1 5021.3 5094.2 5101.4 5115.4 5048.8 5052.0 5086.0 5120.6 5145.2 5123.4 5226.8 5239.8 5401.2 5401.3 5905.8 5909.8 5930.3 6011.8 6051.3 5952.9 5959.6 5960.4 5961.1 5963.8 5330.0 5361.7 5441.3 5499.3 5545.4 6039.5 6080.7 6083.8 6084.4 6112.1 5886.1 5889.4 5913.2 5915.6 5918.1 5750.2 5840.9 5973.3 5985.4 5986.7 5805.1 5856.8 5873.2 5876.4 5915.6 5893.6 5940.8 5946.5 5948.6 5954.0 4413.3 4490.5 4505.7 4522.8 4592.9 4058.7 4107.7 4111.0 4115.8 4122.7 5614.3 5620.8 5628.1 5637.5 5657.1 5743.8 5838.0 5839.8 5955.4 5995.7 5631.5 5646.4 5654.3 5662.2 5663.6 5222.5 5233.0 5233.1 5242.2 5267.3 5940.6 6071.8 6076.7 6159.9 6166.4 6069.0 6069.7 6079.1 6079.4 6082.2 6090.5 6140.6 6150.3 6158.8 6223.9 5706.6 5743.9 5801.4 5829.2 5838.0 5938.7 6010.9 6038.5 6054.1 6061.0 5623.7 5893.7 5909.5 5912.3 5913.5 5721.9 5722.9 5818.3 5824.6 5864.1 5754.0 5778.7 5791.8 5796.6 5797.2 5582.2 5717.4 5851.9 5874.4 5876.0 5669.6 5752.4 5761.8 5764.5 5789.1 12:20:50 INFO - 2016-01-21 12:20:50,391 DEBUG : BROWSER_OUTPUT: 12:20:50 INFO - 2016-01-21 12:20:50,391 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:50857.96 Median:34078.23 stddev:84909.29 (249.2%) stddev-sans-first:85063.56 12:20:50 INFO - 2016-01-21 12:20:50,391 DEBUG : BROWSER_OUTPUT: Values: 578.6 623.0 628.7 671.9 672.9 41325.6 41789.2 41935.4 41946.8 41967.2 45846.3 46221.5 46314.0 46409.5 46502.0 8003.3 8108.0 9092.1 9262.5 10408.8 10778.1 10833.9 10880.7 10906.2 10910.9 10842.5 10928.0 10941.7 10948.5 10950.3 8217.1 8399.2 10911.8 11100.2 11101.8 10649.2 10798.0 10819.5 10821.8 10823.4 41906.2 42278.8 42387.0 42407.0 42452.4 3547.4 3617.8 3678.2 3689.2 3771.4 32671.3 33018.3 33039.5 33122.5 33134.8 4024.4 4143.9 4184.8 4192.2 4219.3 307165.0 308046.0 310524.0 310584.0 310643.0 57215.0 63923.7 64327.0 64495.4 64520.4 51027.7 51356.0 51397.5 74134.0 74210.2 18043.3 18327.5 18400.0 18443.9 18470.0 13762.3 14016.4 14038.5 14089.8 14115.0 20579.3 20714.9 20865.8 20868.0 20878.9 7935.5 7940.1 8033.9 8078.2 8141.8 1042.8 1049.9 1104.3 1120.6 1155.3 29718.3 29728.0 29809.0 30236.2 30307.8 1153.6 1188.1 1204.5 1265.2 1326.1 13999.9 15971.6 16318.0 16546.8 16985.0 332852.0 339132.0 339148.0 339317.0 339524.0 55278.2 55294.7 55613.7 55759.0 55827.0 1456.2 1481.3 1485.2 1499.0 1500.1 30862.2 31220.0 31336.2 31557.7 31619.5 50140.5 50215.5 50500.5 50733.2 50800.7 326690.0 327241.0 327254.0 329159.0 329697.0 30544.8 31106.1 33634.8 33872.5 33988.3 39741.6 39872.4 39960.4 40771.0 41105.6 36320.0 36802.0 36870.2 37511.2 37941.8 36885.6 37696.8 37701.2 38116.8 38144.2 33654.0 34252.5 34279.7 40108.2 40661.6 39208.4 41679.2 41733.6 42329.0 42615.0 31024.4 34168.1 36051.6 36332.3 36653.5 12:20:50 INFO - 2016-01-21 12:20:50,391 DEBUG : BROWSER_OUTPUT: 12:20:50 INFO - 2016-01-21 12:20:50,391 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:32660.77 Median:2004.95 stddev:106602.92 (5317.0%) stddev-sans-first:106874.42 12:20:50 INFO - 2016-01-21 12:20:50,391 DEBUG : BROWSER_OUTPUT: Values: 388.3 391.8 392.1 393.0 393.4 14340.4 15985.0 17313.0 18572.9 18576.4 17804.6 18190.6 18472.0 19224.2 19240.6 6139.4 6219.9 6247.3 6248.0 6282.7 4552.7 4692.7 6231.1 6736.7 7000.5 5411.7 6880.9 6923.2 6982.1 6985.2 5458.7 5476.6 5486.2 5530.9 5551.4 5328.9 5444.8 5597.1 5603.6 5604.2 13673.6 13928.4 17105.3 21309.1 21328.4 2114.9 2153.0 2216.5 2249.9 2274.9 10825.9 12143.5 12740.0 13900.9 14227.4 1964.3 2017.6 2031.2 2053.5 2083.2 59784.0 60523.4 60773.1 61634.0 62756.1 10929.0 11009.0 11090.0 11113.2 11191.6 14559.2 14581.6 14638.6 14675.0 14675.9 8125.9 8158.0 8216.9 8405.7 8497.7 7762.4 7830.2 7841.5 7856.5 7866.8 4386.5 4728.4 4748.5 4757.7 4815.8 1753.6 1920.8 1996.2 2013.7 2047.9 926.8 933.3 1002.7 1057.6 1069.2 15657.8 16464.3 17481.5 17513.8 17627.6 1018.1 1030.9 1037.7 1059.9 1088.2 9476.8 9539.4 9758.6 10287.3 10440.8 641715.0 645064.0 658352.0 660236.0 661259.0 79720.2 82756.0 83094.2 84676.6 85566.1 1192.5 1218.8 1220.6 1229.6 1230.6 16129.4 16369.0 16465.7 16572.3 16725.2 35932.0 36486.3 36680.1 37283.8 37956.0 48376.0 49171.3 49500.0 50640.5 52641.0 9308.0 10051.8 10506.8 10543.6 10548.3 12276.0 12336.8 13373.9 15005.8 17388.8 15125.4 15214.3 15314.5 15412.8 15493.0 14943.2 14971.6 15008.3 15045.4 15182.0 15149.4 15311.0 15397.6 15645.2 15844.4 10357.2 10371.1 10432.4 10467.7 10729.4 15306.7 15433.7 15534.5 15663.0 16011.6 12:20:50 INFO - 2016-01-21 12:20:50,391 DEBUG : BROWSER_OUTPUT: 12:20:50 INFO - 2016-01-21 12:20:50,391 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5547.92 Median:6691.81 stddev:2808.98 (42.0%) stddev-sans-first:2800.44 12:20:50 INFO - 2016-01-21 12:20:50,393 DEBUG : BROWSER_OUTPUT: Values: 1507.9 1561.2 1620.8 1741.1 1864.0 6823.4 7790.0 8001.2 8008.8 8018.7 7696.7 7910.6 7912.8 7916.5 7924.6 2001.8 2037.7 2039.9 2286.6 2318.2 2355.5 2394.4 2402.0 2402.9 2404.5 2304.6 2309.2 2313.3 2362.4 2363.7 2318.6 2336.2 2358.1 2367.0 2370.1 2344.2 2348.2 2379.7 2386.2 2388.1 7868.3 7881.4 7916.8 7942.7 7954.0 4764.8 4796.2 4848.1 4852.8 4855.6 3105.5 3129.6 3177.7 3235.3 3260.1 989.9 993.1 1006.1 1033.7 1065.0 10617.7 10619.6 10636.8 10638.1 10654.4 4276.7 4292.7 4294.3 4322.9 4329.7 9152.0 9170.4 9170.9 9172.5 9201.1 4799.4 4807.1 4809.1 4809.7 4829.7 4414.6 4416.3 4420.4 4430.2 4444.8 3331.5 3421.9 3469.3 3696.7 3777.9 1193.8 1194.3 1200.0 1200.5 1206.3 1157.8 1170.5 1214.6 1450.4 1470.1 7487.6 7499.1 7502.5 7662.2 7707.9 1172.8 1183.4 1188.9 1190.9 1199.0 6059.7 6099.5 6116.8 6127.8 6145.0 8980.7 9151.0 9152.4 9193.4 9250.8 8085.2 8172.1 8225.5 8233.2 8298.1 7203.6 7342.9 7398.2 7503.0 7516.8 7596.4 7625.1 7690.3 7694.4 7707.5 8473.8 8495.0 8524.9 8543.4 8708.7 8563.8 8585.7 8614.8 8649.4 8751.9 7574.6 7690.4 7705.0 7752.4 7777.5 6836.1 6968.3 6974.9 7023.4 7802.7 7283.7 7318.6 7330.9 7403.4 7416.9 7201.9 7227.5 7243.1 7299.0 7340.1 7210.0 7231.9 7270.2 7270.3 7341.4 6671.2 6742.5 6742.8 6744.8 6751.5 6664.6 6685.4 6698.3 6699.3 6700.8 12:20:50 INFO - 2016-01-21 12:20:50,393 DEBUG : BROWSER_OUTPUT: 12:20:50 INFO - 2016-01-21 12:20:50,393 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5446.81 Median:6140.21 stddev:1598.78 (26.0%) stddev-sans-first:1557.05 12:20:50 INFO - 2016-01-21 12:20:50,393 DEBUG : BROWSER_OUTPUT: Values: 362.1 369.1 369.5 372.5 378.5 5940.9 6005.8 6006.1 6011.2 6135.9 5876.2 5990.9 6036.3 6050.6 6089.0 4734.5 4742.6 4754.2 4754.5 4758.0 4578.5 4657.9 4661.5 4763.0 4764.2 4691.5 4692.3 4696.8 4704.4 4735.4 4717.6 4719.4 4726.3 4726.8 4739.6 4208.0 4213.6 4223.1 4225.2 4237.1 5714.5 5824.7 5837.3 5861.1 5876.3 5477.6 5478.9 5479.2 5488.2 5493.1 6046.3 6493.9 6497.9 6499.3 6502.9 4479.7 4482.2 4550.2 4562.7 4575.8 6583.5 6650.9 6661.3 6665.1 6670.6 4686.6 4692.3 4694.0 4694.7 4701.0 4754.5 4756.2 4799.5 4867.7 4878.9 5515.7 5531.3 5548.6 5549.9 5550.9 6496.4 6502.7 6507.2 6508.6 6561.2 1710.6 1737.3 1737.9 1738.1 1738.8 1190.3 1193.1 1198.8 1203.4 1219.7 6144.5 6179.8 6180.0 6201.7 6212.7 6584.4 6627.6 6637.0 6637.6 6677.8 6199.8 6200.2 6201.8 6205.2 6212.5 3482.8 3574.2 3586.5 3586.7 3588.4 6730.1 6788.4 6800.1 6801.0 6803.7 6709.6 6732.0 6749.7 6751.5 6752.0 6660.1 6745.9 6749.1 6764.4 6791.7 6510.3 6582.4 6604.4 6643.9 6651.8 6633.8 6649.1 6650.2 6660.4 6671.9 6951.2 6951.8 6955.0 6960.8 6970.4 4759.1 4837.3 4840.0 4858.5 4860.4 6757.5 6758.6 6760.2 6764.3 6773.9 6582.0 6583.9 6584.2 6584.8 6592.1 6430.0 6547.9 6559.5 6560.2 6573.0 6422.7 6461.1 6462.1 6462.2 6470.7 6574.7 6579.3 6584.2 6589.4 6593.9 6288.2 6415.1 6437.9 6439.7 6440.0 12:20:50 INFO - 2016-01-21 12:20:50,393 DEBUG : BROWSER_OUTPUT: 12:20:50 INFO - 2016-01-21 12:20:50,393 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14411.21 Median:4462.40 stddev:16311.34 (365.5%) stddev-sans-first:16325.16 12:20:50 INFO - 2016-01-21 12:20:50,393 DEBUG : BROWSER_OUTPUT: Values: 817.3 840.1 885.2 915.1 917.6 13799.3 13994.6 14041.6 14053.2 14057.6 13723.7 13771.4 13803.4 13826.2 13846.7 734.3 772.1 772.7 772.9 774.3 854.8 934.1 935.6 935.8 936.0 960.6 978.2 978.5 978.7 978.9 758.5 796.9 903.4 958.7 968.7 720.4 723.1 723.2 725.0 726.0 14082.4 14177.4 14195.0 14200.6 14219.7 12504.9 12599.7 12676.1 12792.8 12885.2 10890.7 11111.3 11128.4 11128.7 11163.9 3841.4 3853.7 3871.1 3884.4 3899.6 46933.3 48047.5 48237.8 48448.0 48865.3 10214.7 10219.1 10248.6 10845.0 10888.5 18559.0 18632.1 18659.7 18681.6 18717.2 7627.1 7812.8 7939.9 7972.0 8074.5 4580.1 4598.1 4645.9 4649.7 4705.8 3299.6 3304.8 3318.3 3322.3 3323.4 592.6 605.9 613.8 617.9 622.6 722.9 738.2 746.8 754.4 771.0 9384.4 9609.3 9684.9 9745.4 9986.9 635.7 743.5 753.9 773.1 790.1 4312.7 4440.4 4462.0 4462.8 4654.1 54907.5 63747.4 64210.0 65108.3 65131.3 49717.3 50496.7 51104.0 51405.0 51738.0 46698.8 46759.3 47415.5 47669.8 48112.5 9274.0 9508.9 9676.0 9724.9 9830.9 35871.5 36381.6 36530.8 37528.4 37548.4 40982.4 41269.0 43422.3 45880.5 46311.5 11881.6 11890.2 12084.5 12261.1 12361.9 13362.5 13594.5 13605.0 13670.6 13789.9 11204.0 11383.8 11829.7 12368.9 12427.8 11770.6 11970.5 12081.0 12217.4 12685.2 11094.3 12497.8 12625.2 12703.5 12962.8 10763.5 10816.9 10834.7 11099.2 11405.3 10527.6 10893.6 10948.4 11055.6 11087.8 12:20:50 INFO - 2016-01-21 12:20:50,393 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:20:50 INFO - 2016-01-21 12:20:50,393 DEBUG : BROWSER_OUTPUT: 12:20:51 INFO - 2016-01-21 12:20:51,041 INFO : Browser exited with error code: 0 12:20:51 INFO - 2016-01-21 12:20:51,062 INFO : Completed test dromaeo_css (00:18:31) 12:20:51 INFO - 2016-01-21 12:20:51,062 INFO : Starting test kraken 12:20:51 INFO - 2016-01-21 12:20:51,062 DEBUG : operating with platform_type : linux_ 12:20:51 INFO - 2016-01-21 12:20:51,062 INFO : Initialising browser for kraken test... 12:20:51 INFO - 2016-01-21 12:20:51,068 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmptaNB0d/profile http://localhost:57421/getInfo.html 12:20:55 INFO - 2016-01-21 12:20:55,247 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:20:55 INFO - 2016-01-21 12:20:55,247 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:20:55 INFO - 2016-01-21 12:20:55,247 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 12:20:55 INFO - 2016-01-21 12:20:55,247 DEBUG : BROWSER_OUTPUT: __metrics 12:20:55 INFO - 2016-01-21 12:20:55,396 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] 12:20:56 INFO - 2016-01-21 12:20:56,187 INFO : Browser initialized. 12:20:56 INFO - 2016-01-21 12:20:56,188 INFO : Running cycle 1/1 for kraken test... 12:20:56 INFO - 2016-01-21 12:20:56,188 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmptaNB0d/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:21:07 INFO - 2016-01-21 12:21:07,994 DEBUG : BROWSER_OUTPUT: RSS: Main: 138039296 12:21:07 INFO - 2016-01-21 12:21:07,994 DEBUG : BROWSER_OUTPUT: 12:21:10 INFO - 2016-01-21 12:21:10,437 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57421/tests/kraken/driver.html?testName=audio-beat-detection) 12:21:11 INFO - 2016-01-21 12:21:11,023 DEBUG : BROWSER_OUTPUT: RSS: Main: 167542784 12:21:11 INFO - 2016-01-21 12:21:11,024 DEBUG : BROWSER_OUTPUT: 12:21:13 INFO - 2016-01-21 12:21:13,587 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57421/tests/kraken/driver.html?testName=audio-dft) 12:21:14 INFO - 2016-01-21 12:21:14,161 DEBUG : BROWSER_OUTPUT: RSS: Main: 167215104 12:21:14 INFO - 2016-01-21 12:21:14,161 DEBUG : BROWSER_OUTPUT: 12:21:16 INFO - 2016-01-21 12:21:16,966 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57421/tests/kraken/driver.html?testName=audio-fft) 12:21:17 INFO - 2016-01-21 12:21:17,534 DEBUG : BROWSER_OUTPUT: RSS: Main: 164745216 12:21:17 INFO - 2016-01-21 12:21:17,534 DEBUG : BROWSER_OUTPUT: 12:21:19 INFO - 2016-01-21 12:21:19,520 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57421/tests/kraken/driver.html?testName=audio-oscillator) 12:21:20 INFO - 2016-01-21 12:21:20,081 DEBUG : BROWSER_OUTPUT: RSS: Main: 166195200 12:21:20 INFO - 2016-01-21 12:21:20,082 DEBUG : BROWSER_OUTPUT: 12:21:22 INFO - 2016-01-21 12:21:22,276 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57421/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:21:22 INFO - 2016-01-21 12:21:22,842 DEBUG : BROWSER_OUTPUT: RSS: Main: 162865152 12:21:22 INFO - 2016-01-21 12:21:22,842 DEBUG : BROWSER_OUTPUT: 12:21:25 INFO - 2016-01-21 12:21:25,708 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57421/tests/kraken/driver.html?testName=imaging-darkroom) 12:21:26 INFO - 2016-01-21 12:21:26,276 DEBUG : BROWSER_OUTPUT: RSS: Main: 162353152 12:21:26 INFO - 2016-01-21 12:21:26,276 DEBUG : BROWSER_OUTPUT: 12:21:29 INFO - 2016-01-21 12:21:29,598 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57421/tests/kraken/driver.html?testName=imaging-desaturate) 12:21:30 INFO - 2016-01-21 12:21:30,165 DEBUG : BROWSER_OUTPUT: RSS: Main: 164806656 12:21:30 INFO - 2016-01-21 12:21:30,165 DEBUG : BROWSER_OUTPUT: 12:21:32 INFO - 2016-01-21 12:21:32,904 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57421/tests/kraken/driver.html?testName=json-parse-financial) 12:21:33 INFO - 2016-01-21 12:21:33,472 DEBUG : BROWSER_OUTPUT: RSS: Main: 165257216 12:21:33 INFO - 2016-01-21 12:21:33,473 DEBUG : BROWSER_OUTPUT: 12:21:35 INFO - 2016-01-21 12:21:35,040 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57421/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:21:35 INFO - 2016-01-21 12:21:35,602 DEBUG : BROWSER_OUTPUT: RSS: Main: 171724800 12:21:35 INFO - 2016-01-21 12:21:35,603 DEBUG : BROWSER_OUTPUT: 12:21:37 INFO - 2016-01-21 12:21:37,373 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57421/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:21:37 INFO - 2016-01-21 12:21:37,963 DEBUG : BROWSER_OUTPUT: RSS: Main: 168620032 12:21:37 INFO - 2016-01-21 12:21:37,963 DEBUG : BROWSER_OUTPUT: 12:21:39 INFO - 2016-01-21 12:21:39,983 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57421/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:21:40 INFO - 2016-01-21 12:21:40,556 DEBUG : BROWSER_OUTPUT: RSS: Main: 176181248 12:21:40 INFO - 2016-01-21 12:21:40,557 DEBUG : BROWSER_OUTPUT: 12:21:42 INFO - 2016-01-21 12:21:42,963 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57421/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:21:43 INFO - 2016-01-21 12:21:43,545 DEBUG : BROWSER_OUTPUT: RSS: Main: 181747712 12:21:43 INFO - 2016-01-21 12:21:43,545 DEBUG : BROWSER_OUTPUT: 12:21:46 INFO - 2016-01-21 12:21:46,028 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57421/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:21:46 INFO - 2016-01-21 12:21:46,602 DEBUG : BROWSER_OUTPUT: RSS: Main: 180600832 12:21:46 INFO - 2016-01-21 12:21:46,602 DEBUG : BROWSER_OUTPUT: 12:21:48 INFO - 2016-01-21 12:21:48,232 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57421/tests/kraken/driver.html?testName=ai-astar) 12:21:48 INFO - 2016-01-21 12:21:48,483 DEBUG : BROWSER_OUTPUT: RSS: Main: 237846528 12:21:48 INFO - 2016-01-21 12:21:48,483 DEBUG : BROWSER_OUTPUT: 12:21:48 INFO - 2016-01-21 12:21:48,487 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:21:48 INFO - 2016-01-21 12:21:48,487 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:21:48 INFO - 2016-01-21 12:21:48,487 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:21:48 INFO - 2016-01-21 12:21:48,487 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:21:48 INFO - 2016-01-21 12:21:48,488 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;109;104;109;110;112;99;108;108;109 12:21:48 INFO - 2016-01-21 12:21:48,488 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;121;145;156;127;152;147;120;168;121 12:21:48 INFO - 2016-01-21 12:21:48,488 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;160;160;159;160;162;163;161;162 12:21:48 INFO - 2016-01-21 12:21:48,488 DEBUG : BROWSER_OUTPUT: |3;audio-fft;78;72;72;157;71;71;71;73;150;71 12:21:48 INFO - 2016-01-21 12:21:48,488 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;91;94;134;92;91;92;138;92;94;135 12:21:48 INFO - 2016-01-21 12:21:48,489 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;110;109;109;109;109;109;109;110 12:21:48 INFO - 2016-01-21 12:21:48,489 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;145;146;146;146;146;146;145;146 12:21:48 INFO - 2016-01-21 12:21:48,489 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;95;94;95;95;95 12:21:48 INFO - 2016-01-21 12:21:48,489 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;57;59;57;57;57;57;60;57;55 12:21:48 INFO - 2016-01-21 12:21:48,489 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;63;63;64;63;63;63;63;64;63 12:21:48 INFO - 2016-01-21 12:21:48,489 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;81;82;81;81;84;81;82;83;82;81 12:21:48 INFO - 2016-01-21 12:21:48,489 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;120;116;120;114;127;112;118;111;119;123 12:21:48 INFO - 2016-01-21 12:21:48,489 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;149;135;142;141;143;135;142;143;141;137 12:21:48 INFO - 2016-01-21 12:21:48,489 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;65;66;65;66;65;66;67;65;65 12:21:48 INFO - 2016-01-21 12:21:48,490 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:21:48 INFO - 2016-01-21 12:21:48,490 DEBUG : BROWSER_OUTPUT: __start_cc_report 12:21:48 INFO - 2016-01-21 12:21:48,490 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,929 12:21:48 INFO - 2016-01-21 12:21:48,490 DEBUG : BROWSER_OUTPUT: __end_cc_report 12:21:48 INFO - 2016-01-21 12:21:48,490 DEBUG : BROWSER_OUTPUT: __startTimestamp1453407708485__endTimestamp 12:21:48 INFO - 2016-01-21 12:21:48,490 DEBUG : BROWSER_OUTPUT: 12:21:48 INFO - 2016-01-21 12:21:48,491 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:21:48 INFO - 2016-01-21 12:21:48,492 DEBUG : BROWSER_OUTPUT: Number of tests: 14 12:21:48 INFO - 2016-01-21 12:21:48,492 DEBUG : BROWSER_OUTPUT: 12:21:48 INFO - 2016-01-21 12:21:48,492 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.60 Median:109.00 stddev:3.63 (3.3%) stddev-sans-first:3.84 12:21:48 INFO - 2016-01-21 12:21:48,492 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 104.0 109.0 110.0 112.0 99.0 108.0 108.0 109.0 12:21:48 INFO - 2016-01-21 12:21:48,492 DEBUG : BROWSER_OUTPUT: 12:21:48 INFO - 2016-01-21 12:21:48,492 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.60 Median:148.00 stddev:17.08 (11.5%) stddev-sans-first:17.85 12:21:48 INFO - 2016-01-21 12:21:48,492 DEBUG : BROWSER_OUTPUT: Values: 149.0 121.0 145.0 156.0 127.0 152.0 147.0 120.0 168.0 121.0 12:21:48 INFO - 2016-01-21 12:21:48,492 DEBUG : BROWSER_OUTPUT: 12:21:48 INFO - 2016-01-21 12:21:48,492 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.70 Median:160.50 stddev:1.25 (0.8%) stddev-sans-first:1.30 12:21:48 INFO - 2016-01-21 12:21:48,493 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 160.0 160.0 159.0 160.0 162.0 163.0 161.0 162.0 12:21:48 INFO - 2016-01-21 12:21:48,493 DEBUG : BROWSER_OUTPUT: 12:21:48 INFO - 2016-01-21 12:21:48,493 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.60 Median:71.50 stddev:34.31 (48.0%) stddev-sans-first:36.18 12:21:48 INFO - 2016-01-21 12:21:48,493 DEBUG : BROWSER_OUTPUT: Values: 78.0 72.0 72.0 157.0 71.0 71.0 71.0 73.0 150.0 71.0 12:21:48 INFO - 2016-01-21 12:21:48,493 DEBUG : BROWSER_OUTPUT: 12:21:48 INFO - 2016-01-21 12:21:48,493 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.30 Median:92.00 stddev:21.00 (22.8%) stddev-sans-first:21.63 12:21:48 INFO - 2016-01-21 12:21:48,495 DEBUG : BROWSER_OUTPUT: Values: 91.0 94.0 134.0 92.0 91.0 92.0 138.0 92.0 94.0 135.0 12:21:48 INFO - 2016-01-21 12:21:48,495 DEBUG : BROWSER_OUTPUT: 12:21:48 INFO - 2016-01-21 12:21:48,495 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 12:21:48 INFO - 2016-01-21 12:21:48,495 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 110.0 109.0 109.0 109.0 109.0 109.0 109.0 110.0 12:21:48 INFO - 2016-01-21 12:21:48,495 DEBUG : BROWSER_OUTPUT: 12:21:48 INFO - 2016-01-21 12:21:48,495 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 12:21:48 INFO - 2016-01-21 12:21:48,495 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 145.0 146.0 12:21:48 INFO - 2016-01-21 12:21:48,496 DEBUG : BROWSER_OUTPUT: 12:21:48 INFO - 2016-01-21 12:21:48,496 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 12:21:48 INFO - 2016-01-21 12:21:48,496 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 12:21:48 INFO - 2016-01-21 12:21:48,496 DEBUG : BROWSER_OUTPUT: 12:21:48 INFO - 2016-01-21 12:21:48,496 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.10 Median:57.00 stddev:1.52 (2.7%) stddev-sans-first:1.41 12:21:48 INFO - 2016-01-21 12:21:48,496 DEBUG : BROWSER_OUTPUT: Values: 55.0 57.0 59.0 57.0 57.0 57.0 57.0 60.0 57.0 55.0 12:21:48 INFO - 2016-01-21 12:21:48,496 DEBUG : BROWSER_OUTPUT: 12:21:48 INFO - 2016-01-21 12:21:48,496 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:63.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 12:21:48 INFO - 2016-01-21 12:21:48,496 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 63.0 64.0 63.0 63.0 63.0 63.0 64.0 63.0 12:21:48 INFO - 2016-01-21 12:21:48,496 DEBUG : BROWSER_OUTPUT: 12:21:48 INFO - 2016-01-21 12:21:48,497 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:82.00 stddev:1.03 (1.3%) stddev-sans-first:1.05 12:21:48 INFO - 2016-01-21 12:21:48,497 DEBUG : BROWSER_OUTPUT: Values: 81.0 82.0 81.0 81.0 84.0 81.0 82.0 83.0 82.0 81.0 12:21:48 INFO - 2016-01-21 12:21:48,497 DEBUG : BROWSER_OUTPUT: 12:21:48 INFO - 2016-01-21 12:21:48,497 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:118.00 Median:119.50 stddev:4.94 (4.1%) stddev-sans-first:5.19 12:21:48 INFO - 2016-01-21 12:21:48,497 DEBUG : BROWSER_OUTPUT: Values: 120.0 116.0 120.0 114.0 127.0 112.0 118.0 111.0 119.0 123.0 12:21:48 INFO - 2016-01-21 12:21:48,497 DEBUG : BROWSER_OUTPUT: 12:21:48 INFO - 2016-01-21 12:21:48,497 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.80 Median:142.00 stddev:4.24 (3.0%) stddev-sans-first:3.30 12:21:48 INFO - 2016-01-21 12:21:48,497 DEBUG : BROWSER_OUTPUT: Values: 149.0 135.0 142.0 141.0 143.0 135.0 142.0 143.0 141.0 137.0 12:21:48 INFO - 2016-01-21 12:21:48,497 DEBUG : BROWSER_OUTPUT: 12:21:48 INFO - 2016-01-21 12:21:48,498 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.03 (1.6%) stddev-sans-first:0.73 12:21:48 INFO - 2016-01-21 12:21:48,498 DEBUG : BROWSER_OUTPUT: Values: 68.0 65.0 66.0 65.0 66.0 65.0 66.0 67.0 65.0 65.0 12:21:48 INFO - 2016-01-21 12:21:48,498 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:21:48 INFO - 2016-01-21 12:21:48,498 DEBUG : BROWSER_OUTPUT: 12:21:49 INFO - 2016-01-21 12:21:49,305 INFO : Browser exited with error code: 0 12:21:49 INFO - 2016-01-21 12:21:49,359 INFO : Completed test kraken (00:00:58) 12:21:49 INFO - 2016-01-21 12:21:49,359 INFO : Starting test v8_7 12:21:49 INFO - 2016-01-21 12:21:49,359 DEBUG : operating with platform_type : linux_ 12:21:49 INFO - 2016-01-21 12:21:49,360 INFO : Initialising browser for v8_7 test... 12:21:49 INFO - 2016-01-21 12:21:49,366 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpMPknAw/profile http://localhost:57421/getInfo.html 12:21:53 INFO - 2016-01-21 12:21:53,517 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:21:53 INFO - 2016-01-21 12:21:53,517 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:21:53 INFO - 2016-01-21 12:21:53,517 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 12:21:53 INFO - 2016-01-21 12:21:53,517 DEBUG : BROWSER_OUTPUT: __metrics 12:21:53 INFO - 2016-01-21 12:21:53,699 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] 12:21:54 INFO - 2016-01-21 12:21:54,485 INFO : Browser initialized. 12:21:54 INFO - 2016-01-21 12:21:54,485 INFO : Running cycle 1/1 for v8_7 test... 12:21:54 INFO - 2016-01-21 12:21:54,486 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpMPknAw/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:22:06 INFO - 2016-01-21 12:22:06,259 DEBUG : BROWSER_OUTPUT: RSS: Main: 137560064 12:22:06 INFO - 2016-01-21 12:22:06,259 DEBUG : BROWSER_OUTPUT: 12:22:27 INFO - 2016-01-21 12:22:27,647 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57421/tests/v8_7/run.html (next: http://localhost:57421/tests/v8_7/run.html) 12:22:27 INFO - 2016-01-21 12:22:27,906 DEBUG : BROWSER_OUTPUT: RSS: Main: 426262528 12:22:27 INFO - 2016-01-21 12:22:27,906 DEBUG : BROWSER_OUTPUT: 12:22:27 INFO - 2016-01-21 12:22:27,906 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:22:27 INFO - 2016-01-21 12:22:27,906 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:22:27 INFO - 2016-01-21 12:22:27,906 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:22:27 INFO - 2016-01-21 12:22:27,906 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:22:27 INFO - 2016-01-21 12:22:27,906 DEBUG : BROWSER_OUTPUT: |0;Richards;164.88046166529267 12:22:27 INFO - 2016-01-21 12:22:27,907 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;174.70300489168415 12:22:27 INFO - 2016-01-21 12:22:27,907 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.3296089385475 12:22:27 INFO - 2016-01-21 12:22:27,907 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5117.34693877551 12:22:27 INFO - 2016-01-21 12:22:27,907 DEBUG : BROWSER_OUTPUT: |4;RayTrace;990.0990099009902 12:22:27 INFO - 2016-01-21 12:22:27,907 DEBUG : BROWSER_OUTPUT: |5;Earley;554.6311702717693 12:22:27 INFO - 2016-01-21 12:22:27,907 DEBUG : BROWSER_OUTPUT: |6;Boyer;11021.978021978022 12:22:27 INFO - 2016-01-21 12:22:27,907 DEBUG : BROWSER_OUTPUT: |7;RegExp;24261.904761904763 12:22:27 INFO - 2016-01-21 12:22:27,907 DEBUG : BROWSER_OUTPUT: |8;Splay;535.2564102564103 12:22:27 INFO - 2016-01-21 12:22:27,908 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 12:22:27 INFO - 2016-01-21 12:22:27,908 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:22:27 INFO - 2016-01-21 12:22:27,908 DEBUG : BROWSER_OUTPUT: __startTimestamp1453407747899__endTimestamp 12:22:27 INFO - 2016-01-21 12:22:27,908 DEBUG : BROWSER_OUTPUT: 12:22:27 INFO - 2016-01-21 12:22:27,908 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:22:27 INFO - 2016-01-21 12:22:27,908 DEBUG : BROWSER_OUTPUT: Number of tests: 10 12:22:27 INFO - 2016-01-21 12:22:27,908 DEBUG : BROWSER_OUTPUT: 12:22:27 INFO - 2016-01-21 12:22:27,909 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.88 Median:164.88 stddev:0.00 (0.0%) 12:22:27 INFO - 2016-01-21 12:22:27,909 DEBUG : BROWSER_OUTPUT: Values: 164.9 12:22:27 INFO - 2016-01-21 12:22:27,909 DEBUG : BROWSER_OUTPUT: 12:22:27 INFO - 2016-01-21 12:22:27,909 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:174.70 Median:174.70 stddev:0.00 (0.0%) 12:22:27 INFO - 2016-01-21 12:22:27,909 DEBUG : BROWSER_OUTPUT: Values: 174.7 12:22:27 INFO - 2016-01-21 12:22:27,909 DEBUG : BROWSER_OUTPUT: 12:22:27 INFO - 2016-01-21 12:22:27,909 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.33 Median:279.33 stddev:0.00 (0.0%) 12:22:27 INFO - 2016-01-21 12:22:27,909 DEBUG : BROWSER_OUTPUT: Values: 279.3 12:22:27 INFO - 2016-01-21 12:22:27,909 DEBUG : BROWSER_OUTPUT: 12:22:27 INFO - 2016-01-21 12:22:27,909 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5117.35 Median:5117.35 stddev:0.00 (0.0%) 12:22:27 INFO - 2016-01-21 12:22:27,909 DEBUG : BROWSER_OUTPUT: Values: 5117.3 12:22:27 INFO - 2016-01-21 12:22:27,909 DEBUG : BROWSER_OUTPUT: 12:22:27 INFO - 2016-01-21 12:22:27,909 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:990.10 Median:990.10 stddev:0.00 (0.0%) 12:22:27 INFO - 2016-01-21 12:22:27,909 DEBUG : BROWSER_OUTPUT: Values: 990.1 12:22:27 INFO - 2016-01-21 12:22:27,909 DEBUG : BROWSER_OUTPUT: 12:22:27 INFO - 2016-01-21 12:22:27,910 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:554.63 Median:554.63 stddev:0.00 (0.0%) 12:22:27 INFO - 2016-01-21 12:22:27,910 DEBUG : BROWSER_OUTPUT: Values: 554.6 12:22:27 INFO - 2016-01-21 12:22:27,910 DEBUG : BROWSER_OUTPUT: 12:22:27 INFO - 2016-01-21 12:22:27,910 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:11021.98 Median:11021.98 stddev:0.00 (0.0%) 12:22:27 INFO - 2016-01-21 12:22:27,910 DEBUG : BROWSER_OUTPUT: Values: 11022.0 12:22:27 INFO - 2016-01-21 12:22:27,910 DEBUG : BROWSER_OUTPUT: 12:22:27 INFO - 2016-01-21 12:22:27,910 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24261.90 Median:24261.90 stddev:0.00 (0.0%) 12:22:27 INFO - 2016-01-21 12:22:27,910 DEBUG : BROWSER_OUTPUT: Values: 24261.9 12:22:27 INFO - 2016-01-21 12:22:27,910 DEBUG : BROWSER_OUTPUT: 12:22:27 INFO - 2016-01-21 12:22:27,910 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:535.26 Median:535.26 stddev:0.00 (0.0%) 12:22:27 INFO - 2016-01-21 12:22:27,910 DEBUG : BROWSER_OUTPUT: Values: 535.3 12:22:27 INFO - 2016-01-21 12:22:27,910 DEBUG : BROWSER_OUTPUT: 12:22:27 INFO - 2016-01-21 12:22:27,910 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 12:22:27 INFO - 2016-01-21 12:22:27,910 DEBUG : BROWSER_OUTPUT: Values: 4840.6 12:22:27 INFO - 2016-01-21 12:22:27,910 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:22:27 INFO - 2016-01-21 12:22:27,910 DEBUG : BROWSER_OUTPUT: 12:22:28 INFO - 2016-01-21 12:22:28,753 INFO : Browser exited with error code: 0 12:22:28 INFO - 2016-01-21 12:22:28,772 INFO : Completed test v8_7 (00:00:39) 12:22:28 INFO - 2016-01-21 12:22:28,904 INFO : Completed test suite (00:20:09) 12:22:28 INFO - 2016-01-21 12:22:28,905 DEBUG : Working with test: dromaeo_css 12:22:28 INFO - 2016-01-21 12:22:28,905 DEBUG : Generating results file: dromaeo_css 12:22:28 INFO - 2016-01-21 12:22:28,906 DEBUG : Working with test: kraken 12:22:28 INFO - 2016-01-21 12:22:28,906 DEBUG : Generating results file: kraken 12:22:28 INFO - 2016-01-21 12:22:28,906 INFO : javascript benchmark 12:22:28 INFO - 2016-01-21 12:22:28,906 DEBUG : Working with test: v8_7 12:22:28 INFO - 2016-01-21 12:22:28,906 DEBUG : Generating results file: v8_7 12:22:28 INFO - 2016-01-21 12:22:28,907 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:22:28 INFO - 2016-01-21 12:22:28,975 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,52,35]] 12:22:28 INFO - 2016-01-21 12:22:28,975 DEBUG : process_Request line: dromaeo_css 7390.88 graph.html#tests=[[72,52,35]] 12:22:28 INFO - 2016-01-21 12:22:28,975 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:22:29 INFO - 2016-01-21 12:22:29,008 DEBUG : process_Request line: kraken 1479.50 graph.html#tests=[[232,52,35]] 12:22:29 INFO - 2016-01-21 12:22:29,009 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:22:29 INFO - 2016-01-21 12:22:29,040 DEBUG : process_Request line: v8_7 22903.72 graph.html#tests=[[230,52,35]] 12:22:29 INFO - 2016-01-21 12:22:29,041 INFO : javascript benchmark 12:22:29 INFO - 2016-01-21 12:22:29,045 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3311.288073064291, 3371.79769213847, 3391.1691635549278, 3394.7284217262622, 3419.179396944734, 5483.341998960121, 5484.451554844519, 5496.065668836298, 5515.751791169397, 5570.025245582017, 5533.501984821365, 5575.637583557066, 5589.0, 5589.0219211638005, 5603.60774745768, 4854.538818812225, 5067.670601410906, 5101.54086132248, 5175.301334319856, 5186.196139598368, 5063.316452278932, 5115.079285728568, 5129.589632829383, 5130.333056702623, 5137.537621614054, 5080.491950804927, 5119.385673719159, 5121.718305493197, 5126.436092029874, 5128.512791284822, 4948.1093403187415, 5021.297018417406, 5094.2103973884105, 5101.362329708786, 5115.437301896788, 5048.848534543933, 5052.014857102867, 5086.033653605803, 5120.564751996085, 5145.176771716545, 5123.410807757118, 5226.7909283628605, 5239.842804715865, 5401.216823560601, 5401.3248343957, 5905.763769449217, 5909.8227053188475, 5930.318013428425, 6011.759529618854, 6051.304100028509, 5952.880942381172, 5959.582829201958, 5960.374160713106, 5961.105834124916, 5963.761449542013, 5330.040592693274, 5361.678299302054, 5441.2654291670515, 5499.312585926759, 5545.445455454463, 6039.516838652898, 6080.726367313481, 6083.847903802441, 6084.391560843968, 6112.113743507255, 5886.117082437583, 5889.352171261158, 5913.201717768132, 5915.585908986416, 5918.082697181943, 5750.166225897222, 5840.941590584125, 5973.342932277533, 5985.401459854067, 5986.73059712314, 5805.129230615357, 5856.795012174639, 5873.20711703923, 5876.412358764175, 5915.58590898633, 5893.6463812171405, 5940.762369505172, 5946.494547963472, 5948.613340132878, 5954.047352423591, 4413.3159360299205, 4490.50604433512, 4505.7296562206375, 4522.751248681288, 4592.908141837144, 4058.6753059755147, 4107.712460127762, 4111.013356794348, 4115.794210289474, 4122.711410201317, 5614.29821272341, 5620.803271885465, 5628.0713682242795, 5637.520810731134, 5657.12314591239, 5743.770249190069, 5837.970810146007, 5839.824805255849, 5955.37468565803, 5995.670238136944, 5631.493165615032, 5646.378898321182, 5654.264945557051, 5662.150677398341, 5663.575231857627, 5222.477752224823, 5232.973835130876, 5233.136532472099, 5242.213667949762, 5267.262583238273, 5940.584159108821, 6071.787487437919, 6076.665783381956, 6159.876802464015, 6166.383361663798, 6068.969655151696, 6069.726862291119, 6079.148919151234, 6079.422454866781, 6082.2093127893095, 6090.543209259235, 6140.631562106288, 6150.292716337678, 6158.846028849314, 6223.937760622336, 5706.572007099401, 5743.8564035899435, 5801.448862358069, 5829.183914251923, 5838.0, 5938.703064846827, 6010.939890601213, 6038.456538911519, 6054.061620448838, 6061.0, 5623.690697011703, 5893.70531473416, 5909.52723782105, 5912.290525137011, 5913.46778789911, 5721.914171287517, 5722.914156287576, 5818.272715910512, 5824.621399609012, 5864.149698293809, 5753.971230143822, 5778.739956702043, 5791.768329266951, 5796.594238403271, 5797.159411885337, 5582.218489411567, 5717.399673034438, 5851.853703657443, 5874.412558744091, 5876.0, 5669.574781891292, 5752.424757524214, 5761.769529218785, 5764.48119669215, 5789.131630255495], "unit": "ms", "name": "dojo.html", "value": 5486.580141730491}, {"lowerIsBetter": false, "replicates": [578.6354596604139, 623.0187454758756, 628.7170773152081, 671.8891382921818, 672.8586996730686, 41325.58674413259, 41789.164216715726, 41935.370969435484, 41946.790266048665, 41967.16065678689, 45846.31230531545, 46221.537784622225, 46314.0, 46409.53590464094, 46502.0, 8003.3197178239625, 8107.959460202691, 9092.090790920905, 9262.536873156349, 10408.843867341995, 10778.137748980102, 10833.891661083406, 10880.727981800439, 10906.182036347305, 10910.890891091107, 10842.512086956105, 10928.0, 10941.726456838604, 10948.507317170746, 10950.288231264982, 8217.096119426858, 8399.160083991614, 10911.83632245517, 11100.222984391095, 11101.777964440747, 10649.201309901788, 10798.0, 10819.513121909533, 10821.837672434842, 10823.404712740796, 41906.1618767626, 42278.78860605708, 42387.0, 42407.0, 42452.363214551806, 3547.3792086385038, 3617.8191090445634, 3678.2459595782802, 3689.1699367642336, 3771.396576547739, 32671.346573068404, 33018.33963320744, 33039.50440743391, 33122.50316245258, 33134.83432582822, 4024.416459613369, 4143.937840932388, 4184.832606695729, 4192.203481338536, 4219.261629214875, 307165.0, 308046.0, 310524.0, 310584.0, 310643.0, 57215.0, 63923.68038159779, 64327.0, 64495.35504644894, 64520.354796452375, 51027.74486127545, 51356.0, 51397.48602514002, 74134.0, 74210.1763385389, 18043.278268869362, 18327.450176494727, 18400.0, 18443.907780461013, 18470.0, 13762.311884405939, 14016.439342426189, 14038.508652197124, 14089.788653170212, 14115.0, 20579.279725209544, 20714.896425517774, 20865.79134208669, 20868.0, 20878.895605521877, 7935.523868567789, 7940.0868900076075, 8033.919660803317, 8078.23256790604, 8141.755747327471, 1042.7966546523355, 1049.9002594753485, 1104.2711810205387, 1120.6413947536873, 1155.3241353808116, 29718.257043573652, 29728.0, 29809.0, 30236.24409389783, 30307.848460757996, 1153.6193056291404, 1188.0970462448429, 1204.5302332090491, 1265.2408554867006, 1326.1380102933172, 13999.860001400059, 15971.60070998211, 16318.020918744676, 16546.82680577995, 16985.0, 332852.0, 339132.0, 339148.0, 339317.0, 339524.0, 55278.17082743762, 55294.72352638291, 55613.721931390086, 55759.0, 55827.0, 1456.1699831096173, 1481.3037872199834, 1485.2499487758612, 1498.9700205995819, 1500.0699566268984, 30862.228444289074, 31220.0, 31336.216594584406, 31557.684423155475, 31619.525707113953, 50140.49859501358, 50215.497845022546, 50500.49499504958, 50733.23900141575, 50800.74599626978, 326690.0, 327241.0, 327254.0, 329159.0, 329697.0, 30544.847275763477, 31106.066817995496, 33634.831825840716, 33872.49191262084, 33988.32023359568, 39741.60258397379, 39872.401913971895, 39960.40059399053, 40771.0, 41105.588944110175, 36320.0, 36802.0, 36870.23473239464, 37511.24977500489, 37941.81029094837, 36885.63114368822, 37696.811515942245, 37701.24597508089, 38116.809415952746, 38144.237115258096, 33654.0, 34252.48621270633, 34279.65720342765, 40108.197836042535, 40661.59338406696, 39208.411873821344, 41679.1664166721, 41733.58266417297, 42329.0, 42615.0, 31024.379512410076, 34168.14579635529, 36051.63948360483, 36332.27335453329, 36653.45019824758], "unit": "ms", "name": "ext.html", "value": 20382.546240725856}, {"lowerIsBetter": false, "replicates": [388.273927755098, 391.8491380818384, 392.0610138717771, 393.0, 393.3823896482523, 14340.426382944695, 15985.0, 17313.0, 18572.90713546432, 18576.442706718786, 17804.554886127855, 18190.636187276246, 18472.0, 19224.231030758787, 19240.61518769624, 6139.38606139387, 6219.906701399483, 6247.312795592481, 6247.968760156193, 6282.717277722513, 4552.726836389811, 4692.718436893797, 6231.127642130105, 6736.730530778763, 7000.474964377667, 5411.675299482024, 6880.862382752342, 6923.20383155936, 6982.057422248007, 6985.196702379215, 5458.699771512565, 5476.58925580583, 5486.231927530149, 5530.861728456781, 5551.3893471718075, 5328.92006619901, 5444.829361687256, 5597.076482380402, 5603.60774745768, 5604.215409842625, 13673.589792306248, 13928.442862285496, 17105.31578736849, 21309.147634094617, 21328.36014919555, 2114.873107613548, 2152.956940861174, 2216.5012872103807, 2249.887505624712, 2274.943126421836, 10825.945870270676, 12143.514259429612, 12740.0, 13900.860991390058, 14227.430902763877, 1964.3106928041873, 2017.5863947890648, 2031.1672214392177, 2053.5071582820165, 2083.197968782015, 59784.0, 60523.39476605177, 60773.08840367398, 61634.0, 62756.05865912015, 10929.0, 11009.0, 11090.0, 11113.22207445471, 11191.608293709682, 14559.199244041467, 14581.635459113608, 14638.560843174722, 14675.046122002035, 14675.926620367043, 8125.9187408126345, 8157.95921020403, 8216.917830821734, 8405.663773449116, 8497.660093596187, 7762.417818663623, 7830.177831327743, 7841.490303130278, 7856.489328193649, 7866.842663146819, 4386.451693538308, 4728.38530990969, 4748.525147485224, 4757.714537127784, 4815.783289751969, 1753.626016879382, 1920.750302460699, 1996.2314508914174, 2013.6677448221174, 2047.9283225087052, 926.7822061815468, 933.286036182321, 1002.684154491333, 1057.582255009267, 1069.187417562658, 15657.843421565638, 16464.341426343053, 17481.47555573335, 17513.824861751218, 17627.647447051244, 1018.1244130048152, 1030.8814486334018, 1037.6679462572056, 1059.9400599400599, 1088.2491081153978, 9476.810463790824, 9539.427634341962, 9758.560864761102, 10287.331323464101, 10440.843387349196, 641715.0, 645064.0, 658352.0, 660236.0, 661259.0, 79720.20279797127, 82756.0, 83094.16905830865, 84676.57661711652, 85566.14433855706, 1192.540871764377, 1218.8232706257543, 1220.6216073017392, 1229.59423390281, 1230.6369620961839, 16129.435469758502, 16369.0, 16465.67068658644, 16572.254248558602, 16725.247363868413, 35932.0, 36486.27027459489, 36680.08299792527, 37283.81358093192, 37956.0, 48376.0, 49171.262431062845, 49500.0, 50640.49359506358, 52641.0, 9307.976122626642, 10051.798964020825, 10506.842397363893, 10543.630972915911, 10548.261621686408, 12276.0, 12336.81494777561, 13373.866261337262, 15005.77491337609, 17388.82611173923, 15125.39498420051, 15214.315355809236, 15314.463993760164, 15412.768808468107, 15493.0, 14943.178125203218, 14971.550853474413, 15008.324625392102, 15045.398184072514, 15182.0, 15149.394024238907, 15311.0, 15397.615059623151, 15645.217739112772, 15844.366225351318, 10357.17142628603, 10371.066604005675, 10432.426216558162, 10467.738306542398, 10729.409882456539, 15306.693866122838, 15433.69132617319, 15534.533963981099, 15663.0, 16011.599710006876], "unit": "ms", "name": "jquery.html", "value": 9496.85203408857}, {"lowerIsBetter": false, "replicates": [1507.901986370886, 1561.2427972433372, 1620.7568864670304, 1741.1294352823588, 1864.012073600992, 6823.385895269431, 7789.961050194748, 8001.159878212791, 8008.839823203532, 8018.67925282988, 7696.692132314701, 7910.604469776517, 7912.7626171214815, 7916.485428447147, 7924.564148971805, 2001.7898120697262, 2037.6943458481273, 2039.9082041308102, 2286.6455699366625, 2318.246569864792, 2355.458244603742, 2394.3774618599114, 2401.987990060047, 2402.8678422686744, 2404.507076049406, 2304.642780369037, 2309.2264091529273, 2313.3175713164655, 2362.4093976505874, 2363.7163540375095, 2318.6406107053435, 2336.2056900653774, 2358.080348664005, 2366.9644955325684, 2370.0756704885107, 2344.15610380263, 2348.2250857217077, 2379.654950032253, 2386.1529157149307, 2388.056717596557, 7868.25251601097, 7881.448298619099, 7916.80207994799, 7942.722004729864, 7953.965984421988, 4764.8332308369045, 4796.184648609745, 4848.10310092634, 4852.781624826891, 4855.6115510759055, 3105.534169874514, 3129.640091389474, 3177.7457803375905, 3235.288236587947, 3260.1197676627417, 989.9406035637885, 993.056596233581, 1006.059334522224, 1033.7105610429091, 1064.9627263045757, 10617.73455663607, 10619.57521699131, 10636.787264254748, 10638.095761860252, 10654.4140072296, 4276.657867370665, 4292.720973136736, 4294.334378171388, 4322.891927701769, 4329.696921215547, 9151.993280739114, 9170.358074934691, 9170.86243706345, 9172.541372931326, 9201.125893040151, 4799.352087468217, 4807.086653535817, 4809.134355815917, 4809.711417314972, 4829.710217386898, 4414.624756895636, 4416.33754936762, 4420.3590479380955, 4430.202563538594, 4444.822207111744, 3331.466965285591, 3421.9486707699402, 3469.340825243196, 3696.722745794076, 3777.9433308500393, 1193.7552801675636, 1194.2714943884223, 1200.0459634590522, 1200.4537935239446, 1206.252123683322, 1157.779763677897, 1170.5142365918048, 1214.5991822698488, 1450.4198320671815, 1470.0811992504684, 7487.625618719042, 7499.100107987077, 7502.549847009197, 7662.233776622294, 7707.92292077072, 1172.7888979983513, 1183.4319526627096, 1188.9227200231958, 1190.892819646236, 1198.9628970940073, 6059.666718330534, 6099.451049405575, 6116.755329786759, 6127.84680382994, 6145.0, 8980.730578082668, 9151.0, 9152.405093668891, 9193.356465047516, 9250.76873078165, 8085.231902969209, 8172.141925097898, 8225.506469611842, 8233.217844304781, 8298.087210406851, 7203.567785932861, 7342.853142937218, 7398.223186565333, 7502.962485187648, 7516.774496765106, 7596.354309883722, 7625.084989801259, 7690.307872291521, 7694.4229182810395, 7707.460477766503, 8473.83052338962, 8495.0, 8524.91475085266, 8543.359248056546, 8708.69519566812, 8563.82872342562, 8585.699500517452, 8614.78463038404, 8649.394542381973, 8751.91248087511, 7574.5833979129475, 7690.423218258541, 7705.036870391201, 7752.3798096153305, 7777.494462859896, 6836.145481814773, 6968.338007889242, 6974.930250697428, 7023.367896889305, 7802.726904558313, 7283.7450689225625, 7318.597477138808, 7330.890036649348, 7403.444741644291, 7416.888746668912, 7201.855962880818, 7227.530210536298, 7243.130824301117, 7299.0, 7340.1191856975365, 7210.026646402668, 7231.891521627074, 7270.163931147986, 7270.309320614533, 7341.412686985135, 6671.166104236971, 6742.5280230383405, 6742.7977160683295, 6744.763933262312, 6751.493637977073, 6664.600123992576, 6685.364890335411, 6698.263191049078, 6699.29657385987, 6700.765473208415], "unit": "ms", "name": "mootools.html", "value": 4590.308932121258}, {"lowerIsBetter": false, "replicates": [362.0983750461349, 369.1122849546839, 369.46612145449825, 372.4989888599832, 378.51361001113577, 5940.851478713023, 6005.8198254052395, 6006.099085137226, 6011.188489553914, 6135.938640613593, 5876.177335173079, 5990.850228744283, 6036.275646922375, 6050.606710563821, 6088.969555152229, 4734.526547345264, 4742.596879265267, 4754.239321708527, 4754.500777418356, 4757.976210118962, 4578.519255478177, 4657.930131048037, 4661.510541393156, 4763.0, 4764.237721964487, 4691.5073917238715, 4692.272697731857, 4696.788644510988, 4704.411948506437, 4735.360726301939, 4717.57541821236, 4719.43366795985, 4726.314684370781, 4726.76366181693, 4739.597134243593, 4207.978960105211, 4213.599708027763, 4223.134257477241, 4225.154969006211, 4237.067845074079, 5714.51426628737, 5824.679642619654, 5837.270341207349, 5861.120831875252, 5876.3242227144, 5477.616566840323, 5478.863028424321, 5479.150731636602, 5488.17677348401, 5493.066178749622, 6046.2744470663365, 6493.870122597569, 6497.870042599122, 6499.317571654956, 6502.93497065028, 4479.664025198123, 4482.170798402273, 4550.249208880531, 4562.726236425825, 4575.771211439448, 6583.506237032242, 6650.900236496456, 6661.333866613349, 6665.066890635315, 6670.6331151786635, 4686.554777296152, 4692.272697731857, 4693.9765301173275, 4694.671373003857, 4700.952990470051, 4754.5245475452175, 4756.191447453941, 4799.496052914464, 4867.707937523689, 4878.853634390974, 5515.7242137892945, 5531.2809334787025, 5548.55611551075, 5549.944500554942, 5550.91673624896, 6496.35036496356, 6502.739890404426, 6507.1540699709685, 6508.60948343101, 6561.179852518435, 1710.606560491069, 1737.2703464544923, 1737.878348515617, 1738.1396208876688, 1738.8435040846384, 1190.3096204201543, 1193.0574845871665, 1198.844150260478, 1203.4283715235192, 1219.6584956212275, 6144.477719393813, 6179.783707570214, 6179.980303250003, 6201.658908760061, 6212.658303793335, 6584.440322572635, 6627.635480048547, 6636.96681516599, 6637.568558043712, 6677.799666010027, 6199.814005579749, 6200.193974783249, 6201.844953876189, 6205.162303089025, 6212.534059945522, 3482.7562070655317, 3574.2136729919375, 3586.5158203642172, 3586.748927575097, 3588.3899737044767, 6730.091437655854, 6788.389044985975, 6800.115984921928, 6801.047853300542, 6803.65981700913, 6709.563878347989, 6731.966340168267, 6749.662516874137, 6751.459883209332, 6751.966240168866, 6660.100886380276, 6745.942487541347, 6749.122614060141, 6764.391204791593, 6791.660416979132, 6510.316416776171, 6582.3746744059235, 6604.405603495708, 6643.900341494978, 6651.800445986628, 6633.800985970429, 6649.0691303218555, 6650.168728908887, 6660.400563949268, 6671.899921501278, 6951.16586009682, 6951.756688515878, 6955.0, 6960.79117626472, 6970.442364610921, 4759.0719809636785, 4837.346958160603, 4839.975800120977, 4858.538438848445, 4860.416749990024, 6757.493188011017, 6758.628275444898, 6760.18877734675, 6764.255931847394, 6773.89839152423, 6582.045603387578, 6583.868322633616, 6584.176977877765, 6584.8024559263295, 6592.143021407186, 6430.0, 6547.901781473377, 6559.4752419807255, 6560.212774467094, 6573.0, 6422.710978005907, 6461.127747753993, 6462.063000864942, 6462.159919210472, 6470.676466176578, 6574.704138313692, 6579.309172537006, 6584.242812076673, 6589.374009469093, 6593.868122637425, 6288.245410550763, 6415.069814876723, 6437.903431448625, 6439.67801609927, 6440.0], "unit": "ms", "name": "prototype.html", "value": 4942.184903206312}, {"lowerIsBetter": false, "replicates": [817.3338728935913, 840.0591337701775, 885.1502557544761, 915.1306259053898, 917.6100157433092, 13799.310034498272, 13994.580162595114, 14041.578752637437, 14053.156810591372, 14057.578272651825, 13723.725525489484, 13771.380287887045, 13803.447862085504, 13826.17042977422, 13846.653833654138, 734.258399016994, 772.0966469230985, 772.7102336623766, 772.8531578999972, 774.3069952392606, 854.7905763088052, 934.0799312677008, 935.5976929920132, 935.8455854783953, 936.0405584276103, 960.5773459677754, 978.1538968792015, 978.5449765858874, 978.7112801723473, 978.9021097890189, 758.5031804168278, 796.928276455116, 903.4443817052531, 958.6788425877339, 968.7287559483356, 720.3948683106164, 723.0925188887965, 723.1611330856182, 724.9669221359542, 726.0162479839846, 14082.43670253199, 14177.43290268388, 14195.0, 14200.573982780534, 14219.715605687828, 12504.874951250462, 12599.748005039848, 12676.112672112955, 12792.808107878389, 12885.226886386847, 10890.727731806768, 11111.277766945122, 11128.443577821077, 11128.721781955435, 11163.944180279128, 3841.42378643203, 3853.6531712146043, 3871.1483473635753, 3884.3784994401044, 3899.590542992973, 46933.29600056002, 48047.51952480465, 48237.75881120607, 48448.0, 48865.26702099471, 10214.693559193236, 10219.123804200759, 10248.64129755455, 10845.0, 10888.510017049251, 18559.0, 18632.068396580118, 18659.720104198448, 18681.626367472847, 18717.15772790228, 7627.093226693327, 7812.765617031499, 7939.880901786478, 7971.960140199262, 8074.515529068275, 4580.083983203373, 4598.1033698429155, 4645.883852903705, 4649.651276154302, 4705.788239529229, 3299.637039925606, 3304.801711897294, 3318.303156337196, 3322.302316513511, 3323.401787678241, 592.5911121326271, 605.9060845568944, 613.7575657615217, 617.9196704428485, 622.5548732656173, 722.9349358557755, 738.1806195123409, 746.7746116572536, 754.4492520460095, 770.9730159444392, 9384.436933783858, 9609.275177862148, 9684.854727178958, 9745.415275083376, 9986.850197247048, 635.6662752055115, 743.5278598090251, 753.852998665255, 773.1109224391994, 790.1071788878645, 4312.676549258818, 4440.400545926259, 4461.955380446219, 4462.843800466968, 4654.069186162781, 54907.45092549024, 63747.36252637414, 64210.0, 65108.348916511175, 65131.34868651348, 49717.25424118641, 50496.747516262185, 51104.0, 51405.0, 51738.0, 46698.76650616725, 46759.29861052087, 47415.52584474111, 47669.76165119222, 48112.5188748115, 9273.953630231807, 9508.904910951078, 9675.984021677623, 9724.854127188239, 9830.901690983284, 35871.46192807058, 36381.63618363783, 36530.8173459131, 37528.437073443376, 37548.436773448964, 40982.38526422166, 41269.0, 43422.34866476942, 45880.541194588965, 46311.53688463207, 11881.5841445549, 11890.16768826174, 12084.516619335129, 12261.080418968435, 12361.876381236072, 13362.532311369056, 13594.456221751414, 13605.0, 13670.589882303548, 13789.86210137926, 11204.0, 11383.829242561535, 11829.704257393634, 12368.876311236772, 12427.813582796447, 11770.588029419272, 11970.521179152736, 12081.0, 12217.389130543259, 12685.175463594836, 11094.334339939629, 12497.750044999231, 12625.179363341353, 12703.491860325854, 12962.805557916827, 10763.51564179598, 10816.891831081588, 10834.729131771768, 11099.223054386439, 11405.315681059163, 10527.631532896312, 10893.618723344645, 10948.397838118979, 11055.613053543088, 11087.778244434905], "unit": "ms", "name": "yui.html", "value": 6764.513551641964}], "name": "dromaeo_css", "value": 7390.882015893413}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 109.0, 104.0, 109.0, 110.0, 112.0, 99.0, 108.0, 108.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 107.6}, {"lowerIsBetter": true, "replicates": [149.0, 121.0, 145.0, 156.0, 127.0, 152.0, 147.0, 120.0, 168.0, 121.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.6}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 160.0, 160.0, 159.0, 160.0, 162.0, 163.0, 161.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 160.7}, {"lowerIsBetter": true, "replicates": [78.0, 72.0, 72.0, 157.0, 71.0, 71.0, 71.0, 73.0, 150.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 88.6}, {"lowerIsBetter": true, "replicates": [91.0, 94.0, 134.0, 92.0, 91.0, 92.0, 138.0, 92.0, 94.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 105.3}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 94.9}, {"lowerIsBetter": true, "replicates": [55.0, 57.0, 59.0, 57.0, 57.0, 57.0, 57.0, 60.0, 57.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 57.1}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 63.0, 64.0, 63.0, 63.0, 63.0, 63.0, 64.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.2}, {"lowerIsBetter": true, "replicates": [81.0, 82.0, 81.0, 81.0, 84.0, 81.0, 82.0, 83.0, 82.0, 81.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 81.8}, {"lowerIsBetter": true, "replicates": [120.0, 116.0, 120.0, 114.0, 127.0, 112.0, 118.0, 111.0, 119.0, 123.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 118.0}, {"lowerIsBetter": true, "replicates": [149.0, 135.0, 142.0, 141.0, 143.0, 135.0, 142.0, 143.0, 141.0, 137.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 140.8}, {"lowerIsBetter": true, "replicates": [68.0, 65.0, 66.0, 65.0, 66.0, 65.0, 66.0, 67.0, 65.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.8}], "name": "kraken", "value": 1479.4999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.88046166529267], "unit": "score", "name": "Richards", "value": 214.10663000000005}, {"lowerIsBetter": false, "replicates": [174.70300489168415], "unit": "score", "name": "DeltaBlue", "value": 378.4594320000001}, {"lowerIsBetter": false, "replicates": [279.3296089385475], "unit": "score", "name": "Encrypt", "value": 952.9279799999998}, {"lowerIsBetter": false, "replicates": [5117.34693877551], "unit": "score", "name": "Decrypt", "value": 52.01542971086741}, {"lowerIsBetter": false, "replicates": [990.0990099009902], "unit": "score", "name": "RayTrace", "value": 747.3888899999998}, {"lowerIsBetter": false, "replicates": [554.6311702717693], "unit": "score", "name": "Earley", "value": 1201.6327890000002}, {"lowerIsBetter": false, "replicates": [11021.978021978022], "unit": "score", "name": "Boyer", "value": 60.46673280159525}, {"lowerIsBetter": false, "replicates": [24261.904761904763], "unit": "score", "name": "RegExp", "value": 37.547958783120734}, {"lowerIsBetter": false, "replicates": [535.2564102564103], "unit": "score", "name": "Splay", "value": 152.24665868263472}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 22903.720091462394}]} 12:22:29 INFO - RETURN: dromaeo_css: 7390.88 12:22:29 INFO - RETURN: kraken: 1479.50 12:22:29 INFO - RETURN: v8_7: 22903.72 12:22:29 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,52,35]]", "result": "22903.72"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,52,35]]", "result": "1479.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,52,35]]", "result": "7390.88"}}} 12:22:29 INFO - Return code: 0 12:22:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 12:22:29 INFO - # TBPL SUCCESS # 12:22:29 INFO - Running post-action listener: _resource_record_post_action 12:22:29 INFO - Running post-run listener: _resource_record_post_run 12:22:30 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 0; Write bytes: 502374400; Read time: 0; Write time: 419968 12:22:30 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 237793280; Read time: 0; Write time: 297996 12:22:30 INFO - run-tests - Wall time: 1211s; CPU: 13.0%; Read bytes: 0; Write bytes: 264581120; Read time: 0; Write time: 121972 12:22:30 INFO - Running post-run listener: _upload_blobber_files 12:22:30 INFO - Blob upload gear active. 12:22:30 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 12:22:30 INFO - Copying logs to upload dir... 12:22:30 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1274.921339 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-01-21 12:22:28.953907) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-21 12:22:28.957000) ========= 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=574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009963 build_url: 'https://queue.taskcluster.net/v1/task/FOsifgnaQnWJ1BFJw8AZ2w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-21 12:22:29.008775) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 12:22:29.009120) ========= 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=574dc5aafe09851ec43d388c000001b6-1453406289.522548-675726989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004538 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 12:22:29.058311) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-21 12:22:29.058645) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-21 12:22:29.058942) ========= ========= Total master_lag: 0.25 =========