builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1453485479.6 results: success (0) buildid: 20160122072128 builduid: b5bac9d27c914ec0832868b7f26cf391 revision: 829ed713fa9a047575d96c6943d86031bd78ab9f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-22 09:57:59.603919) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-22 09:57:59.604414) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-22 09:57:59.604757) ========= 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=c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006298 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-22 09:57:59.628195) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-22 09:57:59.628538) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-22 09:57:59.641953) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:57:59.642275) ========= 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=c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004484 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:57:59.691541) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-22 09:57:59.691905) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-22 09:57:59.692379) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:57:59.692757) ========= 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=c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-22 09:57:59-- 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% 34.7M=0s 2016-01-22 09:58:00 (34.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.164602 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:57:59.870155) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:57:59.870507) ========= 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=c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023273 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:57:59.920993) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:57:59.921583) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 829ed713fa9a047575d96c6943d86031bd78ab9f --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 829ed713fa9a047575d96c6943d86031bd78ab9f --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=c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-22 09:58:00,145 truncating revision to first 12 chars 2016-01-22 09:58:00,145 Setting DEBUG logging. 2016-01-22 09:58:00,145 attempt 1/10 2016-01-22 09:58:00,145 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/829ed713fa9a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-22 09:58:00,641 unpacking tar archive at: mozilla-aurora-829ed713fa9a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.806452 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 09:58:00.741764) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-22 09:58:00.742162) ========= echo 829ed713fa9a047575d96c6943d86031bd78ab9f in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'829ed713fa9a047575d96c6943d86031bd78ab9f'] 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=c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 829ed713fa9a047575d96c6943d86031bd78ab9f program finished with exit code 0 elapsedTime=0.003612 script_repo_revision: '829ed713fa9a047575d96c6943d86031bd78ab9f' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-22 09:58:00.791020) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-22 09:58:00.791369) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-22 09:58:00.802109) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-01-22 09:58:00.802453) ========= /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=c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:58:01 INFO - MultiFileLogger online at 20160122 09:58:01 in /builds/slave/test-pgo 09:58:01 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 09:58:01 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:58:01 INFO - {'append_to_log': False, 09:58:01 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:58:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:58:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:58:01 INFO - 'branch': 'Mozilla-Aurora', 09:58:01 INFO - 'buildbot_json_path': 'buildprops.json', 09:58:01 INFO - 'config_files': ('talos/linux_config.py',), 09:58:01 INFO - 'default_actions': ('clobber', 09:58:01 INFO - 'read-buildbot-config', 09:58:01 INFO - 'download-and-extract', 09:58:01 INFO - 'populate-webroot', 09:58:01 INFO - 'create-virtualenv', 09:58:01 INFO - 'install', 09:58:01 INFO - 'run-tests'), 09:58:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:58:01 INFO - 'download_minidump_stackwalk': True, 09:58:01 INFO - 'download_symbols': 'ondemand', 09:58:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:58:01 INFO - 'tooltool.py': '/tools/tooltool.py', 09:58:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:58:01 INFO - '/tools/misc-python/virtualenv.py')}, 09:58:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:58:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:58:01 INFO - 'installer_path': 'installer.exe', 09:58:01 INFO - 'log_level': 'info', 09:58:01 INFO - 'log_name': 'talos', 09:58:01 INFO - 'log_to_console': True, 09:58:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:58:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:58:01 INFO - 'opt_config_files': (), 09:58:01 INFO - 'pip_index': False, 09:58:01 INFO - 'sps_profile': False, 09:58:01 INFO - 'sps_profile_interval': 0, 09:58:01 INFO - 'suite': 'dromaeojs', 09:58:01 INFO - 'system_bits': '32', 09:58:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:58:01 INFO - 'title': 'talos-linux64-ix-004', 09:58:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:58:01 INFO - 'use_talos_json': True, 09:58:01 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:58:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:58:01 INFO - 'work_dir': 'build'} 09:58:01 INFO - ##### 09:58:01 INFO - ##### Running clobber step. 09:58:01 INFO - ##### 09:58:01 INFO - Running pre-action listener: _resource_record_pre_action 09:58:01 INFO - Running main action method: clobber 09:58:01 INFO - rmtree: /builds/slave/test-pgo/build 09:58:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:58:01 INFO - Running post-action listener: _resource_record_post_action 09:58:01 INFO - ##### 09:58:01 INFO - ##### Running read-buildbot-config step. 09:58:01 INFO - ##### 09:58:01 INFO - Running pre-action listener: _resource_record_pre_action 09:58:01 INFO - Running main action method: read_buildbot_config 09:58:01 INFO - Using buildbot properties: 09:58:01 INFO - { 09:58:01 INFO - "properties": { 09:58:01 INFO - "buildnumber": 213, 09:58:01 INFO - "product": "firefox", 09:58:01 INFO - "script_repo_revision": "production", 09:58:01 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:58:01 INFO - "repository": "", 09:58:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:58:01 INFO - "buildid": "20160122072128", 09:58:01 INFO - "slavename": "talos-linux64-ix-004", 09:58:01 INFO - "pgo_build": "True", 09:58:01 INFO - "basedir": "/builds/slave/test-pgo", 09:58:01 INFO - "project": "", 09:58:01 INFO - "platform": "ubuntu64_hw", 09:58:01 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:58:01 INFO - "slavebuilddir": "test", 09:58:01 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:58:01 INFO - "branch": "mozilla-aurora", 09:58:01 INFO - "repo_path": "releases/mozilla-aurora", 09:58:01 INFO - "stage_platform": "linux64-pgo", 09:58:01 INFO - "builduid": "b5bac9d27c914ec0832868b7f26cf391", 09:58:01 INFO - "revision": "829ed713fa9a047575d96c6943d86031bd78ab9f" 09:58:01 INFO - }, 09:58:01 INFO - "sourcestamp": { 09:58:01 INFO - "repository": "", 09:58:01 INFO - "hasPatch": false, 09:58:01 INFO - "project": "", 09:58:01 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 09:58:01 INFO - "changes": [ 09:58:01 INFO - { 09:58:01 INFO - "category": null, 09:58:01 INFO - "files": [ 09:58:01 INFO - { 09:58:01 INFO - "url": null, 09:58:01 INFO - "name": "https://queue.taskcluster.net/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2" 09:58:01 INFO - } 09:58:01 INFO - ], 09:58:01 INFO - "repository": "", 09:58:01 INFO - "rev": "829ed713fa9a047575d96c6943d86031bd78ab9f", 09:58:01 INFO - "who": "cbook@mozilla.com", 09:58:01 INFO - "when": 1453485414, 09:58:01 INFO - "number": 7501782, 09:58:01 INFO - "comments": "Bug 1241294 - \"Update E10S_AUTOSTART_STATUS telemetry probe (and rename it to E10S_STATUS)\". r=felipc a=sylvestre", 09:58:01 INFO - "project": "", 09:58:01 INFO - "at": "Fri 22 Jan 2016 09:56:54", 09:58:01 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 09:58:01 INFO - "revlink": "", 09:58:01 INFO - "properties": [ 09:58:01 INFO - [ 09:58:01 INFO - "buildid", 09:58:01 INFO - "20160122072128", 09:58:01 INFO - "Change" 09:58:01 INFO - ], 09:58:01 INFO - [ 09:58:01 INFO - "builduid", 09:58:01 INFO - "b5bac9d27c914ec0832868b7f26cf391", 09:58:01 INFO - "Change" 09:58:01 INFO - ], 09:58:01 INFO - [ 09:58:01 INFO - "pgo_build", 09:58:01 INFO - "True", 09:58:01 INFO - "Change" 09:58:01 INFO - ] 09:58:01 INFO - ], 09:58:01 INFO - "revision": "829ed713fa9a047575d96c6943d86031bd78ab9f" 09:58:01 INFO - } 09:58:01 INFO - ], 09:58:01 INFO - "revision": "829ed713fa9a047575d96c6943d86031bd78ab9f" 09:58:01 INFO - } 09:58:01 INFO - } 09:58:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2. 09:58:01 INFO - Running post-action listener: _resource_record_post_action 09:58:01 INFO - ##### 09:58:01 INFO - ##### Running download-and-extract step. 09:58:01 INFO - ##### 09:58:01 INFO - Running pre-action listener: _resource_record_pre_action 09:58:01 INFO - Running main action method: download_and_extract 09:58:01 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:01 INFO - https://queue.taskcluster.net/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:58:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/test_packages.json 09:58:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/test_packages.json 09:58:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:58:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:58:02 INFO - Downloaded 1302 bytes. 09:58:02 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:58:02 INFO - Using the following test package requirements: 09:58:02 INFO - {u'common': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 09:58:02 INFO - u'cppunittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:58:02 INFO - u'firefox-45.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:58:02 INFO - u'jittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:58:02 INFO - u'jsshell-linux-x86_64.zip'], 09:58:02 INFO - u'mochitest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:58:02 INFO - u'firefox-45.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:58:02 INFO - u'mozbase': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 09:58:02 INFO - u'reftest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:58:02 INFO - u'firefox-45.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:58:02 INFO - u'talos': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:58:02 INFO - u'firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:58:02 INFO - u'web-platform': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:58:02 INFO - u'firefox-45.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:58:02 INFO - u'webapprt': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 09:58:02 INFO - u'xpcshell': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:58:02 INFO - u'firefox-45.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:58:02 INFO - Downloading packages: [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:02 INFO - https://queue.taskcluster.net/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:58:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:58:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:58:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/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 09:58:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/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 09:58:03 INFO - Downloaded 23158751 bytes. 09:58:03 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 09:58:03 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:58:03 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 09:58:04 INFO - Return code: 0 09:58:04 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 09:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:04 INFO - https://queue.taskcluster.net/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:58:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:58:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:58:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/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 09:58:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/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 09:58:05 INFO - Downloaded 23158751 bytes. 09:58:05 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 09:58:05 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:58:05 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 09:58:06 INFO - Return code: 0 09:58:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:06 INFO - https://queue.taskcluster.net/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:58:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 09:58:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 09:58:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/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 09:58:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/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 09:58:08 INFO - Downloaded 11132038 bytes. 09:58:08 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 09:58:08 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 09:58:08 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 09:58:08 INFO - Return code: 0 09:58:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:08 INFO - https://queue.taskcluster.net/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:58:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:58:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:58:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/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 09:58:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dspZIrreQbuDI2eT32GVtA/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 09:58:10 INFO - Downloaded 58011750 bytes. 09:58:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:58:10 INFO - mkdir: /builds/slave/test-pgo/properties 09:58:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:58:10 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:58:10 INFO - Contents: 09:58:10 INFO - build_url:https://queue.taskcluster.net/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:58:10 INFO - Running post-action listener: _resource_record_post_action 09:58:10 INFO - Running post-action listener: set_extra_try_arguments 09:58:10 INFO - ##### 09:58:10 INFO - ##### Running populate-webroot step. 09:58:10 INFO - ##### 09:58:10 INFO - Running pre-action listener: _resource_record_pre_action 09:58:10 INFO - Running main action method: populate_webroot 09:58:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:58:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:58:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:58:10 INFO - '--tppagecycles', 09:58:10 INFO - '7'], 09:58:10 INFO - 'tests': ['tsvgm']}}, 09:58:10 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:58:10 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:58:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:58:10 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:58:10 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:58:10 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:58:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:58:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:10 INFO - 'talos_options': ['--e10s'], 09:58:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:58:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:10 INFO - 'tests': ['damp', 'tps']}, 09:58:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:10 INFO - 'talos_options': ['--e10s'], 09:58:10 INFO - 'tests': ['damp', 'tps']}, 09:58:10 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:58:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:58:10 INFO - 'other': {'tests': ['a11yr', 09:58:10 INFO - 'ts_paint', 09:58:10 INFO - 'tpaint', 09:58:10 INFO - 'sessionrestore', 09:58:10 INFO - 'sessionrestore_no_auto_restore']}, 09:58:10 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:58:10 INFO - 'tests': ['a11yr', 09:58:10 INFO - 'ts_paint', 09:58:10 INFO - 'tpaint', 09:58:10 INFO - 'sessionrestore', 09:58:10 INFO - 'sessionrestore_no_auto_restore']}, 09:58:10 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:58:10 INFO - 'tests': ['a11yr', 09:58:10 INFO - 'ts_paint', 09:58:10 INFO - 'tpaint', 09:58:10 INFO - 'sessionrestore', 09:58:10 INFO - 'sessionrestore_no_auto_restore']}, 09:58:10 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:58:10 INFO - 'tests': ['a11yr', 09:58:10 INFO - 'ts_paint', 09:58:10 INFO - 'tpaint', 09:58:10 INFO - 'sessionrestore', 09:58:10 INFO - 'sessionrestore_no_auto_restore']}, 09:58:10 INFO - 'other_l64': {'tests': ['a11yr', 09:58:10 INFO - 'ts_paint', 09:58:10 INFO - 'tpaint', 09:58:10 INFO - 'sessionrestore', 09:58:10 INFO - 'sessionrestore_no_auto_restore']}, 09:58:10 INFO - 'other_nol64': {'tests': ['a11yr', 09:58:10 INFO - 'ts_paint', 09:58:10 INFO - 'tpaint', 09:58:10 INFO - 'sessionrestore', 09:58:10 INFO - 'sessionrestore_no_auto_restore']}, 09:58:10 INFO - 'svgr': {'tests': ['tsvgx', 09:58:10 INFO - 'tsvgr_opacity', 09:58:10 INFO - 'tart', 09:58:10 INFO - 'tscrollx', 09:58:10 INFO - 'cart']}, 09:58:10 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:58:10 INFO - 'tests': ['tsvgx', 09:58:10 INFO - 'tsvgr_opacity', 09:58:10 INFO - 'tart', 09:58:10 INFO - 'tscrollx', 09:58:10 INFO - 'cart']}, 09:58:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:10 INFO - 'tests': ['tp5o']}, 09:58:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:10 INFO - 'talos_options': ['--e10s'], 09:58:10 INFO - 'tests': ['tp5o']}, 09:58:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:10 INFO - 'talos_options': ['--xperf_path', 09:58:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:58:10 INFO - 'tests': ['tp5n']}, 09:58:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:10 INFO - 'talos_options': ['--e10s', 09:58:10 INFO - '--xperf_path', 09:58:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:58:10 INFO - 'tests': ['tp5n']}}, 09:58:10 INFO - 'talos.zip': {'path': '', 09:58:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:58:10 INFO - Running post-action listener: _resource_record_post_action 09:58:10 INFO - ##### 09:58:10 INFO - ##### Running create-virtualenv step. 09:58:10 INFO - ##### 09:58:10 INFO - Running pre-action listener: _resource_record_pre_action 09:58:10 INFO - Running main action method: create_virtualenv 09:58:10 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:58:10 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 09:58:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:58:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:58:10 INFO - Using real prefix '/usr' 09:58:10 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:58:10 INFO - Installing distribute.............................................................................................................................................................................................done. 09:58:12 INFO - Installing pip.................done. 09:58:12 INFO - Return code: 0 09:58:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:12 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 0x7fd0c6556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4e0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edce30>, '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 0x1eddd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ede1c0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 09:58:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:58:12 INFO - Using env: {'DISPLAY': ':0', 09:58:12 INFO - 'HOME': '/home/cltbld', 09:58:12 INFO - 'LANG': 'en_US.UTF-8', 09:58:12 INFO - 'LANGUAGE': 'en_US:en', 09:58:12 INFO - 'LOGNAME': 'cltbld', 09:58:12 INFO - 'MAIL': '/var/mail/cltbld', 09:58:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:12 INFO - 'MOZ_NO_REMOTE': '1', 09:58:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:12 INFO - 'NO_EM_RESTART': '1', 09:58:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:58:12 INFO - 'PWD': '/builds/slave/test-pgo', 09:58:12 INFO - 'SHELL': '/bin/bash', 09:58:12 INFO - 'SHLVL': '1', 09:58:12 INFO - 'TERM': 'linux', 09:58:12 INFO - 'TMOUT': '86400', 09:58:12 INFO - 'USER': 'cltbld', 09:58:12 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 09:58:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:12 INFO - '_': '/tools/buildbot/bin/python'} 09:58:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:58:13 INFO - Cleaning up... 09:58:13 INFO - Return code: 0 09:58:13 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:58:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:13 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 0x7fd0c6556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4e0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edce30>, '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 0x1eddd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ede1c0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:13 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 09:58:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:58:13 INFO - Using env: {'DISPLAY': ':0', 09:58:13 INFO - 'HOME': '/home/cltbld', 09:58:13 INFO - 'LANG': 'en_US.UTF-8', 09:58:13 INFO - 'LANGUAGE': 'en_US:en', 09:58:13 INFO - 'LOGNAME': 'cltbld', 09:58:13 INFO - 'MAIL': '/var/mail/cltbld', 09:58:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:13 INFO - 'MOZ_NO_REMOTE': '1', 09:58:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:13 INFO - 'NO_EM_RESTART': '1', 09:58:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:58:13 INFO - 'PWD': '/builds/slave/test-pgo', 09:58:13 INFO - 'SHELL': '/bin/bash', 09:58:13 INFO - 'SHLVL': '1', 09:58:13 INFO - 'TERM': 'linux', 09:58:13 INFO - 'TMOUT': '86400', 09:58:13 INFO - 'USER': 'cltbld', 09:58:13 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 09:58:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:13 INFO - '_': '/tools/buildbot/bin/python'} 09:58:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:13 INFO - Downloading/unpacking psutil>=0.7.1 09:58:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:58:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:58:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:58:15 INFO - Installing collected packages: psutil 09:58:15 INFO - Running setup.py install for psutil 09:58:15 INFO - building 'psutil._psutil_linux' extension 09:58:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:58:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:58:15 INFO - building 'psutil._psutil_posix' extension 09:58:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:58:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:58:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:58:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:58:15 INFO - Successfully installed psutil 09:58:15 INFO - Cleaning up... 09:58:15 INFO - Return code: 0 09:58:15 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:58: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')]} 09:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58: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')]} 09:58:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:15 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 0x7fd0c6556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4e0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edce30>, '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 0x1eddd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ede1c0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:15 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 09:58:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:58:15 INFO - Using env: {'DISPLAY': ':0', 09:58:15 INFO - 'HOME': '/home/cltbld', 09:58:15 INFO - 'LANG': 'en_US.UTF-8', 09:58:15 INFO - 'LANGUAGE': 'en_US:en', 09:58:15 INFO - 'LOGNAME': 'cltbld', 09:58:15 INFO - 'MAIL': '/var/mail/cltbld', 09:58:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:15 INFO - 'MOZ_NO_REMOTE': '1', 09:58:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:15 INFO - 'NO_EM_RESTART': '1', 09:58:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:58:15 INFO - 'PWD': '/builds/slave/test-pgo', 09:58:15 INFO - 'SHELL': '/bin/bash', 09:58:15 INFO - 'SHLVL': '1', 09:58:15 INFO - 'TERM': 'linux', 09:58:15 INFO - 'TMOUT': '86400', 09:58:15 INFO - 'USER': 'cltbld', 09:58:15 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 09:58:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:15 INFO - '_': '/tools/buildbot/bin/python'} 09:58:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:15 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:17 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:58:17 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:58:17 INFO - Installing collected packages: mozsystemmonitor 09:58:17 INFO - Running setup.py install for mozsystemmonitor 09:58:17 INFO - Successfully installed mozsystemmonitor 09:58:17 INFO - Cleaning up... 09:58:17 INFO - Return code: 0 09:58:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:58:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:17 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 0x7fd0c6556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4e0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edce30>, '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 0x1eddd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ede1c0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 09:58:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:58:17 INFO - Using env: {'DISPLAY': ':0', 09:58:17 INFO - 'HOME': '/home/cltbld', 09:58:17 INFO - 'LANG': 'en_US.UTF-8', 09:58:17 INFO - 'LANGUAGE': 'en_US:en', 09:58:17 INFO - 'LOGNAME': 'cltbld', 09:58:17 INFO - 'MAIL': '/var/mail/cltbld', 09:58:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:17 INFO - 'MOZ_NO_REMOTE': '1', 09:58:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:17 INFO - 'NO_EM_RESTART': '1', 09:58:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:58:17 INFO - 'PWD': '/builds/slave/test-pgo', 09:58:17 INFO - 'SHELL': '/bin/bash', 09:58:17 INFO - 'SHLVL': '1', 09:58:17 INFO - 'TERM': 'linux', 09:58:17 INFO - 'TMOUT': '86400', 09:58:17 INFO - 'USER': 'cltbld', 09:58:17 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 09:58:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:17 INFO - '_': '/tools/buildbot/bin/python'} 09:58:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:18 INFO - Downloading/unpacking blobuploader==1.2.4 09:58:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:19 INFO - Downloading blobuploader-1.2.4.tar.gz 09:58:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:58:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:20 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:58:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:58:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:20 INFO - Downloading docopt-0.6.1.tar.gz 09:58:20 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:58:20 INFO - Installing collected packages: blobuploader, requests, docopt 09:58:20 INFO - Running setup.py install for blobuploader 09:58:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:58:20 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:58:20 INFO - Running setup.py install for requests 09:58:20 INFO - Running setup.py install for docopt 09:58:21 INFO - Successfully installed blobuploader requests docopt 09:58:21 INFO - Cleaning up... 09:58:21 INFO - Return code: 0 09:58:21 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:58:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:21 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 0x7fd0c6556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4e0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edce30>, '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 0x1eddd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ede1c0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:58:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:58:21 INFO - Using env: {'DISPLAY': ':0', 09:58:21 INFO - 'HOME': '/home/cltbld', 09:58:21 INFO - 'LANG': 'en_US.UTF-8', 09:58:21 INFO - 'LANGUAGE': 'en_US:en', 09:58:21 INFO - 'LOGNAME': 'cltbld', 09:58:21 INFO - 'MAIL': '/var/mail/cltbld', 09:58:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:21 INFO - 'MOZ_NO_REMOTE': '1', 09:58:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:21 INFO - 'NO_EM_RESTART': '1', 09:58:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:58:21 INFO - 'PWD': '/builds/slave/test-pgo', 09:58:21 INFO - 'SHELL': '/bin/bash', 09:58:21 INFO - 'SHLVL': '1', 09:58:21 INFO - 'TERM': 'linux', 09:58:21 INFO - 'TMOUT': '86400', 09:58:21 INFO - 'USER': 'cltbld', 09:58:21 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 09:58:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:21 INFO - '_': '/tools/buildbot/bin/python'} 09:58:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:58:21 INFO - Running setup.py (path:/tmp/pip-AqHXIS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:58:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:58:21 INFO - Running setup.py (path:/tmp/pip-DIvvZs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:58:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:58:21 INFO - Running setup.py (path:/tmp/pip-5od5Qf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:58:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:58:21 INFO - Running setup.py (path:/tmp/pip-S5lTNj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:58:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:58:21 INFO - Running setup.py (path:/tmp/pip-7MH2Uw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:58:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:58:21 INFO - Running setup.py (path:/tmp/pip-vr40sr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:58:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:58:21 INFO - Running setup.py (path:/tmp/pip-VZu0Q1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:58:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:58:21 INFO - Running setup.py (path:/tmp/pip-5h3_vK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:58:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:58:21 INFO - Running setup.py (path:/tmp/pip-AJPw8O-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:58:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:58:21 INFO - Running setup.py (path:/tmp/pip-BOznqg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:58:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:58:22 INFO - Running setup.py (path:/tmp/pip-QKiyPL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:58:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:58:22 INFO - Running setup.py (path:/tmp/pip-fLLZbX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:58:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:58:22 INFO - Running setup.py (path:/tmp/pip-CtKrTY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:58:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:58:22 INFO - Running setup.py (path:/tmp/pip-NDR2gO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:58:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:58:22 INFO - Running setup.py (path:/tmp/pip-NjJxGR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:58:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:58:22 INFO - Running setup.py (path:/tmp/pip-VEkaag-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:58:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:58:22 INFO - Running setup.py (path:/tmp/pip-AhfngK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:58:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:58:22 INFO - Running setup.py install for manifestparser 09:58:22 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:58:22 INFO - Running setup.py install for mozcrash 09:58:22 INFO - Running setup.py install for mozdebug 09:58:22 INFO - Running setup.py install for mozdevice 09:58:22 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:58:22 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:58:22 INFO - Running setup.py install for mozfile 09:58:23 INFO - Running setup.py install for mozhttpd 09:58:23 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:58:23 INFO - Running setup.py install for mozinfo 09:58:23 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:58:23 INFO - Running setup.py install for mozInstall 09:58:23 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:58:23 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:58:23 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:58:23 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:58:23 INFO - Running setup.py install for mozleak 09:58:23 INFO - Running setup.py install for mozlog 09:58:23 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:58:23 INFO - Running setup.py install for moznetwork 09:58:23 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:58:23 INFO - Running setup.py install for mozprocess 09:58:23 INFO - Running setup.py install for mozprofile 09:58:23 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:58:23 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:58:23 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:58:23 INFO - Running setup.py install for mozrunner 09:58:24 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:58:24 INFO - Running setup.py install for mozscreenshot 09:58:24 INFO - Running setup.py install for moztest 09:58:24 INFO - Running setup.py install for mozversion 09:58:24 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:58:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:58:24 INFO - Cleaning up... 09:58:24 INFO - Return code: 0 09:58:24 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:58:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:24 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 0x7fd0c6556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4e0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edce30>, '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 0x1eddd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ede1c0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:24 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:58:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:58:24 INFO - Using env: {'DISPLAY': ':0', 09:58:24 INFO - 'HOME': '/home/cltbld', 09:58:24 INFO - 'LANG': 'en_US.UTF-8', 09:58:24 INFO - 'LANGUAGE': 'en_US:en', 09:58:24 INFO - 'LOGNAME': 'cltbld', 09:58:24 INFO - 'MAIL': '/var/mail/cltbld', 09:58:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:24 INFO - 'MOZ_NO_REMOTE': '1', 09:58:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:24 INFO - 'NO_EM_RESTART': '1', 09:58:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:58:24 INFO - 'PWD': '/builds/slave/test-pgo', 09:58:24 INFO - 'SHELL': '/bin/bash', 09:58:24 INFO - 'SHLVL': '1', 09:58:24 INFO - 'TERM': 'linux', 09:58:24 INFO - 'TMOUT': '86400', 09:58:24 INFO - 'USER': 'cltbld', 09:58:24 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 09:58:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:24 INFO - '_': '/tools/buildbot/bin/python'} 09:58:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:58:24 INFO - Running setup.py (path:/tmp/pip-wZiaxR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:58:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 09:58:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:58:24 INFO - Running setup.py (path:/tmp/pip-hGZ9Ss-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:58:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:58:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:58:24 INFO - Running setup.py (path:/tmp/pip-7pOxYw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:58:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 09:58:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:58:24 INFO - Running setup.py (path:/tmp/pip-SrWjqW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:58:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:58:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:58:24 INFO - Running setup.py (path:/tmp/pip-n_Gy5d-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:58:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 09:58:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:58:24 INFO - Running setup.py (path:/tmp/pip-cYi9BU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:58:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 09:58:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:58:24 INFO - Running setup.py (path:/tmp/pip-MZV7vE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 09:58:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:58:25 INFO - Running setup.py (path:/tmp/pip-Djp3SH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 09:58:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:58:25 INFO - Running setup.py (path:/tmp/pip-aQsi8_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 09:58:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:58:25 INFO - Running setup.py (path:/tmp/pip-nhCFPY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:58:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:58:25 INFO - Running setup.py (path:/tmp/pip-o3rF8B-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 09:58:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:58:25 INFO - Running setup.py (path:/tmp/pip-DlpI9y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 09:58:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:58:25 INFO - Running setup.py (path:/tmp/pip-S0UYPn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 09:58:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:58:25 INFO - Running setup.py (path:/tmp/pip-BJM_6r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 09:58:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:58:25 INFO - Running setup.py (path:/tmp/pip-yXyucN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 09:58:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:58:25 INFO - Running setup.py (path:/tmp/pip-0wFR6n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 09:58:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:58:25 INFO - Running setup.py (path:/tmp/pip-L1QgR4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 09:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 09:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:58:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:27 INFO - Downloading blessings-1.6.tar.gz 09:58:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:58:27 INFO - Installing collected packages: blessings 09:58:27 INFO - Running setup.py install for blessings 09:58:27 INFO - Successfully installed blessings 09:58:27 INFO - Cleaning up... 09:58:27 INFO - Return code: 0 09:58:27 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:58:27 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:58:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:58:28 INFO - Reading from file tmpfile_stdout 09:58:28 INFO - Current package versions: 09:58:28 INFO - argparse == 1.2.1 09:58:28 INFO - blessings == 1.6 09:58:28 INFO - blobuploader == 1.2.4 09:58:28 INFO - docopt == 0.6.1 09:58:28 INFO - manifestparser == 1.1 09:58:28 INFO - mozInstall == 1.12 09:58:28 INFO - mozcrash == 0.16 09:58:28 INFO - mozdebug == 0.1 09:58:28 INFO - mozdevice == 0.47 09:58:28 INFO - mozfile == 1.2 09:58:28 INFO - mozhttpd == 0.7 09:58:28 INFO - mozinfo == 0.9 09:58:28 INFO - mozleak == 0.1 09:58:28 INFO - mozlog == 3.1 09:58:28 INFO - moznetwork == 0.27 09:58:28 INFO - mozprocess == 0.22 09:58:28 INFO - mozprofile == 0.27 09:58:28 INFO - mozrunner == 6.11 09:58:28 INFO - mozscreenshot == 0.1 09:58:28 INFO - mozsystemmonitor == 0.0 09:58:28 INFO - moztest == 0.7 09:58:28 INFO - mozversion == 1.4 09:58:28 INFO - psutil == 3.1.1 09:58:28 INFO - requests == 1.2.3 09:58:28 INFO - wsgiref == 0.1.2 09:58:28 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:58:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:28 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 0x7fd0c6556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4e0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edce30>, '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 0x1eddd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ede1c0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 09:58:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:58:28 INFO - Using env: {'DISPLAY': ':0', 09:58:28 INFO - 'HOME': '/home/cltbld', 09:58:28 INFO - 'LANG': 'en_US.UTF-8', 09:58:28 INFO - 'LANGUAGE': 'en_US:en', 09:58:28 INFO - 'LOGNAME': 'cltbld', 09:58:28 INFO - 'MAIL': '/var/mail/cltbld', 09:58:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:28 INFO - 'MOZ_NO_REMOTE': '1', 09:58:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:28 INFO - 'NO_EM_RESTART': '1', 09:58:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:58:28 INFO - 'PWD': '/builds/slave/test-pgo', 09:58:28 INFO - 'SHELL': '/bin/bash', 09:58:28 INFO - 'SHLVL': '1', 09:58:28 INFO - 'TERM': 'linux', 09:58:28 INFO - 'TMOUT': '86400', 09:58:28 INFO - 'USER': 'cltbld', 09:58:28 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 09:58:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:28 INFO - '_': '/tools/buildbot/bin/python'} 09:58:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:58:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 09:58:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 09:58:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 09:58:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 09:58:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 09:58:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 09:58:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 09:58:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:58:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 09:58:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:58:28 INFO - Cleaning up... 09:58:28 INFO - Return code: 0 09:58:28 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:58:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:28 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 0x7fd0c6556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4e0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edce30>, '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 0x1eddd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ede1c0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 09:58:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:58:28 INFO - Using env: {'DISPLAY': ':0', 09:58:28 INFO - 'HOME': '/home/cltbld', 09:58:28 INFO - 'LANG': 'en_US.UTF-8', 09:58:28 INFO - 'LANGUAGE': 'en_US:en', 09:58:28 INFO - 'LOGNAME': 'cltbld', 09:58:28 INFO - 'MAIL': '/var/mail/cltbld', 09:58:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:28 INFO - 'MOZ_NO_REMOTE': '1', 09:58:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:28 INFO - 'NO_EM_RESTART': '1', 09:58:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:58:28 INFO - 'PWD': '/builds/slave/test-pgo', 09:58:28 INFO - 'SHELL': '/bin/bash', 09:58:28 INFO - 'SHLVL': '1', 09:58:28 INFO - 'TERM': 'linux', 09:58:28 INFO - 'TMOUT': '86400', 09:58:28 INFO - 'USER': 'cltbld', 09:58:28 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 09:58:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:28 INFO - '_': '/tools/buildbot/bin/python'} 09:58:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:28 INFO - Downloading/unpacking jsonschema 09:58:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:58:30 INFO - Downloading/unpacking functools32 (from jsonschema) 09:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:30 INFO - Downloading functools32-3.2.3-2.tar.gz 09:58:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:58:30 INFO - warning: no files found matching '*.txt' 09:58:30 INFO - no previously-included directories found matching 'build' 09:58:30 INFO - no previously-included directories found matching 'dist' 09:58:30 INFO - no previously-included directories found matching '.git*' 09:58:30 INFO - Installing collected packages: jsonschema, functools32 09:58:30 INFO - Running setup.py install for functools32 09:58:30 INFO - warning: no files found matching '*.txt' 09:58:30 INFO - no previously-included directories found matching 'build' 09:58:30 INFO - no previously-included directories found matching 'dist' 09:58:30 INFO - no previously-included directories found matching '.git*' 09:58:30 INFO - Successfully installed jsonschema functools32 09:58:30 INFO - Cleaning up... 09:58:30 INFO - Return code: 0 09:58:30 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:30 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 0x7fd0c6556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4e0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edce30>, '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 0x1eddd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ede1c0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 09:58:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:58:30 INFO - Using env: {'DISPLAY': ':0', 09:58:30 INFO - 'HOME': '/home/cltbld', 09:58:30 INFO - 'LANG': 'en_US.UTF-8', 09:58:30 INFO - 'LANGUAGE': 'en_US:en', 09:58:30 INFO - 'LOGNAME': 'cltbld', 09:58:30 INFO - 'MAIL': '/var/mail/cltbld', 09:58:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:30 INFO - 'MOZ_NO_REMOTE': '1', 09:58:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:30 INFO - 'NO_EM_RESTART': '1', 09:58:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:58:30 INFO - 'PWD': '/builds/slave/test-pgo', 09:58:30 INFO - 'SHELL': '/bin/bash', 09:58:30 INFO - 'SHLVL': '1', 09:58:30 INFO - 'TERM': 'linux', 09:58:30 INFO - 'TMOUT': '86400', 09:58:30 INFO - 'USER': 'cltbld', 09:58:30 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 09:58:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:30 INFO - '_': '/tools/buildbot/bin/python'} 09:58:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:30 INFO - Downloading/unpacking flake8 09:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:58:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:58:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:58:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:58:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:58:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:33 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:58:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:58:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:58:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:58:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:58:33 INFO - no previously-included directories found matching 'docs/_build' 09:58:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:58:33 INFO - Running setup.py install for pep8 09:58:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:58:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:58:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:58:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:58:33 INFO - no previously-included directories found matching 'docs/_build' 09:58:33 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:58:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:58:33 INFO - Cleaning up... 09:58:33 INFO - Return code: 0 09:58:33 INFO - Running post-action listener: _resource_record_post_action 09:58:33 INFO - Running post-action listener: _start_resource_monitoring 09:58:33 INFO - Starting resource monitoring. 09:58:33 INFO - ##### 09:58:33 INFO - ##### Running install step. 09:58:33 INFO - ##### 09:58:33 INFO - Running pre-action listener: _resource_record_pre_action 09:58:33 INFO - Running main action method: install 09:58:33 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:58:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:58:33 INFO - Reading from file tmpfile_stdout 09:58:33 INFO - Detecting whether we're running mozinstall >=1.0... 09:58:33 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:58:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:58:33 INFO - Reading from file tmpfile_stdout 09:58:33 INFO - Output received: 09:58:33 INFO - Usage: mozinstall [options] installer 09:58:33 INFO - Options: 09:58:33 INFO - -h, --help show this help message and exit 09:58:33 INFO - -d DEST, --destination=DEST 09:58:33 INFO - Directory to install application into. [default: 09:58:33 INFO - "/builds/slave/test-pgo"] 09:58:33 INFO - --app=APP Application being installed. [default: firefox] 09:58:33 INFO - mkdir: /builds/slave/test-pgo/build/application 09:58:33 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'] 09:58:33 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 09:58:48 INFO - Reading from file tmpfile_stdout 09:58:48 INFO - Output received: 09:58:48 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:58:48 INFO - Running post-action listener: _resource_record_post_action 09:58:48 INFO - ##### 09:58:48 INFO - ##### Running run-tests step. 09:58:48 INFO - ##### 09:58:48 INFO - Running pre-action listener: _resource_record_pre_action 09:58:48 INFO - Running main action method: run_tests 09:58:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:58:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:58:48 INFO - Python 2.7.3 09:58:48 INFO - Return code: 0 09:58:48 INFO - grabbing minidump binary from tooltool 09:58:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:48 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 0x1edce30>, '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 0x1eddd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ede1c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:58:48 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 09:58:48 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:58:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:58:48 INFO - Return code: 0 09:58:48 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:58:48 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:58:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:58:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:58:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:58:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:58:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:58:49 INFO - Return code: 0 09:58:49 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/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:58:49 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/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --webServer localhost 09:58:49 INFO - Using env: {'DISPLAY': ':0', 09:58:49 INFO - 'HOME': '/home/cltbld', 09:58:49 INFO - 'LANG': 'en_US.UTF-8', 09:58:49 INFO - 'LANGUAGE': 'en_US:en', 09:58:49 INFO - 'LOGNAME': 'cltbld', 09:58:49 INFO - 'MAIL': '/var/mail/cltbld', 09:58:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:58:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:58:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:49 INFO - 'MOZ_NO_REMOTE': '1', 09:58:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:58:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:49 INFO - 'NO_EM_RESTART': '1', 09:58:49 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:58:49 INFO - 'PWD': '/builds/slave/test-pgo', 09:58:49 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:58:49 INFO - 'SHELL': '/bin/bash', 09:58:49 INFO - 'SHLVL': '1', 09:58:49 INFO - 'TERM': 'linux', 09:58:49 INFO - 'TMOUT': '86400', 09:58:49 INFO - 'USER': 'cltbld', 09:58:49 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468', 09:58:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:49 INFO - '_': '/tools/buildbot/bin/python'} 09:58:49 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/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost'] with output_timeout 3600 09:58:49 INFO - mozversion INFO | application_buildid: 20160122072128 09:58:49 INFO - mozversion INFO | application_changeset: 829ed713fa9a047575d96c6943d86031bd78ab9f 09:58:49 INFO - mozversion INFO | application_display_name: FirefoxDeveloperEdition 09:58:49 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:58:49 INFO - mozversion INFO | application_name: Firefox 09:58:49 INFO - mozversion INFO | application_remotingname: firefox-dev 09:58:49 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:58:49 INFO - mozversion INFO | application_vendor: Mozilla 09:58:49 INFO - mozversion INFO | application_version: 45.0a2 09:58:49 INFO - mozversion INFO | platform_buildid: 20160122072128 09:58:49 INFO - mozversion INFO | platform_changeset: 829ed713fa9a047575d96c6943d86031bd78ab9f 09:58:49 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:58:49 INFO - mozversion INFO | platform_version: 45.0a2 09:58:49 INFO - 2016-01-22 09:58:49,841 DEBUG : using testdate: 1453485529 09:58:49 INFO - 2016-01-22 09:58:49,841 DEBUG : actual date: 1453485529 09:58:49 INFO - 2016-01-22 09:58:49,850 INFO : starting webserver on 'localhost:53897' 09:58:49 INFO - 2016-01-22 09:58:49,851 INFO : Starting test suite talos-linux64-ix-004 09:58:49 INFO - 2016-01-22 09:58:49,851 INFO : Starting test dromaeo_css 09:58:49 INFO - 2016-01-22 09:58:49,851 DEBUG : operating with platform_type : linux_ 09:58:49 INFO - 2016-01-22 09:58:49,852 INFO : Initialising browser for dromaeo_css test... 09:58:49 INFO - 2016-01-22 09:58:49,866 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpiy6OFX/profile http://localhost:53897/getInfo.html 09:58:53 INFO - 2016-01-22 09:58:53,963 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:58:53 INFO - 2016-01-22 09:58:53,963 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:58:53 INFO - 2016-01-22 09:58:53,963 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:58:53 INFO - 2016-01-22 09:58:53,963 DEBUG : BROWSER_OUTPUT: __metrics 09:58:54 INFO - 2016-01-22 09:58:54,100 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:58:54 INFO - 2016-01-22 09:58:54,935 INFO : Browser initialized. 09:58:54 INFO - 2016-01-22 09:58:54,935 INFO : Running cycle 1/1 for dromaeo_css test... 09:58:54 INFO - 2016-01-22 09:58:54,935 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpiy6OFX/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:59:06 INFO - 2016-01-22 09:59:06,730 DEBUG : BROWSER_OUTPUT: RSS: Main: 137388032 09:59:06 INFO - 2016-01-22 09:59:06,731 DEBUG : BROWSER_OUTPUT: 09:59:57 INFO - 2016-01-22 09:59:57,470 DEBUG : BROWSER_OUTPUT: 1453485597468 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] 10:02:05 INFO - 2016-01-22 10:02:05,904 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53897/tests/dromaeo/cssquery-ext.html) 10:02:07 INFO - 2016-01-22 10:02:07,082 DEBUG : BROWSER_OUTPUT: RSS: Main: 251555840 10:02:07 INFO - 2016-01-22 10:02:07,082 DEBUG : BROWSER_OUTPUT: 10:05:09 INFO - 2016-01-22 10:05:09,280 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/dromaeo/cssquery-ext.html (next: http://localhost:53897/tests/dromaeo/cssquery-jquery.html) 10:05:09 INFO - 2016-01-22 10:05:09,846 DEBUG : BROWSER_OUTPUT: RSS: Main: 217481216 10:05:09 INFO - 2016-01-22 10:05:09,846 DEBUG : BROWSER_OUTPUT: 10:08:11 INFO - 2016-01-22 10:08:11,731 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53897/tests/dromaeo/cssquery-mootools.html) 10:08:12 INFO - 2016-01-22 10:08:12,297 DEBUG : BROWSER_OUTPUT: RSS: Main: 206295040 10:08:12 INFO - 2016-01-22 10:08:12,297 DEBUG : BROWSER_OUTPUT: 10:11:14 INFO - 2016-01-22 10:11:14,096 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53897/tests/dromaeo/cssquery-prototype.html) 10:11:14 INFO - 2016-01-22 10:11:14,670 DEBUG : BROWSER_OUTPUT: RSS: Main: 199454720 10:11:14 INFO - 2016-01-22 10:11:14,671 DEBUG : BROWSER_OUTPUT: 10:14:17 INFO - 2016-01-22 10:14:17,097 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53897/tests/dromaeo/cssquery-yui.html) 10:14:17 INFO - 2016-01-22 10:14:17,678 DEBUG : BROWSER_OUTPUT: RSS: Main: 200282112 10:14:17 INFO - 2016-01-22 10:14:17,679 DEBUG : BROWSER_OUTPUT: 10:17:19 INFO - 2016-01-22 10:17:19,819 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/dromaeo/cssquery-yui.html (next: http://localhost:53897/tests/dromaeo/cssquery-dojo.html) 10:17:20 INFO - 2016-01-22 10:17:20,069 DEBUG : BROWSER_OUTPUT: RSS: Main: 243372032 10:17:20 INFO - 2016-01-22 10:17:20,069 DEBUG : BROWSER_OUTPUT: 10:17:20 INFO - 2016-01-22 10:17:20,071 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:17:20 INFO - 2016-01-22 10:17:20,071 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:17:20 INFO - 2016-01-22 10:17:20,071 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:17:20 INFO - 2016-01-22 10:17:20,071 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:17:20 INFO - 2016-01-22 10:17:20,075 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3247.594050743657;3282.5404443377915;3291.9341613167735;3306.5040243963417;3308.8841890533836;5322.733863306834;5434.701091439974;5442.510174084332;5461.617686761928;5462.562994960404;5428.185772134182;5430.91853622195;5449.618526703132;5464.289642346499;5470.863228419282;4881.267809828519;4947.134251505972;4960.578350840183;5019.121653710605;5046.167382381903;4928.359313289267;4988.127077761395;4991.875203119915;4993.950060499385;4994.225894986283;4895.167821470341;5006.949930500703;5010.574101201402;5025.547700706936;5046.041252162096;4911.238757992517;4985.725785081819;5000.42495113063;5001.17480615698;5006.349174607314;4964.702117872939;4997.875053123665;5014.2979982802435;5020.447750747415;5024.246363045572;5032.924506132411;5100.15847385181;5133.153029750087;5202.271681964528;5254.133068043769;5723.227364305807;5735.942640573582;5749.59752817303;5756.222909907151;5805.071188609802;5772.7113644317615;5830.970845145747;5846.9707651461895;5848.64908105515;5849;5218.347706536683;5341.919871201935;5344.652597581145;5439.184122381595;5482.369527504308;5856.209411729447;5884.852878677982;5886.676232807236;5890.4993075589055;5896.616719913192;5516.172574113915;5715.48560629537;5723.971380143073;5727.971360143256;5758.222639943638;5620.156976453564;5697.800576979786;5709.7716091356715;5794.50746686528;5796.594238403271;5766.394528574523;5809.2738407699035;5833.066709326487;5845.503132233808;5846.473817356458;5798.159266906275;5817.621854579439;5829.475347218686;5838.299404071538;5854.297484301911;4292.141571685675;4380.540043295472;4437.423134992495;4487.955120448819;4497.955020449754;4030.1133750574827;4034.293998550242;4044.090079732037;4062.918741625151;4067.5525692173837;5479.890402192013;5521.69630670317;5529.723513824292;5534.557235421156;5552.08390615552;5629.971850140723;5721.256236689287;5741.684207368635;5806.361300256968;5857.70711464425;5439.891202175934;5465.617406781487;5477.096279113981;5482.451754824566;5495.422980587061;4934.629902757308;5072.923906141411;5082.1360368737405;5092.1852503599985;5121.155009423478;5881.676507792027;5926.733297001645;5971.28344598651;5971.820845374646;5999.700014999233;5779.682117483579;5808.82573522795;5821.068629019336;5826.329972053099;5834.504067154339;5825.50483208932;5966.254218222723;5973.820785376446;5984;6024.277086749618;5576.052071147834;5684.63049901755;5688.886222275614;5710.828675139753;5710.971445142914;5760.971195143998;5776.971115144397;5791.565632577489;5805.883882322414;5809.76760929575;5459.69971651563;5667.631603945731;5679.943200567942;5680.40355762639;5690.203371528072;5547.5007249347755;5584.078627026658;5615.578831587729;5635.9436405635415;5680.857978550569;5574.637648552831;5641.238432811682;5644.520215781705;5646.153077038477;5653.660780353192;5520.392756796668;5707.971460142673;5735.8566035849435;5773.53811695055;5774.855628609318;5470.343558772814;5595.524380427709;5596.86007849807;5608.327000759935;5612.438756124355 10:17:20 INFO - 2016-01-22 10:17:20,075 DEBUG : BROWSER_OUTPUT: |1;ext.html;570.9000924838155;575.4475703324808;581.6422899916553;605.9878802423951;630.3885231325612;41414.10101292911;42600.360994585106;42610.36084458732;42625.93435164123;42654.99999999996;47259.763701181444;47471;47607;47608.761956190174;47686.52313476864;7836.255555722198;7977.880331795028;7985.880211796828;9249.167574918256;9431;11032.669019929415;11304.208705390625;11307.208495405284;11307.830382544269;11316.603918862842;11004.944975275112;11169.218154729131;11170.162237832155;11180.44097795111;11184.552617895275;8119.066307374639;8291.792705182359;8311.418200725951;10396.844047339258;11143.387113708743;10797.40614266215;10955.945220273848;10958.56165753369;10960.945195274053;10961.616343427942;44305.55694443079;44614.55385446169;44807.103857922986;44923.77538112321;44931;3322.2026713588807;3480.443129099332;3482.0424383294544;3486.2678837443914;3909.2963266612;33510.66489335124;33917.321653567036;33979.830100849584;34037.489437658456;34106.488402673735;4077.7961101944784;4086.5709100544314;4194.538600753915;4211.810468528924;4249.617534421917;304141;308064;308227;308521;309034.45482772664;45890.77054614772;46830;63841.361586383544;64381;69926.65036674784;65734.34265657282;65743.34256657468;65986.67006664936;66152;66157;18276.177572009292;18408.17163227658;18563.35028274004;18565.907170464332;18580.628387432174;14041;14083.859161408254;14103.365348559339;14113.647158821112;14117.929410352883;20847.583048338947;21067.789322106888;21088.472788180417;21127.154913803275;21166.682499762515;7664.397733065451;7893.368530517545;7923.326517246099;7934.841303173905;7967.56178410193;958.0611001218705;958.3339578992533;1071.3786004117594;1106.4799544214206;1116.5031560955458;26611.467770644478;27106.32234194161;30170;30898.227544311572;31318.686813132033;1037.5227395398201;1040.354979912444;1064.1433645914958;1117.9608713694981;1223.8592561855323;13326.733465330833;14274.714505710035;14279.57161285163;15770.68458630844;16047.913771209667;268879;272042;273358;273553;273976;39510.40734388986;40338.394924076165;53842.19236711453;54832.451675483535;55071.72464137734;1461.1087236785552;1464.9535733123496;1465.4682215859727;1469.4783351909919;1476.7711004794273;28781;29020.27449313784;29077.418451630427;29719.257018573844;30029.5495567571;39196.21607567776;39775.40336894891;49405.25892111549;49651.25523117228;49691.254631179836;315177;316894;318047;318278;318865;36400;36624.63375366319;36629.26741465209;36712.0821979442;36908.81545592362;49500.75249623729;49976.75011624918;50365;50501.74749126231;50876;40402.79798600988;41617.1676566473;46893.53106469028;48053;49078.75460622674;42785.35821962736;42881.35677964771;42932.88956187503;43073;43225.35161972636;42314.788426057676;42880.57119428766;43229.783851081804;43311.35032974571;43354.132917340845;36583.81708091443;37835.24329513339;38063.80968095142;38475.230495389376;38763.2247355057;28469.288267793472;28782.568261475677;28992.855035724686;29038.274043148245;31286 10:17:20 INFO - 2016-01-22 10:17:20,078 DEBUG : BROWSER_OUTPUT: |2;jquery.html;365.7659098177169;369.84096838359505;371.13710622801983;371.9163188282635;372.43947858472995;14191.574252772434;16057.437989670363;17032;17643;18207.635847283047;18243.90878045608;18416.631667366677;18589.535261618465;18718.344857929977;18753.7186942196;5566.972165139168;5701;5701.2018317435595;5709.4290570942985;5713.17159011943;4137.648299894513;4219.578042195772;5648.350439699426;6023.126646636235;6173.660448675321;6021.879562408749;6040.852238074774;6073.453389194971;6092.390760923917;6132.080187971817;5264.473552644724;5325.920111198335;5363.839084827422;5393.6763794172475;5403.918941215885;5149.021685879671;5211.478852114796;5224.425313215544;5233.4243233244315;5453.672779633234;11955.342456164908;12486.75026499474;12651.93674031624;12657.367131643381;12675.74648507034;2117.068489864455;2124.840636952235;2137.839662025339;2186.0709198590534;2212.557488502306;6504.9674751625935;8624.878765760473;9123.27013838898;9153.08469153086;9257.768555786159;1808.5749848785524;1866.0762922353385;1883.9638198990501;1934.8935808530525;1940.2433051110079;63922.68038659777;65629.3437065628;65885.34114658888;67264.3273567263;67660;9109.81780364389;9622.37454565451;10606.681799546026;10622.94688526568;10630.20273479492;13900.513482028282;13907.374168162458;13908.443662253396;13912.721745565032;13927;7939.483933544301;7976.521408715495;8081.959590202011;8149.674013039412;8204.425690201628;7956.801079973047;7994.160613135653;8028.11690714021;8041.758747237593;8085.434019618688;4054.4526488924207;4489.753063581535;4489.932651010237;4524.864254072383;4577.130345234461;1658.0963374960675;1699.7195462748755;1707.402409156785;1713.8971661700339;1776.9911150444166;391.5594955674866;392.9783861887624;1012.2256473797551;1012.987012987013;1018.7656838927088;9398;9792.461414622265;10310.432926188982;10579.523921423555;10666.573337066431;444.4311281559609;454.493240037356;845.526505157114;850.5619605902965;867.3451544084176;7323.633818309063;7484.925150748531;7606.885896711554;7672.578008209491;7705.460617756816;636338;642732;643262;659431;662830;80805;86525.56737216399;88194;89940.55029724809;90749.54625226832;1151.5336288803048;1169.351010189336;1169.602335206017;1180.2564384437921;1183.798754211786;16521.91739041297;16522.339106436088;16600.418985335455;16636.417725379553;16948.322067117177;37935.62064379321;37998.43002354912;38416.80791596024;38592.61407386002;40177;49185.754071229414;50010.24984625161;51130.48869511406;51959.74020129875;52679.209811853616;9791.608335666493;9934.254930880068;10012.499375031364;10112.696619101438;10143.239257055602;11731.706707332385;11895.345755983504;11916.821247681119;12670.683232918882;15829.604259893595;12593.496260149492;14399.568012959628;14626;14635.560933172022;14774.18741969202;14500.637484062983;14570.05294655844;14616.488422905148;14771.852281477477;14878.553643390716;14824.184669843262;15488.84511154874;15494.767578486557;15528.689426211187;15967.121808300655;9492;9644;9665.565049572926;10094.192464602667;10104.69685909424;9900.108990190918;10068.949655251678;14898.1806000671;15338.539843804703;15455 10:17:20 INFO - 2016-01-22 10:17:20,080 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1469.6472846516826;1491.254372813593;1498.9700205995878;1700.7874015748032;1854.972175417369;6716.79849604512;7740.612969351531;7932.286094251516;7956.085050219226;7959.920400795998;7558.773236802891;7661.885071723915;7684.807879803007;7684.846303073935;7735.187805280449;1879.1449890299948;1903.7334773131772;1908.3893154190596;2206.018321846783;2242.360927135767;2190.09111218844;2252.2680128958073;2253.54929014197;2255.9436014099697;2257.7064981552376;2199.846010779246;2221.3891179925567;2278.9202377916777;2279.31620513846;2279.4985103277336;2170.7829217078324;2201.273579718689;2220.500387412835;2257.085880218514;2257.8532395394245;2281.6121259385945;2282.7032485776745;2321.605327094398;2334.299710086967;2335.2760644200353;7882.448228623999;7985.800354991114;8003.639836207326;8006.759797206093;8019.518828870287;4769.260764581495;4828.227483602606;4844.103840789465;4851.636127290432;4876.609871210295;2925.575791510219;2979.582858399826;2979.702029797025;2995.5656429817773;3132.3265497918055;978.1978777402533;990.3513198854762;1000.7598176437675;1022.4018948914894;1042.3276986343842;10902.345859248471;10912.890871091267;10922.235443518957;10930.070943969773;10930.99999999992;4083.3262511685834;4102.056526998807;4144.772037537964;4153.958460415418;4183.163367326547;9340.112689294507;9352.672656457127;9398.34211605181;9403.764905877271;9412.294077944181;4773.66584339093;4797.2564252540915;4802.591779698695;4803.519648035169;4803.5916947059195;4348.608625223682;4356.324769660708;4358.912821743611;4361.978190109029;4371.693981421334;3218.5494030835703;3234.3046245057426;3463.584369875631;3738.7569807962395;3832.0228341773227;1196.1387800783596;1210.9878901211052;1215.4044518186024;1220.5850010996126;1232.06363163996;1043.7964596903682;1066.9519871605796;1067.1569460126568;1211.2974474804591;1322.2793577500286;7493.331617781654;7538.886916696254;7595.088589369311;7604.505707129019;7605.961970190114;1129.7401597632427;1135.2280449294394;1143.868455127671;1145.2098052343856;1159.3971135009917;5843.2403787507355;5899.056151015817;5930.406959304035;5943.494802941712;5949;8880.422772519765;8951.641934322684;8977.50623715689;8985.820283594292;8995.640174392951;8769.517676527852;8829.072947340564;8882.86675699865;8899.599518021705;8926.419782714103;7476.70093196277;7745.6127193640095;7786.57173855432;7801.726939557088;7811.726589569224;7759.534427934342;7794.844103117793;7863.252990965849;7865.977422934981;7876;8474.491530508189;8515.574221289035;8555.700550480704;8696.304295656459;8782.253508451853;8892.110788921056;8904.643814247358;8919.464832110094;8935.374523783274;9162.633494660406;7169.928300717134;7241.891371629536;7253.601051942193;7265.382442492447;7272.345488906024;6608.768693095719;6695.464362851058;7024.8595028098125;7210.603416812126;7504.362129219077;6936.0636314096955;6995.895061574182;7008.824779380557;7049.083619129686;7052.894206586802;6764.594124352555;6894.379505844498;6910.170779506491;6966.303369662993;7019.4033507150425;7057.117860267466;7112.35988761014;7132.465065120033;7145.249748776304;7163.8567228656175;6196.597221180609;6211.472024877755;6280.497560195082;6295.150154729053;6296.275928268308;6235.127082208403;6251.468625166912;6251.53113516479;6267.153934219004;6293.056041593614 10:17:20 INFO - 2016-01-22 10:17:20,081 DEBUG : BROWSER_OUTPUT: |4;prototype.html;336.5775951181268;341.53892245468603;343.03948942959704;348.30686933003324;350.18057744876984;5928.733207005685;6006.129111278873;6027.517798576115;6065.848353791152;6072.240969878765;5908.970455147718;5919.23050003499;6074.574779765408;6077.24034495688;6093.78671746489;4665.83669571565;4681.672282940187;4686.484486706459;4688.882777930562;4690.319903613974;4445.288753799393;4543.772811359436;4683.789229484664;4690.780397096745;4696.64775141864;4463.977680111595;4481.282994720846;4510.661700372469;4574;4651.767411629405;4617.215073437524;4628.83799067031;4642.141203877293;4645.256758918556;4647.209974304376;4059.8376064957365;4064.898377540585;4066.552679205285;4067.8576249831112;4073.4704488416614;5671.659700417947;5800.274965629296;5807.477327040587;5812.709364531756;5830.4461076197695;5417.647852889589;5426.077566813612;5430.321209848769;5432.13085906257;5438.972805135989;6461.83845403864;6465.612063276171;6467.773627923048;6467.93532064678;6472.870542589122;4510.0979804039325;4515.954840451586;4517.435320584927;4518.683692141552;4519.751413672247;6532.216134063895;6708.731650734014;6712.83217919551;6716.429103526206;6718.697658605325;4350.108227813291;4474.910501789946;4487.640988720895;4592.88517787058;4605.171069207508;4644.256918893028;4660.5106463821485;4666.673332866667;4667.463241727177;4668;5357.4106848246665;5360.91958620621;5363.81226657073;5369.140937449989;5386.245925570423;6362.2683391409655;6509.218893732687;6530.542861999615;6531.1509503765155;6538.738450461929;1631.3230009545905;1650.5378494021693;1652.4712092130644;1656.4616499637666;1657.8839481236198;1081.8918108189275;1089.5097206257058;1099.69758316462;1105.7733164701217;1111.855458790352;6305.180326557518;6316.905246421215;6323.430891219812;6327.303996560375;6328.145700330397;6734.8989765153565;6735.8989615155815;6743.797686069426;6743.865122697617;6744.22441419243;6165.10605962133;6301.054841773679;6305.495560355161;6323.241211054611;6326.304106548276;3608.6030536640947;3639.5632524097277;3643.362411577963;3644.580873199563;3645.54430696163;6891.241963384024;6898.827529311808;6899.827504312432;6900.861982760317;6910.585364878123;6728.562643428161;6866.176058872968;6867.896981545281;6875.449964002868;6879.2432832388395;6854.931450685529;6868.553544019723;6884.65576721162;6886.10480637524;6886.173659160932;6688.130543029375;6702;6704.832379190364;6705.832354191184;6705.8994115087335;6493.707783149675;6591.813473574803;6620.073189753534;6628.90056649141;6631.071649969104;6720.529562930548;6859.211190713132;6860.3139686031;6882.724691012303;6932.445404367538;4414.0730446605285;4443.977780111079;4482.462104547475;4506.571875671806;4536.206163921366;6662.333766623299;6688.6990085445295;6725.831854203684;6730.899036514358;6739.73041078371;6432.388923052303;6515.869682606227;6532.673366331759;6536.281009088902;6546.116274302908;6517.576357536745;6518.771842985662;6529.9020514691365;6533.901991470037;6534;6356.4279214870885;6394.23269207698;6406.967965160145;6426.550141490051;6434.260060093149;6462.547621666627;6552.934470655233;6558.672066396756;6564.4748420125325;6572.244191917991;6377.649229292433;6401.743930242738;6405.519586030973;6405.647689376942;6406.199225096863 10:17:20 INFO - 2016-01-22 10:17:20,081 DEBUG : BROWSER_OUTPUT: |5;yui.html;795.3756301303478;852.4757274276318;861.8836457078295;938.5213541094042;948.3835506920501;14036.719265614694;14297.785533216984;14362.712745745079;14429.278536073194;14463.855361446382;13282.601521954333;13921.721565568683;13926.51257205998;13942.163470191796;14019.158850468979;533.1522878622989;542.5713686187906;883.4787475389519;901.179926267097;904.6110172625769;893.6023469556051;900.7703035725914;901.4861528928514;906.1255888068013;910.3172620534599;548.1448939654132;933.5332333833084;943.1982814607597;943.688582767685;945.5603144537788;550.7796881247493;928.8188803183382;941.7033634405209;943.1040511514054;943.3349488610513;697.8464737757686;698.888177891535;699.3146716218084;699.6641612026256;706.1561434086269;13461.259630720306;13789.862101378958;13800.240986745825;13809.4119176295;13817.170969741746;12701.618951431472;13245;13535.59393218205;13543.119697219649;13546.729065418636;10473.738156546071;10705.46472676363;10727.409992450412;10736.24846260762;10775.084117849992;3779.7354185207046;3848.1918797052654;3900.765954042738;3914.158455931988;3920.7255492115564;48740;49322.75338623284;49368.25947610753;49393.50606493961;49712.25431618529;10355.689329320132;10356.482175891175;10397.480125993821;10438.478076096164;10463.058324750657;19294.877563468006;19470.610587788164;19493.317733879245;19502.219911203676;19512.902435487733;8039.236272554098;8096.311813495919;8102.837943241102;8261.958690206511;8378.53918034514;4621.768911554409;4622.491525932145;4663.603593694506;4721.9055618888115;4735.313379560013;3201.807891526469;3224.0005598264142;3227.386796508656;3230.709236168756;3233.644299127094;572.6592677356966;575.1545228514161;575.5223164773312;581.7469400810661;591.0572636644545;682.2018238660779;686.2451303566038;700.7162099349772;707.844274259662;735.871222536054;8843.911560884437;9365.906340936503;9387.389819661701;9427.340086194037;9487.667931622498;549.4999550409109;601.9157317975487;671.3990978074596;744.4491076603383;757.5076200469629;4078.163976384834;4120.526139493936;4168.645665118499;4276.123394704078;4298.011457364823;60844;61207.693961529905;61486.69256653688;61562.38437615656;61569.692151538955;52454.47545524496;53109.46890531122;53178;53399.733001335524;53849.461505385225;48312.27531587029;48884.26673599899;49373.50626493761;49451.50548494541;50486.74756626267;8232.423730338818;8305.709300174465;8446.535440550828;8462.323014158976;8600.741977740678;34846;34866.302673946884;35766.105847354025;35812.28375432529;36613;43508.564914351715;44646.3303050448;45965.31052034289;47735.761321193175;48650;10506.054455099078;10652.467376631292;10657.745385028702;10776.67669969902;11073.889261107286;12303.93848030754;12396.256224626552;12411.131220814817;12480.625581232578;12508.124431289722;11179.496922638338;11417.257878237888;11709.707257318636;12273.631791046282;13074.346282686018;11936.28382297065;12250.816237756619;12283.140180187302;12288.324142172267;12657.493700251887;11797.17419780607;11983.76032479363;12090.335031573348;12181.025517958718;12433.875661243272;9449.527523623929;10139.89860101419;10254.692359229235;10435.94782026085;10652.201084918506;9704.466254355795;10350.430726310125;10392.37645741258;10523.684289471328;10540 10:17:20 INFO - 2016-01-22 10:17:20,081 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:17:20 INFO - 2016-01-22 10:17:20,081 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:17:20 INFO - 2016-01-22 10:17:20,082 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,957 10:17:20 INFO - 2016-01-22 10:17:20,082 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:17:20 INFO - 2016-01-22 10:17:20,082 DEBUG : BROWSER_OUTPUT: __startTimestamp1453486640070__endTimestamp 10:17:20 INFO - 2016-01-22 10:17:20,082 DEBUG : BROWSER_OUTPUT: 10:17:20 INFO - 2016-01-22 10:17:20,082 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:17:20 INFO - 2016-01-22 10:17:20,082 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:17:20 INFO - 2016-01-22 10:17:20,082 DEBUG : BROWSER_OUTPUT: 10:17:20 INFO - 2016-01-22 10:17:20,082 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5386.26 Median:5584.08 stddev:576.43 (10.3%) stddev-sans-first:553.24 10:17:20 INFO - 2016-01-22 10:17:20,083 DEBUG : BROWSER_OUTPUT: Values: 3247.6 3282.5 3291.9 3306.5 3308.9 5322.7 5434.7 5442.5 5461.6 5462.6 5428.2 5430.9 5449.6 5464.3 5470.9 4881.3 4947.1 4960.6 5019.1 5046.2 4928.4 4988.1 4991.9 4994.0 4994.2 4895.2 5006.9 5010.6 5025.5 5046.0 4911.2 4985.7 5000.4 5001.2 5006.3 4964.7 4997.9 5014.3 5020.4 5024.2 5032.9 5100.2 5133.2 5202.3 5254.1 5723.2 5735.9 5749.6 5756.2 5805.1 5772.7 5831.0 5847.0 5848.6 5849.0 5218.3 5341.9 5344.7 5439.2 5482.4 5856.2 5884.9 5886.7 5890.5 5896.6 5516.2 5715.5 5724.0 5728.0 5758.2 5620.2 5697.8 5709.8 5794.5 5796.6 5766.4 5809.3 5833.1 5845.5 5846.5 5798.2 5817.6 5829.5 5838.3 5854.3 4292.1 4380.5 4437.4 4488.0 4498.0 4030.1 4034.3 4044.1 4062.9 4067.6 5479.9 5521.7 5529.7 5534.6 5552.1 5630.0 5721.3 5741.7 5806.4 5857.7 5439.9 5465.6 5477.1 5482.5 5495.4 4934.6 5072.9 5082.1 5092.2 5121.2 5881.7 5926.7 5971.3 5971.8 5999.7 5779.7 5808.8 5821.1 5826.3 5834.5 5825.5 5966.3 5973.8 5984.0 6024.3 5576.1 5684.6 5688.9 5710.8 5711.0 5761.0 5777.0 5791.6 5805.9 5809.8 5459.7 5667.6 5679.9 5680.4 5690.2 5547.5 5584.1 5615.6 5635.9 5680.9 5574.6 5641.2 5644.5 5646.2 5653.7 5520.4 5708.0 5735.9 5773.5 5774.9 5470.3 5595.5 5596.9 5608.3 5612.4 10:17:20 INFO - 2016-01-22 10:17:20,084 DEBUG : BROWSER_OUTPUT: 10:17:20 INFO - 2016-01-22 10:17:20,084 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:49096.25 Median:36604.23 stddev:78005.14 (213.1%) stddev-sans-first:78138.87 10:17:20 INFO - 2016-01-22 10:17:20,084 DEBUG : BROWSER_OUTPUT: Values: 570.9 575.4 581.6 606.0 630.4 41414.1 42600.4 42610.4 42625.9 42655.0 47259.8 47471.0 47607.0 47608.8 47686.5 7836.3 7977.9 7985.9 9249.2 9431.0 11032.7 11304.2 11307.2 11307.8 11316.6 11004.9 11169.2 11170.2 11180.4 11184.6 8119.1 8291.8 8311.4 10396.8 11143.4 10797.4 10955.9 10958.6 10960.9 10961.6 44305.6 44614.6 44807.1 44923.8 44931.0 3322.2 3480.4 3482.0 3486.3 3909.3 33510.7 33917.3 33979.8 34037.5 34106.5 4077.8 4086.6 4194.5 4211.8 4249.6 304141.0 308064.0 308227.0 308521.0 309034.5 45890.8 46830.0 63841.4 64381.0 69926.7 65734.3 65743.3 65986.7 66152.0 66157.0 18276.2 18408.2 18563.4 18565.9 18580.6 14041.0 14083.9 14103.4 14113.6 14117.9 20847.6 21067.8 21088.5 21127.2 21166.7 7664.4 7893.4 7923.3 7934.8 7967.6 958.1 958.3 1071.4 1106.5 1116.5 26611.5 27106.3 30170.0 30898.2 31318.7 1037.5 1040.4 1064.1 1118.0 1223.9 13326.7 14274.7 14279.6 15770.7 16047.9 268879.0 272042.0 273358.0 273553.0 273976.0 39510.4 40338.4 53842.2 54832.5 55071.7 1461.1 1465.0 1465.5 1469.5 1476.8 28781.0 29020.3 29077.4 29719.3 30029.5 39196.2 39775.4 49405.3 49651.3 49691.3 315177.0 316894.0 318047.0 318278.0 318865.0 36400.0 36624.6 36629.3 36712.1 36908.8 49500.8 49976.8 50365.0 50501.7 50876.0 40402.8 41617.2 46893.5 48053.0 49078.8 42785.4 42881.4 42932.9 43073.0 43225.4 42314.8 42880.6 43229.8 43311.4 43354.1 36583.8 37835.2 38063.8 38475.2 38763.2 28469.3 28782.6 28992.9 29038.3 31286.0 10:17:20 INFO - 2016-01-22 10:17:20,084 DEBUG : BROWSER_OUTPUT: 10:17:20 INFO - 2016-01-22 10:17:20,085 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:32037.92 Median:38504.71 stddev:106156.44 (275.7%) stddev-sans-first:106427.59 10:17:20 INFO - 2016-01-22 10:17:20,085 DEBUG : BROWSER_OUTPUT: Values: 365.8 369.8 371.1 371.9 372.4 14191.6 16057.4 17032.0 17643.0 18207.6 18243.9 18416.6 18589.5 18718.3 18753.7 5567.0 5701.0 5701.2 5709.4 5713.2 4137.6 4219.6 5648.4 6023.1 6173.7 6021.9 6040.9 6073.5 6092.4 6132.1 5264.5 5325.9 5363.8 5393.7 5403.9 5149.0 5211.5 5224.4 5233.4 5453.7 11955.3 12486.8 12651.9 12657.4 12675.7 2117.1 2124.8 2137.8 2186.1 2212.6 6505.0 8624.9 9123.3 9153.1 9257.8 1808.6 1866.1 1884.0 1934.9 1940.2 63922.7 65629.3 65885.3 67264.3 67660.0 9109.8 9622.4 10606.7 10622.9 10630.2 13900.5 13907.4 13908.4 13912.7 13927.0 7939.5 7976.5 8082.0 8149.7 8204.4 7956.8 7994.2 8028.1 8041.8 8085.4 4054.5 4489.8 4489.9 4524.9 4577.1 1658.1 1699.7 1707.4 1713.9 1777.0 391.6 393.0 1012.2 1013.0 1018.8 9398.0 9792.5 10310.4 10579.5 10666.6 444.4 454.5 845.5 850.6 867.3 7323.6 7484.9 7606.9 7672.6 7705.5 636338.0 642732.0 643262.0 659431.0 662830.0 80805.0 86525.6 88194.0 89940.6 90749.5 1151.5 1169.4 1169.6 1180.3 1183.8 16521.9 16522.3 16600.4 16636.4 16948.3 37935.6 37998.4 38416.8 38592.6 40177.0 49185.8 50010.2 51130.5 51959.7 52679.2 9791.6 9934.3 10012.5 10112.7 10143.2 11731.7 11895.3 11916.8 12670.7 15829.6 12593.5 14399.6 14626.0 14635.6 14774.2 14500.6 14570.1 14616.5 14771.9 14878.6 14824.2 15488.8 15494.8 15528.7 15967.1 9492.0 9644.0 9665.6 10094.2 10104.7 9900.1 10068.9 14898.2 15338.5 15455.0 10:17:20 INFO - 2016-01-22 10:17:20,085 DEBUG : BROWSER_OUTPUT: 10:17:20 INFO - 2016-01-22 10:17:20,086 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5489.60 Median:6243.30 stddev:2864.57 (45.9%) stddev-sans-first:2856.67 10:17:20 INFO - 2016-01-22 10:17:20,086 DEBUG : BROWSER_OUTPUT: Values: 1469.6 1491.3 1499.0 1700.8 1855.0 6716.8 7740.6 7932.3 7956.1 7959.9 7558.8 7661.9 7684.8 7684.8 7735.2 1879.1 1903.7 1908.4 2206.0 2242.4 2190.1 2252.3 2253.5 2255.9 2257.7 2199.8 2221.4 2278.9 2279.3 2279.5 2170.8 2201.3 2220.5 2257.1 2257.9 2281.6 2282.7 2321.6 2334.3 2335.3 7882.4 7985.8 8003.6 8006.8 8019.5 4769.3 4828.2 4844.1 4851.6 4876.6 2925.6 2979.6 2979.7 2995.6 3132.3 978.2 990.4 1000.8 1022.4 1042.3 10902.3 10912.9 10922.2 10930.1 10931.0 4083.3 4102.1 4144.8 4154.0 4183.2 9340.1 9352.7 9398.3 9403.8 9412.3 4773.7 4797.3 4802.6 4803.5 4803.6 4348.6 4356.3 4358.9 4362.0 4371.7 3218.5 3234.3 3463.6 3738.8 3832.0 1196.1 1211.0 1215.4 1220.6 1232.1 1043.8 1067.0 1067.2 1211.3 1322.3 7493.3 7538.9 7595.1 7604.5 7606.0 1129.7 1135.2 1143.9 1145.2 1159.4 5843.2 5899.1 5930.4 5943.5 5949.0 8880.4 8951.6 8977.5 8985.8 8995.6 8769.5 8829.1 8882.9 8899.6 8926.4 7476.7 7745.6 7786.6 7801.7 7811.7 7759.5 7794.8 7863.3 7866.0 7876.0 8474.5 8515.6 8555.7 8696.3 8782.3 8892.1 8904.6 8919.5 8935.4 9162.6 7169.9 7241.9 7253.6 7265.4 7272.3 6608.8 6695.5 7024.9 7210.6 7504.4 6936.1 6995.9 7008.8 7049.1 7052.9 6764.6 6894.4 6910.2 6966.3 7019.4 7057.1 7112.4 7132.5 7145.2 7163.9 6196.6 6211.5 6280.5 6295.2 6296.3 6235.1 6251.5 6251.5 6267.2 6293.1 10:17:20 INFO - 2016-01-22 10:17:20,086 DEBUG : BROWSER_OUTPUT: 10:17:20 INFO - 2016-01-22 10:17:20,086 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5413.27 Median:6233.08 stddev:1638.53 (26.3%) stddev-sans-first:1598.21 10:17:20 INFO - 2016-01-22 10:17:20,086 DEBUG : BROWSER_OUTPUT: Values: 336.6 341.5 343.0 348.3 350.2 5928.7 6006.1 6027.5 6065.8 6072.2 5909.0 5919.2 6074.6 6077.2 6093.8 4665.8 4681.7 4686.5 4688.9 4690.3 4445.3 4543.8 4683.8 4690.8 4696.6 4464.0 4481.3 4510.7 4574.0 4651.8 4617.2 4628.8 4642.1 4645.3 4647.2 4059.8 4064.9 4066.6 4067.9 4073.5 5671.7 5800.3 5807.5 5812.7 5830.4 5417.6 5426.1 5430.3 5432.1 5439.0 6461.8 6465.6 6467.8 6467.9 6472.9 4510.1 4516.0 4517.4 4518.7 4519.8 6532.2 6708.7 6712.8 6716.4 6718.7 4350.1 4474.9 4487.6 4592.9 4605.2 4644.3 4660.5 4666.7 4667.5 4668.0 5357.4 5360.9 5363.8 5369.1 5386.2 6362.3 6509.2 6530.5 6531.2 6538.7 1631.3 1650.5 1652.5 1656.5 1657.9 1081.9 1089.5 1099.7 1105.8 1111.9 6305.2 6316.9 6323.4 6327.3 6328.1 6734.9 6735.9 6743.8 6743.9 6744.2 6165.1 6301.1 6305.5 6323.2 6326.3 3608.6 3639.6 3643.4 3644.6 3645.5 6891.2 6898.8 6899.8 6900.9 6910.6 6728.6 6866.2 6867.9 6875.4 6879.2 6854.9 6868.6 6884.7 6886.1 6886.2 6688.1 6702.0 6704.8 6705.8 6705.9 6493.7 6591.8 6620.1 6628.9 6631.1 6720.5 6859.2 6860.3 6882.7 6932.4 4414.1 4444.0 4482.5 4506.6 4536.2 6662.3 6688.7 6725.8 6730.9 6739.7 6432.4 6515.9 6532.7 6536.3 6546.1 6517.6 6518.8 6529.9 6533.9 6534.0 6356.4 6394.2 6407.0 6426.6 6434.3 6462.5 6552.9 6558.7 6564.5 6572.2 6377.6 6401.7 6405.5 6405.6 6406.2 10:17:20 INFO - 2016-01-22 10:17:20,087 DEBUG : BROWSER_OUTPUT: 10:17:20 INFO - 2016-01-22 10:17:20,087 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14423.76 Median:25293.54 stddev:16660.97 (65.9%) stddev-sans-first:16676.28 10:17:20 INFO - 2016-01-22 10:17:20,087 DEBUG : BROWSER_OUTPUT: Values: 795.4 852.5 861.9 938.5 948.4 14036.7 14297.8 14362.7 14429.3 14463.9 13282.6 13921.7 13926.5 13942.2 14019.2 533.2 542.6 883.5 901.2 904.6 893.6 900.8 901.5 906.1 910.3 548.1 933.5 943.2 943.7 945.6 550.8 928.8 941.7 943.1 943.3 697.8 698.9 699.3 699.7 706.2 13461.3 13789.9 13800.2 13809.4 13817.2 12701.6 13245.0 13535.6 13543.1 13546.7 10473.7 10705.5 10727.4 10736.2 10775.1 3779.7 3848.2 3900.8 3914.2 3920.7 48740.0 49322.8 49368.3 49393.5 49712.3 10355.7 10356.5 10397.5 10438.5 10463.1 19294.9 19470.6 19493.3 19502.2 19512.9 8039.2 8096.3 8102.8 8262.0 8378.5 4621.8 4622.5 4663.6 4721.9 4735.3 3201.8 3224.0 3227.4 3230.7 3233.6 572.7 575.2 575.5 581.7 591.1 682.2 686.2 700.7 707.8 735.9 8843.9 9365.9 9387.4 9427.3 9487.7 549.5 601.9 671.4 744.4 757.5 4078.2 4120.5 4168.6 4276.1 4298.0 60844.0 61207.7 61486.7 61562.4 61569.7 52454.5 53109.5 53178.0 53399.7 53849.5 48312.3 48884.3 49373.5 49451.5 50486.7 8232.4 8305.7 8446.5 8462.3 8600.7 34846.0 34866.3 35766.1 35812.3 36613.0 43508.6 44646.3 45965.3 47735.8 48650.0 10506.1 10652.5 10657.7 10776.7 11073.9 12303.9 12396.3 12411.1 12480.6 12508.1 11179.5 11417.3 11709.7 12273.6 13074.3 11936.3 12250.8 12283.1 12288.3 12657.5 11797.2 11983.8 12090.3 12181.0 12433.9 9449.5 10139.9 10254.7 10435.9 10652.2 9704.5 10350.4 10392.4 10523.7 10540.0 10:17:20 INFO - 2016-01-22 10:17:20,087 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:17:20 INFO - 2016-01-22 10:17:20,088 DEBUG : BROWSER_OUTPUT: 10:17:20 INFO - 2016-01-22 10:17:20,820 INFO : Browser exited with error code: 0 10:17:20 INFO - 2016-01-22 10:17:20,841 INFO : Completed test dromaeo_css (00:18:30) 10:17:20 INFO - 2016-01-22 10:17:20,842 INFO : Starting test kraken 10:17:20 INFO - 2016-01-22 10:17:20,842 DEBUG : operating with platform_type : linux_ 10:17:20 INFO - 2016-01-22 10:17:20,842 INFO : Initialising browser for kraken test... 10:17:20 INFO - 2016-01-22 10:17:20,848 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp39ViZc/profile http://localhost:53897/getInfo.html 10:17:24 INFO - 2016-01-22 10:17:24,960 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:17:24 INFO - 2016-01-22 10:17:24,960 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:17:24 INFO - 2016-01-22 10:17:24,960 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:17:24 INFO - 2016-01-22 10:17:24,960 DEBUG : BROWSER_OUTPUT: __metrics 10:17:25 INFO - 2016-01-22 10:17:25,125 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] 10:17:25 INFO - 2016-01-22 10:17:25,852 INFO : Browser initialized. 10:17:25 INFO - 2016-01-22 10:17:25,853 INFO : Running cycle 1/1 for kraken test... 10:17:25 INFO - 2016-01-22 10:17:25,853 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp39ViZc/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:17:37 INFO - 2016-01-22 10:17:37,661 DEBUG : BROWSER_OUTPUT: RSS: Main: 136667136 10:17:37 INFO - 2016-01-22 10:17:37,661 DEBUG : BROWSER_OUTPUT: 10:17:40 INFO - 2016-01-22 10:17:40,121 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53897/tests/kraken/driver.html?testName=audio-beat-detection) 10:17:40 INFO - 2016-01-22 10:17:40,703 DEBUG : BROWSER_OUTPUT: RSS: Main: 168427520 10:17:40 INFO - 2016-01-22 10:17:40,703 DEBUG : BROWSER_OUTPUT: 10:17:43 INFO - 2016-01-22 10:17:43,260 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53897/tests/kraken/driver.html?testName=audio-dft) 10:17:43 INFO - 2016-01-22 10:17:43,830 DEBUG : BROWSER_OUTPUT: RSS: Main: 167436288 10:17:43 INFO - 2016-01-22 10:17:43,831 DEBUG : BROWSER_OUTPUT: 10:17:46 INFO - 2016-01-22 10:17:46,618 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53897/tests/kraken/driver.html?testName=audio-fft) 10:17:47 INFO - 2016-01-22 10:17:47,179 DEBUG : BROWSER_OUTPUT: RSS: Main: 164888576 10:17:47 INFO - 2016-01-22 10:17:47,180 DEBUG : BROWSER_OUTPUT: 10:17:49 INFO - 2016-01-22 10:17:49,173 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53897/tests/kraken/driver.html?testName=audio-oscillator) 10:17:49 INFO - 2016-01-22 10:17:49,739 DEBUG : BROWSER_OUTPUT: RSS: Main: 164777984 10:17:49 INFO - 2016-01-22 10:17:49,740 DEBUG : BROWSER_OUTPUT: 10:17:51 INFO - 2016-01-22 10:17:51,912 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53897/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:17:52 INFO - 2016-01-22 10:17:52,466 DEBUG : BROWSER_OUTPUT: RSS: Main: 164839424 10:17:52 INFO - 2016-01-22 10:17:52,466 DEBUG : BROWSER_OUTPUT: 10:17:55 INFO - 2016-01-22 10:17:55,345 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53897/tests/kraken/driver.html?testName=imaging-darkroom) 10:17:55 INFO - 2016-01-22 10:17:55,911 DEBUG : BROWSER_OUTPUT: RSS: Main: 163500032 10:17:55 INFO - 2016-01-22 10:17:55,911 DEBUG : BROWSER_OUTPUT: 10:17:59 INFO - 2016-01-22 10:17:59,253 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53897/tests/kraken/driver.html?testName=imaging-desaturate) 10:17:59 INFO - 2016-01-22 10:17:59,822 DEBUG : BROWSER_OUTPUT: RSS: Main: 164073472 10:17:59 INFO - 2016-01-22 10:17:59,822 DEBUG : BROWSER_OUTPUT: 10:18:02 INFO - 2016-01-22 10:18:02,507 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53897/tests/kraken/driver.html?testName=json-parse-financial) 10:18:03 INFO - 2016-01-22 10:18:03,077 DEBUG : BROWSER_OUTPUT: RSS: Main: 164913152 10:18:03 INFO - 2016-01-22 10:18:03,077 DEBUG : BROWSER_OUTPUT: 10:18:04 INFO - 2016-01-22 10:18:04,591 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53897/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:18:05 INFO - 2016-01-22 10:18:05,153 DEBUG : BROWSER_OUTPUT: RSS: Main: 170078208 10:18:05 INFO - 2016-01-22 10:18:05,153 DEBUG : BROWSER_OUTPUT: 10:18:06 INFO - 2016-01-22 10:18:06,986 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53897/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:18:07 INFO - 2016-01-22 10:18:07,576 DEBUG : BROWSER_OUTPUT: RSS: Main: 167186432 10:18:07 INFO - 2016-01-22 10:18:07,576 DEBUG : BROWSER_OUTPUT: 10:18:09 INFO - 2016-01-22 10:18:09,580 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53897/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:18:10 INFO - 2016-01-22 10:18:10,146 DEBUG : BROWSER_OUTPUT: RSS: Main: 178700288 10:18:10 INFO - 2016-01-22 10:18:10,146 DEBUG : BROWSER_OUTPUT: 10:18:12 INFO - 2016-01-22 10:18:12,511 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53897/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:18:13 INFO - 2016-01-22 10:18:13,093 DEBUG : BROWSER_OUTPUT: RSS: Main: 182149120 10:18:13 INFO - 2016-01-22 10:18:13,093 DEBUG : BROWSER_OUTPUT: 10:18:15 INFO - 2016-01-22 10:18:15,609 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53897/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:18:16 INFO - 2016-01-22 10:18:16,176 DEBUG : BROWSER_OUTPUT: RSS: Main: 182669312 10:18:16 INFO - 2016-01-22 10:18:16,176 DEBUG : BROWSER_OUTPUT: 10:18:17 INFO - 2016-01-22 10:18:17,830 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53897/tests/kraken/driver.html?testName=ai-astar) 10:18:18 INFO - 2016-01-22 10:18:18,080 DEBUG : BROWSER_OUTPUT: RSS: Main: 239247360 10:18:18 INFO - 2016-01-22 10:18:18,081 DEBUG : BROWSER_OUTPUT: 10:18:18 INFO - 2016-01-22 10:18:18,084 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:18:18 INFO - 2016-01-22 10:18:18,084 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:18:18 INFO - 2016-01-22 10:18:18,084 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:18:18 INFO - 2016-01-22 10:18:18,084 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:18:18 INFO - 2016-01-22 10:18:18,085 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;102;109;111;109;98;108;108;107 10:18:18 INFO - 2016-01-22 10:18:18,085 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;144;126;146;161;127;146;154;121;157;125 10:18:18 INFO - 2016-01-22 10:18:18,085 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;159;161;159;161;161;160;163;159;158 10:18:18 INFO - 2016-01-22 10:18:18,085 DEBUG : BROWSER_OUTPUT: |3;audio-fft;77;70;71;154;72;72;72;73;151;73 10:18:18 INFO - 2016-01-22 10:18:18,085 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;92;136;92;92;93;132;93;93;131 10:18:18 INFO - 2016-01-22 10:18:18,085 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;108;109;109;109;109;110;111;109;109;109 10:18:18 INFO - 2016-01-22 10:18:18,085 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;145;145;146;145;146;146;146;145;146 10:18:18 INFO - 2016-01-22 10:18:18,086 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;96;96;95;96;95;95;96;95 10:18:18 INFO - 2016-01-22 10:18:18,086 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;57;57;56;58;57;57;57;54;56 10:18:18 INFO - 2016-01-22 10:18:18,086 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;69;69;69;69;70;69;69;76;69 10:18:18 INFO - 2016-01-22 10:18:18,086 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;80;79;80;82;81;80;82;82;81;80 10:18:18 INFO - 2016-01-22 10:18:18,086 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;116;114;112;112;124;124;124;114;114;120 10:18:18 INFO - 2016-01-22 10:18:18,086 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;160;140;144;147;130;143;142;141;153 10:18:18 INFO - 2016-01-22 10:18:18,086 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;69;66;63;66;66;66;65;62;64;66 10:18:18 INFO - 2016-01-22 10:18:18,086 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:18:18 INFO - 2016-01-22 10:18:18,087 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:18:18 INFO - 2016-01-22 10:18:18,087 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,906 10:18:18 INFO - 2016-01-22 10:18:18,087 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:18:18 INFO - 2016-01-22 10:18:18,087 DEBUG : BROWSER_OUTPUT: __startTimestamp1453486698082__endTimestamp 10:18:18 INFO - 2016-01-22 10:18:18,087 DEBUG : BROWSER_OUTPUT: 10:18:18 INFO - 2016-01-22 10:18:18,091 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:18:18 INFO - 2016-01-22 10:18:18,091 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:18:18 INFO - 2016-01-22 10:18:18,091 DEBUG : BROWSER_OUTPUT: 10:18:18 INFO - 2016-01-22 10:18:18,091 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.00 Median:109.00 stddev:3.97 (3.6%) stddev-sans-first:4.20 10:18:18 INFO - 2016-01-22 10:18:18,091 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 102.0 109.0 111.0 109.0 98.0 108.0 108.0 107.0 10:18:18 INFO - 2016-01-22 10:18:18,091 DEBUG : BROWSER_OUTPUT: 10:18:18 INFO - 2016-01-22 10:18:18,091 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.70 Median:146.00 stddev:14.76 (10.1%) stddev-sans-first:15.60 10:18:18 INFO - 2016-01-22 10:18:18,091 DEBUG : BROWSER_OUTPUT: Values: 144.0 126.0 146.0 161.0 127.0 146.0 154.0 121.0 157.0 125.0 10:18:18 INFO - 2016-01-22 10:18:18,091 DEBUG : BROWSER_OUTPUT: 10:18:18 INFO - 2016-01-22 10:18:18,091 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.00 Median:160.50 stddev:1.49 (0.9%) stddev-sans-first:1.54 10:18:18 INFO - 2016-01-22 10:18:18,091 DEBUG : BROWSER_OUTPUT: Values: 159.0 159.0 161.0 159.0 161.0 161.0 160.0 163.0 159.0 158.0 10:18:18 INFO - 2016-01-22 10:18:18,091 DEBUG : BROWSER_OUTPUT: 10:18:18 INFO - 2016-01-22 10:18:18,091 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.50 Median:72.00 stddev:33.79 (46.9%) stddev-sans-first:35.58 10:18:18 INFO - 2016-01-22 10:18:18,092 DEBUG : BROWSER_OUTPUT: Values: 77.0 70.0 71.0 154.0 72.0 72.0 72.0 73.0 151.0 73.0 10:18:18 INFO - 2016-01-22 10:18:18,092 DEBUG : BROWSER_OUTPUT: 10:18:18 INFO - 2016-01-22 10:18:18,092 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:104.60 Median:92.00 stddev:19.64 (21.4%) stddev-sans-first:20.30 10:18:18 INFO - 2016-01-22 10:18:18,092 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 136.0 92.0 92.0 93.0 132.0 93.0 93.0 131.0 10:18:18 INFO - 2016-01-22 10:18:18,092 DEBUG : BROWSER_OUTPUT: 10:18:18 INFO - 2016-01-22 10:18:18,092 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.79 (0.7%) stddev-sans-first:0.71 10:18:18 INFO - 2016-01-22 10:18:18,092 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 109.0 109.0 109.0 110.0 111.0 109.0 109.0 109.0 10:18:18 INFO - 2016-01-22 10:18:18,092 DEBUG : BROWSER_OUTPUT: 10:18:18 INFO - 2016-01-22 10:18:18,092 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.53 10:18:18 INFO - 2016-01-22 10:18:18,092 DEBUG : BROWSER_OUTPUT: Values: 147.0 145.0 145.0 146.0 145.0 146.0 146.0 146.0 145.0 146.0 10:18:18 INFO - 2016-01-22 10:18:18,092 DEBUG : BROWSER_OUTPUT: 10:18:18 INFO - 2016-01-22 10:18:18,092 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 10:18:18 INFO - 2016-01-22 10:18:18,092 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 96.0 96.0 95.0 96.0 95.0 95.0 96.0 95.0 10:18:18 INFO - 2016-01-22 10:18:18,092 DEBUG : BROWSER_OUTPUT: 10:18:18 INFO - 2016-01-22 10:18:18,092 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.30 Median:57.00 stddev:1.34 (2.3%) stddev-sans-first:1.13 10:18:18 INFO - 2016-01-22 10:18:18,092 DEBUG : BROWSER_OUTPUT: Values: 54.0 57.0 57.0 56.0 58.0 57.0 57.0 57.0 54.0 56.0 10:18:18 INFO - 2016-01-22 10:18:18,093 DEBUG : BROWSER_OUTPUT: 10:18:18 INFO - 2016-01-22 10:18:18,093 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:69.00 stddev:2.20 (3.2%) stddev-sans-first:2.32 10:18:18 INFO - 2016-01-22 10:18:18,093 DEBUG : BROWSER_OUTPUT: Values: 69.0 69.0 69.0 69.0 69.0 70.0 69.0 69.0 76.0 69.0 10:18:18 INFO - 2016-01-22 10:18:18,093 DEBUG : BROWSER_OUTPUT: 10:18:18 INFO - 2016-01-22 10:18:18,093 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:81.00 stddev:1.06 (1.3%) stddev-sans-first:1.09 10:18:18 INFO - 2016-01-22 10:18:18,093 DEBUG : BROWSER_OUTPUT: Values: 80.0 79.0 80.0 82.0 81.0 80.0 82.0 82.0 81.0 80.0 10:18:18 INFO - 2016-01-22 10:18:18,093 DEBUG : BROWSER_OUTPUT: 10:18:18 INFO - 2016-01-22 10:18:18,093 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.40 Median:118.00 stddev:5.08 (4.3%) stddev-sans-first:5.36 10:18:18 INFO - 2016-01-22 10:18:18,093 DEBUG : BROWSER_OUTPUT: Values: 116.0 114.0 112.0 112.0 124.0 124.0 124.0 114.0 114.0 120.0 10:18:18 INFO - 2016-01-22 10:18:18,093 DEBUG : BROWSER_OUTPUT: 10:18:18 INFO - 2016-01-22 10:18:18,093 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.50 Median:143.50 stddev:8.50 (5.9%) stddev-sans-first:8.44 10:18:18 INFO - 2016-01-22 10:18:18,093 DEBUG : BROWSER_OUTPUT: Values: 135.0 160.0 140.0 144.0 147.0 130.0 143.0 142.0 141.0 153.0 10:18:18 INFO - 2016-01-22 10:18:18,093 DEBUG : BROWSER_OUTPUT: 10:18:18 INFO - 2016-01-22 10:18:18,093 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:66.00 stddev:1.95 (2.9%) stddev-sans-first:1.54 10:18:18 INFO - 2016-01-22 10:18:18,093 DEBUG : BROWSER_OUTPUT: Values: 69.0 66.0 63.0 66.0 66.0 66.0 65.0 62.0 64.0 66.0 10:18:18 INFO - 2016-01-22 10:18:18,093 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:18:18 INFO - 2016-01-22 10:18:18,094 DEBUG : BROWSER_OUTPUT: 10:18:18 INFO - 2016-01-22 10:18:18,908 INFO : Browser exited with error code: 0 10:18:18 INFO - 2016-01-22 10:18:18,957 INFO : Completed test kraken (00:00:58) 10:18:18 INFO - 2016-01-22 10:18:18,957 INFO : Starting test v8_7 10:18:18 INFO - 2016-01-22 10:18:18,957 DEBUG : operating with platform_type : linux_ 10:18:18 INFO - 2016-01-22 10:18:18,957 INFO : Initialising browser for v8_7 test... 10:18:18 INFO - 2016-01-22 10:18:18,963 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmxajXV/profile http://localhost:53897/getInfo.html 10:18:22 INFO - 2016-01-22 10:18:22,950 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:18:22 INFO - 2016-01-22 10:18:22,950 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:18:22 INFO - 2016-01-22 10:18:22,950 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:18:22 INFO - 2016-01-22 10:18:22,950 DEBUG : BROWSER_OUTPUT: __metrics 10:18:23 INFO - 2016-01-22 10:18:23,099 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] 10:18:23 INFO - 2016-01-22 10:18:23,834 INFO : Browser initialized. 10:18:23 INFO - 2016-01-22 10:18:23,834 INFO : Running cycle 1/1 for v8_7 test... 10:18:23 INFO - 2016-01-22 10:18:23,834 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmxajXV/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:18:35 INFO - 2016-01-22 10:18:35,663 DEBUG : BROWSER_OUTPUT: RSS: Main: 137207808 10:18:35 INFO - 2016-01-22 10:18:35,663 DEBUG : BROWSER_OUTPUT: 10:18:57 INFO - 2016-01-22 10:18:57,020 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53897/tests/v8_7/run.html (next: http://localhost:53897/tests/v8_7/run.html) 10:18:57 INFO - 2016-01-22 10:18:57,278 DEBUG : BROWSER_OUTPUT: RSS: Main: 426614784 10:18:57 INFO - 2016-01-22 10:18:57,278 DEBUG : BROWSER_OUTPUT: 10:18:57 INFO - 2016-01-22 10:18:57,279 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:18:57 INFO - 2016-01-22 10:18:57,279 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:18:57 INFO - 2016-01-22 10:18:57,279 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:18:57 INFO - 2016-01-22 10:18:57,279 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:18:57 INFO - 2016-01-22 10:18:57,279 DEBUG : BROWSER_OUTPUT: |0;Richards;165.18004625041294 10:18:57 INFO - 2016-01-22 10:18:57,279 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;167.61649346295675 10:18:57 INFO - 2016-01-22 10:18:57,279 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.01785714285717 10:18:57 INFO - 2016-01-22 10:18:57,279 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5175.257731958763 10:18:57 INFO - 2016-01-22 10:18:57,279 DEBUG : BROWSER_OUTPUT: |4;RayTrace;956.0229445506692 10:18:57 INFO - 2016-01-22 10:18:57,279 DEBUG : BROWSER_OUTPUT: |5;Earley;558.3472920156337 10:18:57 INFO - 2016-01-22 10:18:57,280 DEBUG : BROWSER_OUTPUT: |6;Boyer;11142.857142857143 10:18:57 INFO - 2016-01-22 10:18:57,280 DEBUG : BROWSER_OUTPUT: |7;RegExp;24142.85714285714 10:18:57 INFO - 2016-01-22 10:18:57,280 DEBUG : BROWSER_OUTPUT: |8;Splay;526.3157894736842 10:18:57 INFO - 2016-01-22 10:18:57,280 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 10:18:57 INFO - 2016-01-22 10:18:57,280 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:18:57 INFO - 2016-01-22 10:18:57,280 DEBUG : BROWSER_OUTPUT: __startTimestamp1453486737271__endTimestamp 10:18:57 INFO - 2016-01-22 10:18:57,280 DEBUG : BROWSER_OUTPUT: 10:18:57 INFO - 2016-01-22 10:18:57,280 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:18:57 INFO - 2016-01-22 10:18:57,281 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:18:57 INFO - 2016-01-22 10:18:57,281 DEBUG : BROWSER_OUTPUT: 10:18:57 INFO - 2016-01-22 10:18:57,281 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.18 Median:165.18 stddev:0.00 (0.0%) 10:18:57 INFO - 2016-01-22 10:18:57,281 DEBUG : BROWSER_OUTPUT: Values: 165.2 10:18:57 INFO - 2016-01-22 10:18:57,281 DEBUG : BROWSER_OUTPUT: 10:18:57 INFO - 2016-01-22 10:18:57,281 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:167.62 Median:167.62 stddev:0.00 (0.0%) 10:18:57 INFO - 2016-01-22 10:18:57,281 DEBUG : BROWSER_OUTPUT: Values: 167.6 10:18:57 INFO - 2016-01-22 10:18:57,282 DEBUG : BROWSER_OUTPUT: 10:18:57 INFO - 2016-01-22 10:18:57,282 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.02 Median:279.02 stddev:0.00 (0.0%) 10:18:57 INFO - 2016-01-22 10:18:57,282 DEBUG : BROWSER_OUTPUT: Values: 279.0 10:18:57 INFO - 2016-01-22 10:18:57,282 DEBUG : BROWSER_OUTPUT: 10:18:57 INFO - 2016-01-22 10:18:57,282 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5175.26 Median:5175.26 stddev:0.00 (0.0%) 10:18:57 INFO - 2016-01-22 10:18:57,282 DEBUG : BROWSER_OUTPUT: Values: 5175.3 10:18:57 INFO - 2016-01-22 10:18:57,282 DEBUG : BROWSER_OUTPUT: 10:18:57 INFO - 2016-01-22 10:18:57,283 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:956.02 Median:956.02 stddev:0.00 (0.0%) 10:18:57 INFO - 2016-01-22 10:18:57,283 DEBUG : BROWSER_OUTPUT: Values: 956.0 10:18:57 INFO - 2016-01-22 10:18:57,283 DEBUG : BROWSER_OUTPUT: 10:18:57 INFO - 2016-01-22 10:18:57,283 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:558.35 Median:558.35 stddev:0.00 (0.0%) 10:18:57 INFO - 2016-01-22 10:18:57,283 DEBUG : BROWSER_OUTPUT: Values: 558.3 10:18:57 INFO - 2016-01-22 10:18:57,283 DEBUG : BROWSER_OUTPUT: 10:18:57 INFO - 2016-01-22 10:18:57,283 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:11142.86 Median:11142.86 stddev:0.00 (0.0%) 10:18:57 INFO - 2016-01-22 10:18:57,283 DEBUG : BROWSER_OUTPUT: Values: 11142.9 10:18:57 INFO - 2016-01-22 10:18:57,284 DEBUG : BROWSER_OUTPUT: 10:18:57 INFO - 2016-01-22 10:18:57,284 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24142.86 Median:24142.86 stddev:0.00 (0.0%) 10:18:57 INFO - 2016-01-22 10:18:57,284 DEBUG : BROWSER_OUTPUT: Values: 24142.9 10:18:57 INFO - 2016-01-22 10:18:57,284 DEBUG : BROWSER_OUTPUT: 10:18:57 INFO - 2016-01-22 10:18:57,284 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:526.32 Median:526.32 stddev:0.00 (0.0%) 10:18:57 INFO - 2016-01-22 10:18:57,284 DEBUG : BROWSER_OUTPUT: Values: 526.3 10:18:57 INFO - 2016-01-22 10:18:57,284 DEBUG : BROWSER_OUTPUT: 10:18:57 INFO - 2016-01-22 10:18:57,285 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 10:18:57 INFO - 2016-01-22 10:18:57,285 DEBUG : BROWSER_OUTPUT: Values: 4840.6 10:18:57 INFO - 2016-01-22 10:18:57,285 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:18:57 INFO - 2016-01-22 10:18:57,285 DEBUG : BROWSER_OUTPUT: 10:18:58 INFO - 2016-01-22 10:18:58,088 INFO : Browser exited with error code: 0 10:18:58 INFO - 2016-01-22 10:18:58,101 INFO : Completed test v8_7 (00:00:39) 10:18:58 INFO - 2016-01-22 10:18:58,323 INFO : Completed test suite (00:20:08) 10:18:58 INFO - 2016-01-22 10:18:58,323 DEBUG : Working with test: dromaeo_css 10:18:58 INFO - 2016-01-22 10:18:58,323 DEBUG : Generating results file: dromaeo_css 10:18:58 INFO - 2016-01-22 10:18:58,324 DEBUG : Working with test: kraken 10:18:58 INFO - 2016-01-22 10:18:58,324 DEBUG : Generating results file: kraken 10:18:58 INFO - 2016-01-22 10:18:58,325 INFO : javascript benchmark 10:18:58 INFO - 2016-01-22 10:18:58,325 DEBUG : Working with test: v8_7 10:18:58 INFO - 2016-01-22 10:18:58,325 DEBUG : Generating results file: v8_7 10:18:58 INFO - 2016-01-22 10:18:58,325 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:18:58 INFO - 2016-01-22 10:18:58,369 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,52,35]] 10:18:58 INFO - 2016-01-22 10:18:58,369 DEBUG : process_Request line: dromaeo_css 7169.70 graph.html#tests=[[72,52,35]] 10:18:58 INFO - 2016-01-22 10:18:58,369 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:18:58 INFO - 2016-01-22 10:18:58,397 DEBUG : process_Request line: kraken 1484.10 graph.html#tests=[[232,52,35]] 10:18:58 INFO - 2016-01-22 10:18:58,397 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:18:58 INFO - 2016-01-22 10:18:58,425 DEBUG : process_Request line: v8_7 23062.24 graph.html#tests=[[230,52,35]] 10:18:58 INFO - 2016-01-22 10:18:58,427 INFO : javascript benchmark 10:18:58 INFO - 2016-01-22 10:18:58,430 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3247.594050743657, 3282.5404443377915, 3291.9341613167735, 3306.5040243963417, 3308.8841890533836, 5322.733863306834, 5434.701091439974, 5442.510174084332, 5461.617686761928, 5462.562994960404, 5428.185772134182, 5430.91853622195, 5449.618526703132, 5464.289642346499, 5470.863228419282, 4881.267809828519, 4947.134251505972, 4960.578350840183, 5019.121653710605, 5046.167382381903, 4928.359313289267, 4988.127077761395, 4991.875203119915, 4993.950060499385, 4994.225894986283, 4895.167821470341, 5006.949930500703, 5010.574101201402, 5025.547700706936, 5046.041252162096, 4911.238757992517, 4985.725785081819, 5000.42495113063, 5001.17480615698, 5006.349174607314, 4964.702117872939, 4997.875053123665, 5014.2979982802435, 5020.447750747415, 5024.246363045572, 5032.924506132411, 5100.15847385181, 5133.153029750087, 5202.271681964528, 5254.133068043769, 5723.227364305807, 5735.942640573582, 5749.59752817303, 5756.222909907151, 5805.071188609802, 5772.7113644317615, 5830.970845145747, 5846.9707651461895, 5848.64908105515, 5849.0, 5218.347706536683, 5341.919871201935, 5344.652597581145, 5439.184122381595, 5482.369527504308, 5856.209411729447, 5884.852878677982, 5886.676232807236, 5890.4993075589055, 5896.616719913192, 5516.172574113915, 5715.48560629537, 5723.971380143073, 5727.971360143256, 5758.222639943638, 5620.156976453564, 5697.800576979786, 5709.7716091356715, 5794.50746686528, 5796.594238403271, 5766.394528574523, 5809.2738407699035, 5833.066709326487, 5845.503132233808, 5846.473817356458, 5798.159266906275, 5817.621854579439, 5829.475347218686, 5838.299404071538, 5854.297484301911, 4292.141571685675, 4380.540043295472, 4437.423134992495, 4487.955120448819, 4497.955020449754, 4030.1133750574827, 4034.293998550242, 4044.090079732037, 4062.918741625151, 4067.5525692173837, 5479.890402192013, 5521.69630670317, 5529.723513824292, 5534.557235421156, 5552.08390615552, 5629.971850140723, 5721.256236689287, 5741.684207368635, 5806.361300256968, 5857.70711464425, 5439.891202175934, 5465.617406781487, 5477.096279113981, 5482.451754824566, 5495.422980587061, 4934.629902757308, 5072.923906141411, 5082.1360368737405, 5092.1852503599985, 5121.155009423478, 5881.676507792027, 5926.733297001645, 5971.28344598651, 5971.820845374646, 5999.700014999233, 5779.682117483579, 5808.82573522795, 5821.068629019336, 5826.329972053099, 5834.504067154339, 5825.50483208932, 5966.254218222723, 5973.820785376446, 5984.0, 6024.277086749618, 5576.052071147834, 5684.63049901755, 5688.886222275614, 5710.828675139753, 5710.971445142914, 5760.971195143998, 5776.971115144397, 5791.565632577489, 5805.883882322414, 5809.76760929575, 5459.69971651563, 5667.631603945731, 5679.943200567942, 5680.40355762639, 5690.203371528072, 5547.5007249347755, 5584.078627026658, 5615.578831587729, 5635.9436405635415, 5680.857978550569, 5574.637648552831, 5641.238432811682, 5644.520215781705, 5646.153077038477, 5653.660780353192, 5520.392756796668, 5707.971460142673, 5735.8566035849435, 5773.53811695055, 5774.855628609318, 5470.343558772814, 5595.524380427709, 5596.86007849807, 5608.327000759935, 5612.438756124355], "unit": "ms", "name": "dojo.html", "value": 5350.23549065272}, {"lowerIsBetter": false, "replicates": [570.9000924838155, 575.4475703324808, 581.6422899916553, 605.9878802423951, 630.3885231325612, 41414.10101292911, 42600.360994585106, 42610.36084458732, 42625.93435164123, 42654.99999999996, 47259.763701181444, 47471.0, 47607.0, 47608.761956190174, 47686.52313476864, 7836.255555722198, 7977.880331795028, 7985.880211796828, 9249.167574918256, 9431.0, 11032.669019929415, 11304.208705390625, 11307.208495405284, 11307.830382544269, 11316.603918862842, 11004.944975275112, 11169.218154729131, 11170.162237832155, 11180.44097795111, 11184.552617895275, 8119.066307374639, 8291.792705182359, 8311.418200725951, 10396.844047339258, 11143.387113708743, 10797.40614266215, 10955.945220273848, 10958.56165753369, 10960.945195274053, 10961.616343427942, 44305.55694443079, 44614.55385446169, 44807.103857922986, 44923.77538112321, 44931.0, 3322.2026713588807, 3480.443129099332, 3482.0424383294544, 3486.2678837443914, 3909.2963266612, 33510.66489335124, 33917.321653567036, 33979.830100849584, 34037.489437658456, 34106.488402673735, 4077.7961101944784, 4086.5709100544314, 4194.538600753915, 4211.810468528924, 4249.617534421917, 304141.0, 308064.0, 308227.0, 308521.0, 309034.45482772664, 45890.77054614772, 46830.0, 63841.361586383544, 64381.0, 69926.65036674784, 65734.34265657282, 65743.34256657468, 65986.67006664936, 66152.0, 66157.0, 18276.177572009292, 18408.17163227658, 18563.35028274004, 18565.907170464332, 18580.628387432174, 14041.0, 14083.859161408254, 14103.365348559339, 14113.647158821112, 14117.929410352883, 20847.583048338947, 21067.789322106888, 21088.472788180417, 21127.154913803275, 21166.682499762515, 7664.397733065451, 7893.368530517545, 7923.326517246099, 7934.841303173905, 7967.56178410193, 958.0611001218705, 958.3339578992533, 1071.3786004117594, 1106.4799544214206, 1116.5031560955458, 26611.467770644478, 27106.32234194161, 30170.0, 30898.227544311572, 31318.686813132033, 1037.5227395398201, 1040.354979912444, 1064.1433645914958, 1117.9608713694981, 1223.8592561855323, 13326.733465330833, 14274.714505710035, 14279.57161285163, 15770.68458630844, 16047.913771209667, 268879.0, 272042.0, 273358.0, 273553.0, 273976.0, 39510.40734388986, 40338.394924076165, 53842.19236711453, 54832.451675483535, 55071.72464137734, 1461.1087236785552, 1464.9535733123496, 1465.4682215859727, 1469.4783351909919, 1476.7711004794273, 28781.0, 29020.27449313784, 29077.418451630427, 29719.257018573844, 30029.5495567571, 39196.21607567776, 39775.40336894891, 49405.25892111549, 49651.25523117228, 49691.254631179836, 315177.0, 316894.0, 318047.0, 318278.0, 318865.0, 36400.0, 36624.63375366319, 36629.26741465209, 36712.0821979442, 36908.81545592362, 49500.75249623729, 49976.75011624918, 50365.0, 50501.74749126231, 50876.0, 40402.79798600988, 41617.1676566473, 46893.53106469028, 48053.0, 49078.75460622674, 42785.35821962736, 42881.35677964771, 42932.88956187503, 43073.0, 43225.35161972636, 42314.788426057676, 42880.57119428766, 43229.783851081804, 43311.35032974571, 43354.132917340845, 36583.81708091443, 37835.24329513339, 38063.80968095142, 38475.230495389376, 38763.2247355057, 28469.288267793472, 28782.568261475677, 28992.855035724686, 29038.274043148245, 31286.0], "unit": "ms", "name": "ext.html", "value": 20222.4109369959}, {"lowerIsBetter": false, "replicates": [365.7659098177169, 369.84096838359505, 371.13710622801983, 371.9163188282635, 372.43947858472995, 14191.574252772434, 16057.437989670363, 17032.0, 17643.0, 18207.635847283047, 18243.90878045608, 18416.631667366677, 18589.535261618465, 18718.344857929977, 18753.7186942196, 5566.972165139168, 5701.0, 5701.2018317435595, 5709.4290570942985, 5713.17159011943, 4137.648299894513, 4219.578042195772, 5648.350439699426, 6023.126646636235, 6173.660448675321, 6021.879562408749, 6040.852238074774, 6073.453389194971, 6092.390760923917, 6132.080187971817, 5264.473552644724, 5325.920111198335, 5363.839084827422, 5393.6763794172475, 5403.918941215885, 5149.021685879671, 5211.478852114796, 5224.425313215544, 5233.4243233244315, 5453.672779633234, 11955.342456164908, 12486.75026499474, 12651.93674031624, 12657.367131643381, 12675.74648507034, 2117.068489864455, 2124.840636952235, 2137.839662025339, 2186.0709198590534, 2212.557488502306, 6504.9674751625935, 8624.878765760473, 9123.27013838898, 9153.08469153086, 9257.768555786159, 1808.5749848785524, 1866.0762922353385, 1883.9638198990501, 1934.8935808530525, 1940.2433051110079, 63922.68038659777, 65629.3437065628, 65885.34114658888, 67264.3273567263, 67660.0, 9109.81780364389, 9622.37454565451, 10606.681799546026, 10622.94688526568, 10630.20273479492, 13900.513482028282, 13907.374168162458, 13908.443662253396, 13912.721745565032, 13927.0, 7939.483933544301, 7976.521408715495, 8081.959590202011, 8149.674013039412, 8204.425690201628, 7956.801079973047, 7994.160613135653, 8028.11690714021, 8041.758747237593, 8085.434019618688, 4054.4526488924207, 4489.753063581535, 4489.932651010237, 4524.864254072383, 4577.130345234461, 1658.0963374960675, 1699.7195462748755, 1707.402409156785, 1713.8971661700339, 1776.9911150444166, 391.5594955674866, 392.9783861887624, 1012.2256473797551, 1012.987012987013, 1018.7656838927088, 9398.0, 9792.461414622265, 10310.432926188982, 10579.523921423555, 10666.573337066431, 444.4311281559609, 454.493240037356, 845.526505157114, 850.5619605902965, 867.3451544084176, 7323.633818309063, 7484.925150748531, 7606.885896711554, 7672.578008209491, 7705.460617756816, 636338.0, 642732.0, 643262.0, 659431.0, 662830.0, 80805.0, 86525.56737216399, 88194.0, 89940.55029724809, 90749.54625226832, 1151.5336288803048, 1169.351010189336, 1169.602335206017, 1180.2564384437921, 1183.798754211786, 16521.91739041297, 16522.339106436088, 16600.418985335455, 16636.417725379553, 16948.322067117177, 37935.62064379321, 37998.43002354912, 38416.80791596024, 38592.61407386002, 40177.0, 49185.754071229414, 50010.24984625161, 51130.48869511406, 51959.74020129875, 52679.209811853616, 9791.608335666493, 9934.254930880068, 10012.499375031364, 10112.696619101438, 10143.239257055602, 11731.706707332385, 11895.345755983504, 11916.821247681119, 12670.683232918882, 15829.604259893595, 12593.496260149492, 14399.568012959628, 14626.0, 14635.560933172022, 14774.18741969202, 14500.637484062983, 14570.05294655844, 14616.488422905148, 14771.852281477477, 14878.553643390716, 14824.184669843262, 15488.84511154874, 15494.767578486557, 15528.689426211187, 15967.121808300655, 9492.0, 9644.0, 9665.565049572926, 10094.192464602667, 10104.69685909424, 9900.108990190918, 10068.949655251678, 14898.1806000671, 15338.539843804703, 15455.0], "unit": "ms", "name": "jquery.html", "value": 8650.535455919384}, {"lowerIsBetter": false, "replicates": [1469.6472846516826, 1491.254372813593, 1498.9700205995878, 1700.7874015748032, 1854.972175417369, 6716.79849604512, 7740.612969351531, 7932.286094251516, 7956.085050219226, 7959.920400795998, 7558.773236802891, 7661.885071723915, 7684.807879803007, 7684.846303073935, 7735.187805280449, 1879.1449890299948, 1903.7334773131772, 1908.3893154190596, 2206.018321846783, 2242.360927135767, 2190.09111218844, 2252.2680128958073, 2253.54929014197, 2255.9436014099697, 2257.7064981552376, 2199.846010779246, 2221.3891179925567, 2278.9202377916777, 2279.31620513846, 2279.4985103277336, 2170.7829217078324, 2201.273579718689, 2220.500387412835, 2257.085880218514, 2257.8532395394245, 2281.6121259385945, 2282.7032485776745, 2321.605327094398, 2334.299710086967, 2335.2760644200353, 7882.448228623999, 7985.800354991114, 8003.639836207326, 8006.759797206093, 8019.518828870287, 4769.260764581495, 4828.227483602606, 4844.103840789465, 4851.636127290432, 4876.609871210295, 2925.575791510219, 2979.582858399826, 2979.702029797025, 2995.5656429817773, 3132.3265497918055, 978.1978777402533, 990.3513198854762, 1000.7598176437675, 1022.4018948914894, 1042.3276986343842, 10902.345859248471, 10912.890871091267, 10922.235443518957, 10930.070943969773, 10930.99999999992, 4083.3262511685834, 4102.056526998807, 4144.772037537964, 4153.958460415418, 4183.163367326547, 9340.112689294507, 9352.672656457127, 9398.34211605181, 9403.764905877271, 9412.294077944181, 4773.66584339093, 4797.2564252540915, 4802.591779698695, 4803.519648035169, 4803.5916947059195, 4348.608625223682, 4356.324769660708, 4358.912821743611, 4361.978190109029, 4371.693981421334, 3218.5494030835703, 3234.3046245057426, 3463.584369875631, 3738.7569807962395, 3832.0228341773227, 1196.1387800783596, 1210.9878901211052, 1215.4044518186024, 1220.5850010996126, 1232.06363163996, 1043.7964596903682, 1066.9519871605796, 1067.1569460126568, 1211.2974474804591, 1322.2793577500286, 7493.331617781654, 7538.886916696254, 7595.088589369311, 7604.505707129019, 7605.961970190114, 1129.7401597632427, 1135.2280449294394, 1143.868455127671, 1145.2098052343856, 1159.3971135009917, 5843.2403787507355, 5899.056151015817, 5930.406959304035, 5943.494802941712, 5949.0, 8880.422772519765, 8951.641934322684, 8977.50623715689, 8985.820283594292, 8995.640174392951, 8769.517676527852, 8829.072947340564, 8882.86675699865, 8899.599518021705, 8926.419782714103, 7476.70093196277, 7745.6127193640095, 7786.57173855432, 7801.726939557088, 7811.726589569224, 7759.534427934342, 7794.844103117793, 7863.252990965849, 7865.977422934981, 7876.0, 8474.491530508189, 8515.574221289035, 8555.700550480704, 8696.304295656459, 8782.253508451853, 8892.110788921056, 8904.643814247358, 8919.464832110094, 8935.374523783274, 9162.633494660406, 7169.928300717134, 7241.891371629536, 7253.601051942193, 7265.382442492447, 7272.345488906024, 6608.768693095719, 6695.464362851058, 7024.8595028098125, 7210.603416812126, 7504.362129219077, 6936.0636314096955, 6995.895061574182, 7008.824779380557, 7049.083619129686, 7052.894206586802, 6764.594124352555, 6894.379505844498, 6910.170779506491, 6966.303369662993, 7019.4033507150425, 7057.117860267466, 7112.35988761014, 7132.465065120033, 7145.249748776304, 7163.8567228656175, 6196.597221180609, 6211.472024877755, 6280.497560195082, 6295.150154729053, 6296.275928268308, 6235.127082208403, 6251.468625166912, 6251.53113516479, 6267.153934219004, 6293.056041593614], "unit": "ms", "name": "mootools.html", "value": 4498.0654842039}, {"lowerIsBetter": false, "replicates": [336.5775951181268, 341.53892245468603, 343.03948942959704, 348.30686933003324, 350.18057744876984, 5928.733207005685, 6006.129111278873, 6027.517798576115, 6065.848353791152, 6072.240969878765, 5908.970455147718, 5919.23050003499, 6074.574779765408, 6077.24034495688, 6093.78671746489, 4665.83669571565, 4681.672282940187, 4686.484486706459, 4688.882777930562, 4690.319903613974, 4445.288753799393, 4543.772811359436, 4683.789229484664, 4690.780397096745, 4696.64775141864, 4463.977680111595, 4481.282994720846, 4510.661700372469, 4574.0, 4651.767411629405, 4617.215073437524, 4628.83799067031, 4642.141203877293, 4645.256758918556, 4647.209974304376, 4059.8376064957365, 4064.898377540585, 4066.552679205285, 4067.8576249831112, 4073.4704488416614, 5671.659700417947, 5800.274965629296, 5807.477327040587, 5812.709364531756, 5830.4461076197695, 5417.647852889589, 5426.077566813612, 5430.321209848769, 5432.13085906257, 5438.972805135989, 6461.83845403864, 6465.612063276171, 6467.773627923048, 6467.93532064678, 6472.870542589122, 4510.0979804039325, 4515.954840451586, 4517.435320584927, 4518.683692141552, 4519.751413672247, 6532.216134063895, 6708.731650734014, 6712.83217919551, 6716.429103526206, 6718.697658605325, 4350.108227813291, 4474.910501789946, 4487.640988720895, 4592.88517787058, 4605.171069207508, 4644.256918893028, 4660.5106463821485, 4666.673332866667, 4667.463241727177, 4668.0, 5357.4106848246665, 5360.91958620621, 5363.81226657073, 5369.140937449989, 5386.245925570423, 6362.2683391409655, 6509.218893732687, 6530.542861999615, 6531.1509503765155, 6538.738450461929, 1631.3230009545905, 1650.5378494021693, 1652.4712092130644, 1656.4616499637666, 1657.8839481236198, 1081.8918108189275, 1089.5097206257058, 1099.69758316462, 1105.7733164701217, 1111.855458790352, 6305.180326557518, 6316.905246421215, 6323.430891219812, 6327.303996560375, 6328.145700330397, 6734.8989765153565, 6735.8989615155815, 6743.797686069426, 6743.865122697617, 6744.22441419243, 6165.10605962133, 6301.054841773679, 6305.495560355161, 6323.241211054611, 6326.304106548276, 3608.6030536640947, 3639.5632524097277, 3643.362411577963, 3644.580873199563, 3645.54430696163, 6891.241963384024, 6898.827529311808, 6899.827504312432, 6900.861982760317, 6910.585364878123, 6728.562643428161, 6866.176058872968, 6867.896981545281, 6875.449964002868, 6879.2432832388395, 6854.931450685529, 6868.553544019723, 6884.65576721162, 6886.10480637524, 6886.173659160932, 6688.130543029375, 6702.0, 6704.832379190364, 6705.832354191184, 6705.8994115087335, 6493.707783149675, 6591.813473574803, 6620.073189753534, 6628.90056649141, 6631.071649969104, 6720.529562930548, 6859.211190713132, 6860.3139686031, 6882.724691012303, 6932.445404367538, 4414.0730446605285, 4443.977780111079, 4482.462104547475, 4506.571875671806, 4536.206163921366, 6662.333766623299, 6688.6990085445295, 6725.831854203684, 6730.899036514358, 6739.73041078371, 6432.388923052303, 6515.869682606227, 6532.673366331759, 6536.281009088902, 6546.116274302908, 6517.576357536745, 6518.771842985662, 6529.9020514691365, 6533.901991470037, 6534.0, 6356.4279214870885, 6394.23269207698, 6406.967965160145, 6426.550141490051, 6434.260060093149, 6462.547621666627, 6552.934470655233, 6558.672066396756, 6564.4748420125325, 6572.244191917991, 6377.649229292433, 6401.743930242738, 6405.519586030973, 6405.647689376942, 6406.199225096863], "unit": "ms", "name": "prototype.html", "value": 4881.382764792625}, {"lowerIsBetter": false, "replicates": [795.3756301303478, 852.4757274276318, 861.8836457078295, 938.5213541094042, 948.3835506920501, 14036.719265614694, 14297.785533216984, 14362.712745745079, 14429.278536073194, 14463.855361446382, 13282.601521954333, 13921.721565568683, 13926.51257205998, 13942.163470191796, 14019.158850468979, 533.1522878622989, 542.5713686187906, 883.4787475389519, 901.179926267097, 904.6110172625769, 893.6023469556051, 900.7703035725914, 901.4861528928514, 906.1255888068013, 910.3172620534599, 548.1448939654132, 933.5332333833084, 943.1982814607597, 943.688582767685, 945.5603144537788, 550.7796881247493, 928.8188803183382, 941.7033634405209, 943.1040511514054, 943.3349488610513, 697.8464737757686, 698.888177891535, 699.3146716218084, 699.6641612026256, 706.1561434086269, 13461.259630720306, 13789.862101378958, 13800.240986745825, 13809.4119176295, 13817.170969741746, 12701.618951431472, 13245.0, 13535.59393218205, 13543.119697219649, 13546.729065418636, 10473.738156546071, 10705.46472676363, 10727.409992450412, 10736.24846260762, 10775.084117849992, 3779.7354185207046, 3848.1918797052654, 3900.765954042738, 3914.158455931988, 3920.7255492115564, 48740.0, 49322.75338623284, 49368.25947610753, 49393.50606493961, 49712.25431618529, 10355.689329320132, 10356.482175891175, 10397.480125993821, 10438.478076096164, 10463.058324750657, 19294.877563468006, 19470.610587788164, 19493.317733879245, 19502.219911203676, 19512.902435487733, 8039.236272554098, 8096.311813495919, 8102.837943241102, 8261.958690206511, 8378.53918034514, 4621.768911554409, 4622.491525932145, 4663.603593694506, 4721.9055618888115, 4735.313379560013, 3201.807891526469, 3224.0005598264142, 3227.386796508656, 3230.709236168756, 3233.644299127094, 572.6592677356966, 575.1545228514161, 575.5223164773312, 581.7469400810661, 591.0572636644545, 682.2018238660779, 686.2451303566038, 700.7162099349772, 707.844274259662, 735.871222536054, 8843.911560884437, 9365.906340936503, 9387.389819661701, 9427.340086194037, 9487.667931622498, 549.4999550409109, 601.9157317975487, 671.3990978074596, 744.4491076603383, 757.5076200469629, 4078.163976384834, 4120.526139493936, 4168.645665118499, 4276.123394704078, 4298.011457364823, 60844.0, 61207.693961529905, 61486.69256653688, 61562.38437615656, 61569.692151538955, 52454.47545524496, 53109.46890531122, 53178.0, 53399.733001335524, 53849.461505385225, 48312.27531587029, 48884.26673599899, 49373.50626493761, 49451.50548494541, 50486.74756626267, 8232.423730338818, 8305.709300174465, 8446.535440550828, 8462.323014158976, 8600.741977740678, 34846.0, 34866.302673946884, 35766.105847354025, 35812.28375432529, 36613.0, 43508.564914351715, 44646.3303050448, 45965.31052034289, 47735.761321193175, 48650.0, 10506.054455099078, 10652.467376631292, 10657.745385028702, 10776.67669969902, 11073.889261107286, 12303.93848030754, 12396.256224626552, 12411.131220814817, 12480.625581232578, 12508.124431289722, 11179.496922638338, 11417.257878237888, 11709.707257318636, 12273.631791046282, 13074.346282686018, 11936.28382297065, 12250.816237756619, 12283.140180187302, 12288.324142172267, 12657.493700251887, 11797.17419780607, 11983.76032479363, 12090.335031573348, 12181.025517958718, 12433.875661243272, 9449.527523623929, 10139.89860101419, 10254.692359229235, 10435.94782026085, 10652.201084918506, 9704.466254355795, 10350.430726310125, 10392.37645741258, 10523.684289471328, 10540.0], "unit": "ms", "name": "yui.html", "value": 6609.158122788109}], "name": "dromaeo_css", "value": 7169.6954007600025}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 102.0, 109.0, 111.0, 109.0, 98.0, 108.0, 108.0, 107.0], "unit": "ms", "name": "ai-astar", "value": 107.0}, {"lowerIsBetter": true, "replicates": [144.0, 126.0, 146.0, 161.0, 127.0, 146.0, 154.0, 121.0, 157.0, 125.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.7}, {"lowerIsBetter": true, "replicates": [159.0, 159.0, 161.0, 159.0, 161.0, 161.0, 160.0, 163.0, 159.0, 158.0], "unit": "ms", "name": "audio-dft", "value": 160.0}, {"lowerIsBetter": true, "replicates": [77.0, 70.0, 71.0, 154.0, 72.0, 72.0, 72.0, 73.0, 151.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 88.5}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 136.0, 92.0, 92.0, 93.0, 132.0, 93.0, 93.0, 131.0], "unit": "ms", "name": "audio-oscillator", "value": 104.6}, {"lowerIsBetter": true, "replicates": [108.0, 109.0, 109.0, 109.0, 109.0, 110.0, 111.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [147.0, 145.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [54.0, 57.0, 57.0, 56.0, 58.0, 57.0, 57.0, 57.0, 54.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 56.3}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 69.0, 69.0, 69.0, 70.0, 69.0, 69.0, 76.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.8}, {"lowerIsBetter": true, "replicates": [80.0, 79.0, 80.0, 82.0, 81.0, 80.0, 82.0, 82.0, 81.0, 80.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 80.7}, {"lowerIsBetter": true, "replicates": [116.0, 114.0, 112.0, 112.0, 124.0, 124.0, 124.0, 114.0, 114.0, 120.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 117.4}, {"lowerIsBetter": true, "replicates": [135.0, 160.0, 140.0, 144.0, 147.0, 130.0, 143.0, 142.0, 141.0, 153.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 143.5}, {"lowerIsBetter": true, "replicates": [69.0, 66.0, 63.0, 66.0, 66.0, 66.0, 65.0, 62.0, 64.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.3}], "name": "kraken", "value": 1484.1000000000001}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.18004625041294], "unit": "score", "name": "Richards", "value": 213.71830799999998}, {"lowerIsBetter": false, "replicates": [167.61649346295675], "unit": "score", "name": "DeltaBlue", "value": 394.4599879999999}, {"lowerIsBetter": false, "replicates": [279.01785714285717], "unit": "score", "name": "Encrypt", "value": 953.9927040000001}, {"lowerIsBetter": false, "replicates": [5175.257731958763], "unit": "score", "name": "Decrypt", "value": 51.433380478087685}, {"lowerIsBetter": false, "replicates": [956.0229445506692], "unit": "score", "name": "RayTrace", "value": 774.0284939999998}, {"lowerIsBetter": false, "replicates": [558.3472920156337], "unit": "score", "name": "Earley", "value": 1193.6352329999995}, {"lowerIsBetter": false, "replicates": [11142.857142857143], "unit": "score", "name": "Boyer", "value": 59.81078205128202}, {"lowerIsBetter": false, "replicates": [24142.85714285714], "unit": "score", "name": "RegExp", "value": 37.73310650887576}, {"lowerIsBetter": false, "replicates": [526.3157894736842], "unit": "score", "name": "Splay", "value": 154.83289999999997}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 23062.239175960534}]} 10:18:58 INFO - RETURN: dromaeo_css: 7169.70 10:18:58 INFO - RETURN: kraken: 1484.10 10:18:58 INFO - RETURN: v8_7: 23062.24 10:18:58 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,52,35]]", "result": "23062.24"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,52,35]]", "result": "1484.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,52,35]]", "result": "7169.70"}}} 10:18:58 INFO - Return code: 0 10:18:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 10:18:58 INFO - # TBPL SUCCESS # 10:18:58 INFO - Running post-action listener: _resource_record_post_action 10:18:58 INFO - Running post-run listener: _resource_record_post_run 10:18:59 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 0; Write bytes: 507785216; Read time: 0; Write time: 474640 10:18:59 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 237268992; Read time: 0; Write time: 250964 10:18:59 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 0; Write bytes: 270217216; Read time: 0; Write time: 223648 10:18:59 INFO - Running post-run listener: _upload_blobber_files 10:18:59 INFO - Blob upload gear active. 10:18:59 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:18:59 INFO - Copying logs to upload dir... 10:18:59 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1258.676622 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-01-22 10:18:59.496845) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-22 10:18:59.500150) ========= 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=c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/dspZIrreQbuDI2eT32GVtA/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010155 build_url: 'https://queue.taskcluster.net/v1/task/dspZIrreQbuDI2eT32GVtA/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-22 10:18:59.552241) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 10:18:59.552604) ========= 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=c6927cf182745ad25b1d38b9000001fe-1453485475.94545-736684468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004670 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 10:18:59.602957) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-22 10:18:59.603303) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-22 10:18:59.603625) ========= ========= Total master_lag: 0.27 =========