builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-045 starttime: 1489680609.47 results: success (0) revision: 3945f2297b997d59662bdb8ac20e092363eea532 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-16 09:10:09.469568) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-16 09:10:09.470086) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-16 09:10:09.470422) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-16 09:10:09.486546) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:10:09.486911) ========= 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/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1489680608.599323-2080363727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-16 09:10:09-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.8M=0s 2017-03-16 09:10:10 (42.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080322 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:10:09.584894) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:10:09.585494) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', '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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1489680608.599323-2080363727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023114 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:10:09.635839) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-16 09:10:09.636224) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3945f2297b997d59662bdb8ac20e092363eea532 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 3945f2297b997d59662bdb8ac20e092363eea532 --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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1489680608.599323-2080363727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-16 09:10:10,141 truncating revision to first 12 chars 2017-03-16 09:10:10,141 Setting DEBUG logging. 2017-03-16 09:10:10,141 attempt 1/10 2017-03-16 09:10:10,141 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3945f2297b99?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-16 09:10:11,369 unpacking tar archive at: mozilla-central-3945f2297b99/testing/mozharness/ program finished with exit code 0 elapsedTime=1.547824 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-16 09:10:11.197383) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-16 09:10:11.197767) ========= script_repo_revision: 3945f2297b997d59662bdb8ac20e092363eea532 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-16 09:10:11.198179) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-16 09:10:11.198481) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-16 09:10:11.209083) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-03-16 09:10:11.209436) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1489680608.599323-2080363727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:10:11 INFO - MultiFileLogger online at 20170316 09:10:11 in /builds/slave/test 09:10:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 09:10:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:10:11 INFO - {'append_to_log': False, 09:10:11 INFO - 'base_work_dir': '/builds/slave/test', 09:10:11 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 09:10:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:10:11 INFO - 'branch': 'Firefox-Non-PGO', 09:10:11 INFO - 'buildbot_json_path': 'buildprops.json', 09:10:11 INFO - 'config_files': ('talos/linux_config.py',), 09:10:11 INFO - 'default_actions': ('clobber', 09:10:11 INFO - 'read-buildbot-config', 09:10:11 INFO - 'download-and-extract', 09:10:11 INFO - 'populate-webroot', 09:10:11 INFO - 'create-virtualenv', 09:10:11 INFO - 'install', 09:10:11 INFO - 'run-tests'), 09:10:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:10:11 INFO - 'download_minidump_stackwalk': True, 09:10:11 INFO - 'download_symbols': 'ondemand', 09:10:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:10:11 INFO - 'tooltool.py': '/tools/tooltool.py', 09:10:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:10:11 INFO - '/tools/misc-python/virtualenv.py')}, 09:10:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:10:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:10:11 INFO - 'gecko_profile': False, 09:10:11 INFO - 'gecko_profile_interval': 0, 09:10:11 INFO - 'installer_path': 'installer.exe', 09:10:11 INFO - 'log_level': 'info', 09:10:11 INFO - 'log_name': 'talos', 09:10:11 INFO - 'log_to_console': True, 09:10:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:10:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:10:11 INFO - 'opt_config_files': (), 09:10:11 INFO - 'pip_index': False, 09:10:11 INFO - 'suite': 'dromaeojs-e10s', 09:10:11 INFO - 'system_bits': '32', 09:10:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:10:11 INFO - 'title': 'talos-linux64-ix-045', 09:10:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:10:11 INFO - 'use_talos_json': True, 09:10:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:10:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:10:11 INFO - 'work_dir': 'build'} 09:10:11 INFO - [mozharness: 2017-03-16 16:10:11.804345Z] Running clobber step. 09:10:11 INFO - Running pre-action listener: _resource_record_pre_action 09:10:11 INFO - Running main action method: clobber 09:10:11 INFO - rmtree: /builds/slave/test/build 09:10:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:10:13 INFO - Running post-action listener: _resource_record_post_action 09:10:13 INFO - [mozharness: 2017-03-16 16:10:13.274807Z] Finished clobber step (success) 09:10:13 INFO - [mozharness: 2017-03-16 16:10:13.274904Z] Running read-buildbot-config step. 09:10:13 INFO - Running pre-action listener: _resource_record_pre_action 09:10:13 INFO - Running main action method: read_buildbot_config 09:10:13 INFO - Using buildbot properties: 09:10:13 INFO - { 09:10:13 INFO - "project": "", 09:10:13 INFO - "product": "firefox", 09:10:13 INFO - "who": "ffxbld@noreply.mozilla.org", 09:10:13 INFO - "installer_path": "public/build/target.tar.bz2", 09:10:13 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 09:10:13 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:10:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 09:10:13 INFO - "stage_platform": "linux64", 09:10:13 INFO - "basedir": "/builds/slave/test", 09:10:13 INFO - "buildnumber": 756, 09:10:13 INFO - "platform": "ubuntu64_hw", 09:10:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:10:13 INFO - "slavebuilddir": "test", 09:10:13 INFO - "taskId": "T3gtPbLcQRC51SMhUAtOGg", 09:10:13 INFO - "branch": "mozilla-central", 09:10:13 INFO - "script_repo_revision": "production", 09:10:13 INFO - "revision": "3945f2297b997d59662bdb8ac20e092363eea532", 09:10:13 INFO - "slavename": "talos-linux64-ix-045", 09:10:13 INFO - "repo_path": "mozilla-central" 09:10:13 INFO - } 09:10:13 INFO - Finding installer, test and symbols from parent task. 09:10:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T3gtPbLcQRC51SMhUAtOGg'}, attempt #1 09:10:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T3gtPbLcQRC51SMhUAtOGg'}, attempt #1 09:10:14 INFO - Task dependencies: QhLHFfA6STCaHx-DR6J_Ew 09:10:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew'}, attempt #1 09:10:14 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.tar.bz2 09:10:14 INFO - Set test_url: https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/test_packages.json 09:10:14 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.crashreporter-symbols.zip 09:10:14 INFO - Running post-action listener: _resource_record_post_action 09:10:14 INFO - [mozharness: 2017-03-16 16:10:14.666640Z] Finished read-buildbot-config step (success) 09:10:14 INFO - [mozharness: 2017-03-16 16:10:14.666857Z] Running download-and-extract step. 09:10:14 INFO - Running pre-action listener: _resource_record_pre_action 09:10:14 INFO - Running main action method: download_and_extract 09:10:14 INFO - mkdir: /builds/slave/test/build/tests 09:10:14 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:14 INFO - trying https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.test_packages.json 09:10:14 INFO - Downloading https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:10:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:10:15 INFO - Downloaded 986 bytes. 09:10:15 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:10:15 INFO - Using the following test package requirements: 09:10:15 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:10:15 INFO - u'common': [u'target.common.tests.zip'], 09:10:15 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:10:15 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:10:15 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:10:15 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:10:15 INFO - u'mozbase': [u'target.common.tests.zip'], 09:10:15 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:10:15 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:10:15 INFO - u'web-platform': [u'target.common.tests.zip', 09:10:15 INFO - u'target.web-platform.tests.zip'], 09:10:15 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:10:15 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:10:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.common.tests.zip 09:10:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:10:15 INFO - Fetch https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.common.tests.zip into memory 09:10:16 INFO - Expected file size: 58186228 09:10:16 INFO - Obtained file size: 58186228 09:10:26 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:10:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.common.tests.zip 09:10:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:10:26 INFO - Fetch https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.common.tests.zip into memory 09:10:27 INFO - Expected file size: 58186228 09:10:27 INFO - Obtained file size: 58186228 09:10:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.talos.tests.zip 09:10:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:10:38 INFO - Fetch https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.talos.tests.zip into memory 09:10:39 INFO - Expected file size: 14404452 09:10:39 INFO - Obtained file size: 14404452 09:10:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:39 INFO - trying https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.tar.bz2 09:10:39 INFO - Downloading https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:10:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:10:40 INFO - Downloaded 61664029 bytes. 09:10:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.tar.bz2 09:10:40 INFO - mkdir: /builds/slave/test/properties 09:10:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:10:40 INFO - Writing to file /builds/slave/test/properties/build_url 09:10:40 INFO - Contents: 09:10:40 INFO - build_url:https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.tar.bz2 09:10:40 INFO - Running post-action listener: _resource_record_post_action 09:10:40 INFO - Running post-action listener: set_extra_try_arguments 09:10:40 INFO - [mozharness: 2017-03-16 16:10:40.460850Z] Finished download-and-extract step (success) 09:10:40 INFO - [mozharness: 2017-03-16 16:10:40.460947Z] Running populate-webroot step. 09:10:40 INFO - Running pre-action listener: _resource_record_pre_action 09:10:40 INFO - Running main action method: populate_webroot 09:10:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:10:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:10:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:10:40 INFO - '--tppagecycles', 09:10:40 INFO - '7'], 09:10:40 INFO - 'tests': ['tsvgm']}}, 09:10:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:10:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:10:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:10:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:10:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:10:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:10:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:10:40 INFO - 'talos_options': ['--disable-e10s'], 09:10:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:10:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:10:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:10:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:10:40 INFO - 'talos_options': ['--disable-e10s'], 09:10:40 INFO - 'tests': ['damp', 'tps']}, 09:10:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:10:40 INFO - 'tests': ['damp', 'tps']}, 09:10:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:10:40 INFO - 'tests': ['dromaeo_dom']}, 09:10:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:10:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:10:40 INFO - 'tests': ['basic_compositor_video']}, 09:10:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:10:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:10:40 INFO - 'tests': ['a11yr', 09:10:40 INFO - 'ts_paint', 09:10:40 INFO - 'tpaint', 09:10:40 INFO - 'sessionrestore', 09:10:40 INFO - 'sessionrestore_no_auto_restore', 09:10:40 INFO - 'tabpaint']}, 09:10:40 INFO - 'other-e10s': {'tests': ['a11yr', 09:10:40 INFO - 'ts_paint', 09:10:40 INFO - 'tpaint', 09:10:40 INFO - 'sessionrestore', 09:10:40 INFO - 'sessionrestore_no_auto_restore', 09:10:40 INFO - 'tabpaint']}, 09:10:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:10:40 INFO - 'ts_paint', 09:10:40 INFO - 'tpaint', 09:10:40 INFO - 'sessionrestore', 09:10:40 INFO - 'sessionrestore_no_auto_restore', 09:10:40 INFO - 'tabpaint']}, 09:10:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:10:40 INFO - 'ts_paint', 09:10:40 INFO - 'tpaint', 09:10:40 INFO - 'sessionrestore', 09:10:40 INFO - 'sessionrestore_no_auto_restore', 09:10:40 INFO - 'tabpaint']}, 09:10:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:10:40 INFO - 'tests': ['a11yr', 09:10:40 INFO - 'ts_paint', 09:10:40 INFO - 'tpaint', 09:10:40 INFO - 'sessionrestore', 09:10:40 INFO - 'sessionrestore_no_auto_restore', 09:10:40 INFO - 'tabpaint']}, 09:10:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:10:40 INFO - 'tests': ['a11yr', 09:10:40 INFO - 'ts_paint', 09:10:40 INFO - 'tpaint', 09:10:40 INFO - 'sessionrestore', 09:10:40 INFO - 'sessionrestore_no_auto_restore', 09:10:40 INFO - 'tabpaint']}, 09:10:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:10:40 INFO - 'tests': ['tsvgx', 09:10:40 INFO - 'tsvgr_opacity', 09:10:40 INFO - 'tart', 09:10:40 INFO - 'tscrollx', 09:10:40 INFO - 'cart', 09:10:40 INFO - 'tsvg_static']}, 09:10:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:10:40 INFO - 'tsvgr_opacity', 09:10:40 INFO - 'tart', 09:10:40 INFO - 'tscrollx', 09:10:40 INFO - 'cart', 09:10:40 INFO - 'tsvg_static']}, 09:10:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:10:40 INFO - 'talos_options': ['--disable-e10s'], 09:10:40 INFO - 'tests': ['tp5o']}, 09:10:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:10:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:10:40 INFO - 'talos_options': ['--disable-e10s', 09:10:40 INFO - '--xperf_path', 09:10:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:10:40 INFO - 'tests': ['tp5n']}, 09:10:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:10:40 INFO - 'talos_options': ['--xperf_path', 09:10:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:10:40 INFO - 'tests': ['tp5n']}}, 09:10:40 INFO - 'talos.zip': {'path': '', 09:10:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:10:40 INFO - Running post-action listener: _resource_record_post_action 09:10:40 INFO - [mozharness: 2017-03-16 16:10:40.474228Z] Finished populate-webroot step (success) 09:10:40 INFO - [mozharness: 2017-03-16 16:10:40.474317Z] Running create-virtualenv step. 09:10:40 INFO - Running pre-action listener: _resource_record_pre_action 09:10:40 INFO - Running main action method: create_virtualenv 09:10:40 INFO - Creating virtualenv /builds/slave/test/build/venv 09:10:40 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:10:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:10:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:10:40 INFO - Using real prefix '/usr' 09:10:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:10:41 INFO - Installing distribute.............................................................................................................................................................................................done. 09:10:43 INFO - Installing pip.................done. 09:10:43 INFO - Return code: 0 09:10:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:10:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:10:43 INFO - Reading from file tmpfile_stdout 09:10:43 INFO - Output received: 09:10:43 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:10:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0x1ce01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7b290>, '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 0x1f7bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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:10:43 INFO - Running command: ['/builds/slave/test/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/build 09:10:43 INFO - Copy/paste: /builds/slave/test/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:10:43 INFO - Using env: {'DISPLAY': ':0', 09:10:43 INFO - 'HOME': '/home/cltbld', 09:10:43 INFO - 'LANG': 'en_US.UTF-8', 09:10:43 INFO - 'LANGUAGE': 'en_US:en', 09:10:43 INFO - 'LOGNAME': 'cltbld', 09:10:43 INFO - 'MAIL': '/var/mail/cltbld', 09:10:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:43 INFO - 'MOZ_NO_REMOTE': '1', 09:10:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:43 INFO - 'NO_EM_RESTART': '1', 09:10:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:43 INFO - 'PWD': '/builds/slave/test', 09:10:43 INFO - 'SHELL': '/bin/bash', 09:10:43 INFO - 'SHLVL': '1', 09:10:43 INFO - 'TERM': 'linux', 09:10:43 INFO - 'TMOUT': '86400', 09:10:43 INFO - 'USER': 'cltbld', 09:10:43 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', 09:10:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:43 INFO - '_': '/tools/buildbot/bin/python'} 09:10:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:43 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:10:43 INFO - Cleaning up... 09:10:43 INFO - Return code: 0 09:10:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7b290>, '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 0x1f7bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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:10:43 INFO - Running command: ['/builds/slave/test/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>=3.1.1'] in /builds/slave/test/build 09:10:43 INFO - Copy/paste: /builds/slave/test/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>=3.1.1 09:10:43 INFO - Using env: {'DISPLAY': ':0', 09:10:43 INFO - 'HOME': '/home/cltbld', 09:10:43 INFO - 'LANG': 'en_US.UTF-8', 09:10:43 INFO - 'LANGUAGE': 'en_US:en', 09:10:43 INFO - 'LOGNAME': 'cltbld', 09:10:43 INFO - 'MAIL': '/var/mail/cltbld', 09:10:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:43 INFO - 'MOZ_NO_REMOTE': '1', 09:10:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:43 INFO - 'NO_EM_RESTART': '1', 09:10:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:43 INFO - 'PWD': '/builds/slave/test', 09:10:43 INFO - 'SHELL': '/bin/bash', 09:10:43 INFO - 'SHLVL': '1', 09:10:43 INFO - 'TERM': 'linux', 09:10:43 INFO - 'TMOUT': '86400', 09:10:43 INFO - 'USER': 'cltbld', 09:10:43 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', 09:10:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:43 INFO - '_': '/tools/buildbot/bin/python'} 09:10:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:43 INFO - Downloading/unpacking psutil>=3.1.1 09:10:43 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:10:43 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:10:43 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:10:43 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:10:43 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:10:43 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:10:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:10:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:10:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:10:45 INFO - Installing collected packages: psutil 09:10:45 INFO - Running setup.py install for psutil 09:10:45 INFO - building 'psutil._psutil_linux' extension 09:10:45 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:10:46 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:10:46 INFO - building 'psutil._psutil_posix' extension 09:10:46 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:10:46 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:10:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:10:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:10:46 INFO - Successfully installed psutil 09:10:46 INFO - Cleaning up... 09:10:46 INFO - Return code: 0 09:10:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:10:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7b290>, '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 0x1f7bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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:10:46 INFO - Running command: ['/builds/slave/test/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.3'] in /builds/slave/test/build 09:10:46 INFO - Copy/paste: /builds/slave/test/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.3 09:10:46 INFO - Using env: {'DISPLAY': ':0', 09:10:46 INFO - 'HOME': '/home/cltbld', 09:10:46 INFO - 'LANG': 'en_US.UTF-8', 09:10:46 INFO - 'LANGUAGE': 'en_US:en', 09:10:46 INFO - 'LOGNAME': 'cltbld', 09:10:46 INFO - 'MAIL': '/var/mail/cltbld', 09:10:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:46 INFO - 'MOZ_NO_REMOTE': '1', 09:10:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:46 INFO - 'NO_EM_RESTART': '1', 09:10:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:46 INFO - 'PWD': '/builds/slave/test', 09:10:46 INFO - 'SHELL': '/bin/bash', 09:10:46 INFO - 'SHLVL': '1', 09:10:46 INFO - 'TERM': 'linux', 09:10:46 INFO - 'TMOUT': '86400', 09:10:46 INFO - 'USER': 'cltbld', 09:10:46 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', 09:10:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:46 INFO - '_': '/tools/buildbot/bin/python'} 09:10:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:10:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:10:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:10:48 INFO - Installing collected packages: mozsystemmonitor 09:10:48 INFO - Running setup.py install for mozsystemmonitor 09:10:48 INFO - Successfully installed mozsystemmonitor 09:10:48 INFO - Cleaning up... 09:10:48 INFO - Return code: 0 09:10:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:10: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7b290>, '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 0x1f7bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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:10:48 INFO - Running command: ['/builds/slave/test/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==2.5.1'] in /builds/slave/test/build 09:10:48 INFO - Copy/paste: /builds/slave/test/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==2.5.1 09:10:48 INFO - Using env: {'DISPLAY': ':0', 09:10:48 INFO - 'HOME': '/home/cltbld', 09:10:48 INFO - 'LANG': 'en_US.UTF-8', 09:10:48 INFO - 'LANGUAGE': 'en_US:en', 09:10:48 INFO - 'LOGNAME': 'cltbld', 09:10:48 INFO - 'MAIL': '/var/mail/cltbld', 09:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:48 INFO - 'MOZ_NO_REMOTE': '1', 09:10:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:48 INFO - 'NO_EM_RESTART': '1', 09:10:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:48 INFO - 'PWD': '/builds/slave/test', 09:10:48 INFO - 'SHELL': '/bin/bash', 09:10:48 INFO - 'SHLVL': '1', 09:10:48 INFO - 'TERM': 'linux', 09:10:48 INFO - 'TMOUT': '86400', 09:10:48 INFO - 'USER': 'cltbld', 09:10:48 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', 09:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:48 INFO - '_': '/tools/buildbot/bin/python'} 09:10:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:49 INFO - Downloading/unpacking jsonschema==2.5.1 09:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:10:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:10:51 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:10:51 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:10:51 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:10:51 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:10:51 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:10:51 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:10:51 INFO - Downloading functools32-3.2.3-2.tar.gz 09:10:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:10:51 INFO - warning: no files found matching '*.txt' 09:10:51 INFO - no previously-included directories found matching 'build' 09:10:51 INFO - no previously-included directories found matching 'dist' 09:10:51 INFO - no previously-included directories found matching '.git*' 09:10:51 INFO - Installing collected packages: jsonschema, functools32 09:10:51 INFO - Running setup.py install for functools32 09:10:51 INFO - warning: no files found matching '*.txt' 09:10:51 INFO - no previously-included directories found matching 'build' 09:10:51 INFO - no previously-included directories found matching 'dist' 09:10:51 INFO - no previously-included directories found matching '.git*' 09:10:51 INFO - Successfully installed jsonschema functools32 09:10:51 INFO - Cleaning up... 09:10:51 INFO - Return code: 0 09:10:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:10:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7b290>, '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 0x1f7bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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:10:51 INFO - Running command: ['/builds/slave/test/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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:10:51 INFO - Copy/paste: /builds/slave/test/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 functools32==3.2.3-2 09:10:51 INFO - Using env: {'DISPLAY': ':0', 09:10:51 INFO - 'HOME': '/home/cltbld', 09:10:51 INFO - 'LANG': 'en_US.UTF-8', 09:10:51 INFO - 'LANGUAGE': 'en_US:en', 09:10:51 INFO - 'LOGNAME': 'cltbld', 09:10:51 INFO - 'MAIL': '/var/mail/cltbld', 09:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:51 INFO - 'MOZ_NO_REMOTE': '1', 09:10:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:51 INFO - 'NO_EM_RESTART': '1', 09:10:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:51 INFO - 'PWD': '/builds/slave/test', 09:10:51 INFO - 'SHELL': '/bin/bash', 09:10:51 INFO - 'SHLVL': '1', 09:10:51 INFO - 'TERM': 'linux', 09:10:51 INFO - 'TMOUT': '86400', 09:10:51 INFO - 'USER': 'cltbld', 09:10:51 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', 09:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:51 INFO - '_': '/tools/buildbot/bin/python'} 09:10:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:10:51 INFO - Cleaning up... 09:10:51 INFO - Return code: 0 09:10:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:10:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0x1ce01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7b290>, '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 0x1f7bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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:10:51 INFO - Running command: ['/builds/slave/test/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/build 09:10:51 INFO - Copy/paste: /builds/slave/test/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:10:51 INFO - Using env: {'DISPLAY': ':0', 09:10:51 INFO - 'HOME': '/home/cltbld', 09:10:51 INFO - 'LANG': 'en_US.UTF-8', 09:10:51 INFO - 'LANGUAGE': 'en_US:en', 09:10:51 INFO - 'LOGNAME': 'cltbld', 09:10:51 INFO - 'MAIL': '/var/mail/cltbld', 09:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:51 INFO - 'MOZ_NO_REMOTE': '1', 09:10:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:51 INFO - 'NO_EM_RESTART': '1', 09:10:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:51 INFO - 'PWD': '/builds/slave/test', 09:10:51 INFO - 'SHELL': '/bin/bash', 09:10:51 INFO - 'SHLVL': '1', 09:10:51 INFO - 'TERM': 'linux', 09:10:51 INFO - 'TMOUT': '86400', 09:10:51 INFO - 'USER': 'cltbld', 09:10:51 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', 09:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:51 INFO - '_': '/tools/buildbot/bin/python'} 09:10:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:51 INFO - Downloading/unpacking blobuploader==1.2.4 09:10:51 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:10:51 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:10:51 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:10:51 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:10:51 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:10:51 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:10:53 INFO - Downloading blobuploader-1.2.4.tar.gz 09:10:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:10:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:10:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:54 INFO - Downloading docopt-0.6.1.tar.gz 09:10:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:10:54 INFO - Installing collected packages: blobuploader, requests, docopt 09:10:54 INFO - Running setup.py install for blobuploader 09:10:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:10:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:10:54 INFO - Running setup.py install for requests 09:10:55 INFO - Running setup.py install for docopt 09:10:55 INFO - Successfully installed blobuploader requests docopt 09:10:55 INFO - Cleaning up... 09:10:55 INFO - Return code: 0 09:10:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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 0x1ce01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7b290>, '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 0x1f7bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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:10:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 09:10:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/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:10:55 INFO - Using env: {'DISPLAY': ':0', 09:10:55 INFO - 'HOME': '/home/cltbld', 09:10:55 INFO - 'LANG': 'en_US.UTF-8', 09:10:55 INFO - 'LANGUAGE': 'en_US:en', 09:10:55 INFO - 'LOGNAME': 'cltbld', 09:10:55 INFO - 'MAIL': '/var/mail/cltbld', 09:10:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:55 INFO - 'MOZ_NO_REMOTE': '1', 09:10:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:55 INFO - 'NO_EM_RESTART': '1', 09:10:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:55 INFO - 'PWD': '/builds/slave/test', 09:10:55 INFO - 'SHELL': '/bin/bash', 09:10:55 INFO - 'SHLVL': '1', 09:10:55 INFO - 'TERM': 'linux', 09:10:55 INFO - 'TMOUT': '86400', 09:10:55 INFO - 'USER': 'cltbld', 09:10:55 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', 09:10:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:55 INFO - '_': '/tools/buildbot/bin/python'} 09:10:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:10:55 INFO - Running setup.py (path:/tmp/pip-9aKkq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:10:55 INFO - Running setup.py (path:/tmp/pip-SHXZ0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:10:55 INFO - Running setup.py (path:/tmp/pip-owQ9Rr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:10:55 INFO - Running setup.py (path:/tmp/pip-9BVFGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:10:55 INFO - Running setup.py (path:/tmp/pip-b7PF3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:10:55 INFO - Running setup.py (path:/tmp/pip-cR3AB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:10:56 INFO - Running setup.py (path:/tmp/pip-XhfwCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:10:56 INFO - Running setup.py (path:/tmp/pip-5Q9SBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:10:56 INFO - Running setup.py (path:/tmp/pip-JUSyXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:10:56 INFO - Running setup.py (path:/tmp/pip-NHmN_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:10:56 INFO - Running setup.py (path:/tmp/pip-HMSQXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:10:56 INFO - Running setup.py (path:/tmp/pip-ZIqvD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:10:56 INFO - Running setup.py (path:/tmp/pip-Pr3gS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:10:56 INFO - Running setup.py (path:/tmp/pip-hRf_Vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:56 INFO - Running setup.py (path:/tmp/pip-WLy1w7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:10:56 INFO - Running setup.py (path:/tmp/pip-hcWwfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:10:56 INFO - Running setup.py (path:/tmp/pip-QxaHsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:10:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:10:56 INFO - Running setup.py install for manifestparser 09:10:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:10:56 INFO - Running setup.py install for mozcrash 09:10:57 INFO - Running setup.py install for mozdebug 09:10:57 INFO - Running setup.py install for mozdevice 09:10:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:10:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:10:57 INFO - Running setup.py install for mozfile 09:10:57 INFO - Running setup.py install for mozhttpd 09:10:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:10:57 INFO - Running setup.py install for mozinfo 09:10:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:10:57 INFO - Running setup.py install for mozInstall 09:10:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:10:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:10:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:10:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:10:57 INFO - Running setup.py install for mozleak 09:10:57 INFO - Running setup.py install for mozlog 09:10:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:10:57 INFO - Running setup.py install for moznetwork 09:10:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:10:58 INFO - Running setup.py install for mozprocess 09:10:58 INFO - Running setup.py install for mozprofile 09:10:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:10:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:10:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:10:58 INFO - Running setup.py install for mozrunner 09:10:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:10:58 INFO - Running setup.py install for mozscreenshot 09:10:58 INFO - Running setup.py install for moztest 09:10:58 INFO - Running setup.py install for mozversion 09:10:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:10:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:10:58 INFO - Cleaning up... 09:10:58 INFO - Return code: 0 09:10:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x1ce01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7b290>, '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 0x1f7bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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:10:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 09:10:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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:10:58 INFO - Using env: {'DISPLAY': ':0', 09:10:58 INFO - 'HOME': '/home/cltbld', 09:10:58 INFO - 'LANG': 'en_US.UTF-8', 09:10:58 INFO - 'LANGUAGE': 'en_US:en', 09:10:58 INFO - 'LOGNAME': 'cltbld', 09:10:58 INFO - 'MAIL': '/var/mail/cltbld', 09:10:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:58 INFO - 'MOZ_NO_REMOTE': '1', 09:10:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:58 INFO - 'NO_EM_RESTART': '1', 09:10:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:58 INFO - 'PWD': '/builds/slave/test', 09:10:58 INFO - 'SHELL': '/bin/bash', 09:10:58 INFO - 'SHLVL': '1', 09:10:58 INFO - 'TERM': 'linux', 09:10:58 INFO - 'TMOUT': '86400', 09:10:58 INFO - 'USER': 'cltbld', 09:10:58 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', 09:10:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:58 INFO - '_': '/tools/buildbot/bin/python'} 09:10:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:10:58 INFO - Running setup.py (path:/tmp/pip-SEAxIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:10:58 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:10:58 INFO - Running setup.py (path:/tmp/pip-lhh8vG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:10:59 INFO - Running setup.py (path:/tmp/pip-442SoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:10:59 INFO - Running setup.py (path:/tmp/pip-i2HdJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:10:59 INFO - Running setup.py (path:/tmp/pip-9mTuN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:10:59 INFO - Running setup.py (path:/tmp/pip-gKCcoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:10:59 INFO - Running setup.py (path:/tmp/pip-QeEs2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:10:59 INFO - Running setup.py (path:/tmp/pip-mjEVhb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:10:59 INFO - Running setup.py (path:/tmp/pip-jmVDb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:10:59 INFO - Running setup.py (path:/tmp/pip-fRDLvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:10:59 INFO - Running setup.py (path:/tmp/pip-CcSF_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:10:59 INFO - Running setup.py (path:/tmp/pip-Yud1QH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:10:59 INFO - Running setup.py (path:/tmp/pip-0_vQKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:10:59 INFO - Running setup.py (path:/tmp/pip-joA354-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:59 INFO - Running setup.py (path:/tmp/pip-OCd6Yo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:11:00 INFO - Running setup.py (path:/tmp/pip-VssOPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:11:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:11:00 INFO - Running setup.py (path:/tmp/pip-CeO6Bo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:11:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:11:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:11:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:11:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:11:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:11:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:11:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:02 INFO - Downloading blessings-1.6.tar.gz 09:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:11:02 INFO - Installing collected packages: blessings 09:11:02 INFO - Running setup.py install for blessings 09:11:02 INFO - Successfully installed blessings 09:11:02 INFO - Cleaning up... 09:11:02 INFO - Return code: 0 09:11:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:11:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:02 INFO - Reading from file tmpfile_stdout 09:11:02 INFO - Current package versions: 09:11:02 INFO - argparse == 1.2.1 09:11:02 INFO - blessings == 1.6 09:11:02 INFO - blobuploader == 1.2.4 09:11:02 INFO - docopt == 0.6.1 09:11:02 INFO - functools32 == 3.2.3-2 09:11:02 INFO - jsonschema == 2.5.1 09:11:02 INFO - manifestparser == 1.1 09:11:02 INFO - mozInstall == 1.12 09:11:02 INFO - mozcrash == 1.0 09:11:02 INFO - mozdebug == 0.1 09:11:02 INFO - mozdevice == 0.49 09:11:02 INFO - mozfile == 1.2 09:11:02 INFO - mozhttpd == 0.7 09:11:02 INFO - mozinfo == 0.9 09:11:02 INFO - mozleak == 0.1 09:11:02 INFO - mozlog == 3.4 09:11:02 INFO - moznetwork == 0.27 09:11:02 INFO - mozprocess == 0.25 09:11:02 INFO - mozprofile == 0.28 09:11:02 INFO - mozrunner == 6.13 09:11:02 INFO - mozscreenshot == 0.1 09:11:02 INFO - mozsystemmonitor == 0.3 09:11:02 INFO - moztest == 0.8 09:11:02 INFO - mozversion == 1.4 09:11:02 INFO - psutil == 3.1.1 09:11:02 INFO - requests == 1.2.3 09:11:02 INFO - wsgiref == 0.1.2 09:11:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x1ce01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7b290>, '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 0x1f7bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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:11:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/talos 09:11:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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:11:02 INFO - Using env: {'DISPLAY': ':0', 09:11:02 INFO - 'HOME': '/home/cltbld', 09:11:02 INFO - 'LANG': 'en_US.UTF-8', 09:11:02 INFO - 'LANGUAGE': 'en_US:en', 09:11:02 INFO - 'LOGNAME': 'cltbld', 09:11:02 INFO - 'MAIL': '/var/mail/cltbld', 09:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:02 INFO - 'MOZ_NO_REMOTE': '1', 09:11:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:02 INFO - 'NO_EM_RESTART': '1', 09:11:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:02 INFO - 'PWD': '/builds/slave/test', 09:11:02 INFO - 'SHELL': '/bin/bash', 09:11:02 INFO - 'SHLVL': '1', 09:11:02 INFO - 'TERM': 'linux', 09:11:02 INFO - 'TMOUT': '86400', 09:11:02 INFO - 'USER': 'cltbld', 09:11:02 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', 09:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:02 INFO - '_': '/tools/buildbot/bin/python'} 09:11:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:11:02 INFO - Cleaning up... 09:11:02 INFO - Return code: 0 09:11:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:11: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0x1ce01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7b290>, '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 0x1f7bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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:11:02 INFO - Running command: ['/builds/slave/test/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/build 09:11:02 INFO - Copy/paste: /builds/slave/test/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:11:02 INFO - Using env: {'DISPLAY': ':0', 09:11:02 INFO - 'HOME': '/home/cltbld', 09:11:02 INFO - 'LANG': 'en_US.UTF-8', 09:11:02 INFO - 'LANGUAGE': 'en_US:en', 09:11:02 INFO - 'LOGNAME': 'cltbld', 09:11:02 INFO - 'MAIL': '/var/mail/cltbld', 09:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:02 INFO - 'MOZ_NO_REMOTE': '1', 09:11:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:02 INFO - 'NO_EM_RESTART': '1', 09:11:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:02 INFO - 'PWD': '/builds/slave/test', 09:11:02 INFO - 'SHELL': '/bin/bash', 09:11:02 INFO - 'SHLVL': '1', 09:11:02 INFO - 'TERM': 'linux', 09:11:02 INFO - 'TMOUT': '86400', 09:11:02 INFO - 'USER': 'cltbld', 09:11:02 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', 09:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:02 INFO - '_': '/tools/buildbot/bin/python'} 09:11:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:11:03 INFO - Cleaning up... 09:11:03 INFO - Return code: 0 09:11:03 INFO - Running post-action listener: _resource_record_post_action 09:11:03 INFO - Running post-action listener: _start_resource_monitoring 09:11:03 INFO - Starting resource monitoring. 09:11:03 INFO - [mozharness: 2017-03-16 16:11:03.131164Z] Finished create-virtualenv step (success) 09:11:03 INFO - [mozharness: 2017-03-16 16:11:03.131633Z] Running install step. 09:11:03 INFO - Running pre-action listener: _resource_record_pre_action 09:11:03 INFO - Running main action method: install 09:11:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:03 INFO - Reading from file tmpfile_stdout 09:11:03 INFO - Detecting whether we're running mozinstall >=1.0... 09:11:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:11:03 INFO - Reading from file tmpfile_stdout 09:11:03 INFO - Output received: 09:11:03 INFO - Usage: mozinstall [options] installer 09:11:03 INFO - Options: 09:11:03 INFO - -h, --help show this help message and exit 09:11:03 INFO - -d DEST, --destination=DEST 09:11:03 INFO - Directory to install application into. [default: 09:11:03 INFO - "/builds/slave/test"] 09:11:03 INFO - --app=APP Application being installed. [default: firefox] 09:11:03 INFO - mkdir: /builds/slave/test/build/application 09:11:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:11:18 INFO - Reading from file tmpfile_stdout 09:11:18 INFO - Output received: 09:11:18 INFO - /builds/slave/test/build/application/firefox/firefox 09:11:18 INFO - Running post-action listener: _resource_record_post_action 09:11:18 INFO - [mozharness: 2017-03-16 16:11:18.854067Z] Finished install step (success) 09:11:18 INFO - [mozharness: 2017-03-16 16:11:18.854338Z] Running run-tests step. 09:11:18 INFO - Running pre-action listener: _resource_record_pre_action 09:11:18 INFO - Running main action method: run_tests 09:11:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:11:18 INFO - Python 2.7.3 09:11:18 INFO - Return code: 0 09:11:18 INFO - grabbing minidump binary from tooltool 09:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:18 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7b290>, '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 0x1f7bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7c040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:11:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:11:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:11:18 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:11:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:11:18 INFO - Return code: 0 09:11:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:11:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:11:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:11:18 INFO - ENV: RUST_BACKTRACE is now 1 09:11:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:11:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:11:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-045 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:11:18 INFO - Using env: {'DISPLAY': ':0', 09:11:18 INFO - 'HOME': '/home/cltbld', 09:11:18 INFO - 'LANG': 'en_US.UTF-8', 09:11:18 INFO - 'LANGUAGE': 'en_US:en', 09:11:18 INFO - 'LOGNAME': 'cltbld', 09:11:18 INFO - 'MAIL': '/var/mail/cltbld', 09:11:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:11:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:11:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:18 INFO - 'MOZ_NO_REMOTE': '1', 09:11:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:11:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:18 INFO - 'NO_EM_RESTART': '1', 09:11:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:18 INFO - 'PWD': '/builds/slave/test', 09:11:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:11:18 INFO - 'RUST_BACKTRACE': '1', 09:11:18 INFO - 'SHELL': '/bin/bash', 09:11:18 INFO - 'SHLVL': '1', 09:11:18 INFO - 'TERM': 'linux', 09:11:18 INFO - 'TMOUT': '86400', 09:11:18 INFO - 'USER': 'cltbld', 09:11:18 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1489680608.599323-2080363727', 09:11:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:18 INFO - '_': '/tools/buildbot/bin/python'} 09:11:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:11:19 INFO - mozversion application_buildid: 20170316145716 09:11:19 INFO - mozversion application_changeset: 3945f2297b997d59662bdb8ac20e092363eea532 09:11:19 INFO - mozversion application_display_name: Nightly 09:11:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:11:19 INFO - mozversion application_name: Firefox 09:11:19 INFO - mozversion application_remotingname: firefox 09:11:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:11:19 INFO - mozversion application_vendor: Mozilla 09:11:19 INFO - mozversion application_version: 55.0a1 09:11:19 INFO - mozversion platform_buildid: 20170316145716 09:11:19 INFO - mozversion platform_changeset: 3945f2297b997d59662bdb8ac20e092363eea532 09:11:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:11:19 INFO - mozversion platform_version: 55.0a1 09:11:19 INFO - using testdate: 1489680679 09:11:19 INFO - actual date: 1489680679 09:11:19 INFO - starting webserver on 'localhost:51373' 09:11:19 INFO - SUITE-START | Running 2 tests 09:11:19 INFO - TEST-START | dromaeo_css 09:11:19 INFO - operating with platform_type : linux_ 09:11:19 INFO - Initialising browser for dromaeo_css test... 09:11:19 INFO - TEST-INFO | started process 20903 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppCsC_e/profile http://localhost:51373/getInfo.html) 09:11:23 INFO - PID 20903 | __metrics Screen width/height:1600/1200 09:11:23 INFO - PID 20903 | colorDepth:24 09:11:23 INFO - PID 20903 | Browser inner width/height: 1024/697 09:11:23 INFO - PID 20903 | __metrics 09:11:24 ERROR - PID 20903 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 09:11:25 INFO - TEST-INFO | 20903: exit 0 09:11:25 INFO - Browser initialized. 09:11:25 INFO - Running cycle 1/1 for dromaeo_css test... 09:11:25 INFO - TEST-INFO | started process 21036 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppCsC_e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:11:37 INFO - PID 21036 | RSS: Main: 155021312 09:11:37 INFO - PID 21036 | 09:14:41 INFO - PID 21036 | Cycle 1(1): loaded http://localhost:51373/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51373/tests/dromaeo/cssquery-ext.html) 09:14:43 INFO - PID 21036 | RSS: Main: 164511744 09:14:43 INFO - PID 21036 | 09:16:26 INFO - PID 21036 | ************************* 09:16:26 INFO - PID 21036 | A coding exception was thrown and uncaught in a Task. 09:16:26 INFO - PID 21036 | 09:16:26 ERROR - PID 21036 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:16:26 INFO - PID 21036 | Full stack: 09:16:26 INFO - PID 21036 | ************************* 09:17:45 INFO - PID 21036 | Cycle 1(1): loaded http://localhost:51373/tests/dromaeo/cssquery-ext.html (next: http://localhost:51373/tests/dromaeo/cssquery-jquery.html) 09:17:46 INFO - PID 21036 | RSS: Main: 160538624 09:17:46 INFO - PID 21036 | 09:20:48 INFO - PID 21036 | Cycle 1(1): loaded http://localhost:51373/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51373/tests/dromaeo/cssquery-mootools.html) 09:20:49 INFO - PID 21036 | RSS: Main: 167907328 09:20:49 INFO - PID 21036 | 09:23:51 INFO - PID 21036 | Cycle 1(1): loaded http://localhost:51373/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51373/tests/dromaeo/cssquery-prototype.html) 09:23:51 INFO - PID 21036 | RSS: Main: 161193984 09:23:51 INFO - PID 21036 | 09:26:54 INFO - PID 21036 | Cycle 1(1): loaded http://localhost:51373/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51373/tests/dromaeo/cssquery-yui.html) 09:26:55 INFO - PID 21036 | RSS: Main: 168136704 09:26:55 INFO - PID 21036 | 09:29:58 INFO - PID 21036 | Cycle 1(1): loaded http://localhost:51373/tests/dromaeo/cssquery-yui.html (next: http://localhost:51373/tests/dromaeo/cssquery-dojo.html) 09:29:58 INFO - PID 21036 | RSS: Main: 163020800 09:29:58 INFO - PID 21036 | 09:29:58 INFO - PID 21036 | __start_tp_report 09:29:58 INFO - PID 21036 | _x_x_mozilla_page_load 09:29:58 INFO - PID 21036 | _x_x_mozilla_page_load_details 09:29:58 INFO - PID 21036 | |i|pagename|runs| 09:29:58 INFO - PID 21036 | |0;dojo.html;2630.4739052189566;2649.4303724699184;2659.2952867490117;2687.5834245691913;2690.87891044903;4784.282357646355;4880.999395123996;4900.2404627282785;4933.210686290186;4945.826896058638;4857.319975203474;4863.416390033192;4899.265110233467;4942.209246520558;4942.431620363659;4492.326151077332;4538.183127037132;4545.977155140084;4552.408186935711;4570.81716731332;4573.039661671053;4591.265397536395;4592.035672508777;4595.954040459603;4599.103174880899;4426.911461770746;4463.553644635543;4476.910461790746;4509.143262780061;4517.435320584927;4530.8867278317975;4535.841245556389;4544.8863778405475;4571.474280457756;4586.954130458686;4488.708233964782;4492.14649216651;4502.932456013163;4508.301213311942;4529.365888775574;4739.810407583693;4758.666893317469;4836.733979631119;4901.852944411673;4907.509249075099;5192.247124167013;5228.163493840967;5235.947640523584;5262;5314.73426328682;5229.2156176573435;5264.46931717384;5264.6051546134195;5271.86820329491;5275.762590683428;4805.831795887197;4929.654924155347;4931.260310953386;4933.654644174945;4985.651004429691;5228.9738551307;5361.035013697494;5399.649022813504;5404.216388623706;5411.215373770781;5014.548690617789;5067.771950262247;5136.998285334399;5140.691558506502;5150.227465880073;5210.452902445189;5253.50091741284;5257.316548848626;5271.20931860224;5291.285676433709;5080.212567052113;5200.713960732196;5210.583153347723;5224.947750522446;5254.028004819121;5145.716985565755;5213.113770658997;5235.738213089407;5239;5271.393789714232;4471.61991230749;4480.910381792346;4489.551044895549;4491.326301054868;4524.072565124142;3851.2682590308314;3856.325143099946;3870.9225815484097;3895.590962948906;3896.7467114637457;4477.865664030084;4513.638908887281;4543.477500087466;4574.4968053514085;4579.38178345919;5237.423883372826;5258.947410525846;5260.973695131576;5264.315638966987;5266.710330931835;4549.977250113794;4565.338025986213;4573.1646379245085;4574.245249533857;4610.05493873755;4648.9772250104925;4679.625629949664;4682.274247491644;4683.297505374153;4792.904141917142;4794.832180873653;4869;4908.582770464551;4911.607071434348;4920.065187614342;5253.343332083489;5268.81559145428;5271.235670827632;5277.498637629419;5301.628885977944;4836.830710925101;4882.170031094652;4903.215485522299;4906.582940450102;4943.876403089952;5058.873528161826;5068.417132029864;5100.642954993264;5117.130087884994;5122.667026643257;5214.270002199771;5231.947680523298;5239.161734122522;5247.868803279795;5278.4193738687945;4987.451380348086;5026.773795179152;5071.644984851025;5083.415407228068;5100.438951715382;5037.571806396498;5106.7701953412925;5112.156494178419;5144.022635699355;5144.356955380577;4832.1543729846435;4853.344798452164;4861.562459378673;4873.074115917964;4990.4011518618;5047.646664733434;5050.898982020412;5074.771635276347;5077.162268225701;5079.161938280143;4881.145799485005;4918.6556941015215;4942.505749425029;4943.65394422387;4950.504949505021 09:29:58 INFO - PID 21036 | |1;ext.html;622.7477871462056;670.926198118207;693.3551796828947;829.394541984351;844.4933040175891;33857.322853542915;34828.47757283637;34946.82526587367;34949.47575786362;35016.64983350166;31211.219719507022;31503.21241968952;31526.84236578814;31537.842310788415;31557.526637100404;6364.554481186319;6524.184476940382;9047.547622618875;9246.67636632718;9281.675141370053;9172.6789562365;9267.351285410025;9289.21041711452;9312.72061838146;9323;9479.668211612596;9607.711768646917;9620.56707448163;9622.566984485715;9625.133737963582;9475.47884866332;9510.857337139949;9554.426734395924;9565.330426870121;9566.043395660448;9341;9359.29805264608;9392.953035234848;9412.529373531295;9426.481543515103;35082.82458587716;35514.46728299077;35647.64352356469;35689.643103568895;35700.82149589261;3329.3674201901563;3439.673231043047;3462.6191118976894;3486.2678837443914;3518.7712798668003;24882.751172488224;25046;25080.247592572254;25368.74631253701;25790.871045644653;3307.9173020674434;3354.7483938704695;3380.8309584520675;3389.254364039907;3441.5525981622477;113699.43150284195;114385.42807285994;160265;161822.19088904597;161937;37727;37904.62095379066;38098;38134.61865381366;38143.61856381456;39229.80385098056;39323.213535729694;39382.40926386107;39396.60603393929;39410.80294598566;11839.940800296115;11987.280763154238;11987.400629968466;12000.279983201035;12049.698757531132;10376.948115259527;10401.53193106312;10466.842997355046;10480.528376223088;10488.05607495329;13080.672983175307;13102.475900964046;13189.208647481182;13197.604071877859;13346.13250138738;5556.91664625031;5625.424881033317;5644.633098848643;5679.716014199274;5695.202671625976;853.185208126238;883.1257055515123;890.17213990989;895.2256298302052;912.224609082288;23613.527729445657;24448.266552003468;24534.263972080866;24583.508329833305;24733;942.5946842857637;944.8110377924305;951.9762005949768;955.0544960489066;967.2890425537224;12708.364581770875;13018.414171362312;13225.53710620143;13232.669183270495;13239.470421183238;230026;231067;232057;232068;232435;44418.55581444209;44877.775611122386;45010.77494612506;45058.774706126256;45152;1205.873383294759;1246.7132559511363;1250.2186133666457;1250.6185613388004;1252.1485389934749;22885.54228915379;23095.88452057729;23845.761542384353;24152.87923560371;24156.51686966286;36087.09782255465;36214;36342.18525035981;36452;36452;225880;226559;227173;227432;228129;29011.709882901745;29025.274368140967;29044;29094.56358154587;29114.126576202747;34754.652453476156;34812.303753925284;35210.47184292186;35576.288474229856;36093.45859812157;29648;29807.850960745058;29834.254143646584;30256;30677.846610766803;30532.84733576407;30611.846940766045;31615.841920790248;31881.043568692978;32155.196120097185;30555.2361190972;30580.235494112825;30615.387692246477;30784.846075769477;31529.21176970594;25978;28058.370368888904;28183.295417614725;28233.43533129367;28748.281292967007;21766.45583860416;21881.343559693236;21884;22045.448863778533;22156.66764998494 09:29:58 INFO - PID 21036 | |2;jquery.html;392.21165457430556;406.9572694867041;424.3782858112865;434.5415586556184;437.29813649093205;18425.447236582924;18790.62418751626;19383.127759250834;19455.902720486414;19621.901890490564;18168.182431790567;18475.815241847577;19202;19415.611687766235;19516.707249391235;5519.834404967857;6472.223333200022;6741.797746067626;6778.22050464198;6788.456923446112;6509.414152726254;6636.440436497443;6683.665816709169;6718.899216511756;6755.864882702343;6793.864122717543;6810.114685090931;6812.250652428229;6817.113775209215;6820.181578210621;6567.277599464079;6741.831454213635;6827.180738311409;6865.897011544831;6896.827579310558;6484.708188131545;6568.474522038226;6678.198616166092;6682.131322927989;6696.957427984463;22451.663225051638;22918.65622015671;23408;23409.531809363718;23564.93655824732;2227.9777202227933;2335.591271527476;2353.3410645019253;2416;2539.7841183499427;13670.52153174634;14081.577552673334;14342.282885855666;14567.344469498898;14646.194459304734;1871.6818140916077;1873.4192400355796;1876.2307453943954;1877.5963167918962;1880.0881572437356;85988.5700571493;87106.38680839648;87619;87857;89018.5549072257;16305.836941630669;16597.423244791724;16655.3337866484;16683;16894;28794.424111517652;28813;29027.709722902924;29038.85480572626;29101.708982910324;8265.132161123116;8477.109903460048;8539.871901921477;8739.737807865775;8843.778905527413;6963.293992971754;7036.226015138331;7067.36393724557;7127.857442851114;7224.4942854000765;8712.477251364939;8793.120687931156;8865.867011994826;8922.420042697204;8968.596413161424;3429.5713035870517;3490.2844916791996;3624.8550057997913;3643.3806252937065;3721.3859713147567;827.2596026556198;842.8482873082904;849.4054162086564;852.6589364254348;877.3332267476636;17818.198181081883;18658;18929.43211703651;19115.097011402933;19161.52096197606;847.0386111763194;854;869.7216890595075;878.7495563764296;887.1439061305871;9730.17293530058;10557.77688924421;10746.570137194425;10792.730181745519;10796.974287442681;445280;451557;470568;478444;478459;87067;89120.55439722759;89735.55132224297;89893;90511;1061.5647584490323;1095.2552264460085;1110.9500072496758;1116.771061932302;1125.6960620632383;15805.841941580436;16013.439529616408;16481.34074637001;16595.693636418084;16677;41972.79013604912;41994.37008444937;42084.789576051924;42261.78869105635;42376.78811605922;54160.187597185286;55031.17453238284;55042;55609;56344;11038.282511636718;11081.722956926142;11222.55109795599;11242.831357529807;11737.896293926939;12241.265524068584;12596.118271720892;13123;13256.602301930958;13350.532731354357;10529.78940421172;10928.398938058483;11055.336679799238;11071.391073491035;11130.16523760705;10654.094401975608;10894.67315980522;10908.454577271263;10937.890621094006;11265.324080555192;11947.880521195024;11967.401629918295;12109.333986630467;12304.138710290194;12340.691482713004;11188.10495160369;11252.381119038531;11307.208495405243;11338.943305283421;11769.764604707687;10426.582936682447;10441.58233670675;10484.580616775243;10733.302335348177;10929.125669946545 09:29:58 INFO - PID 21036 | |3;mootools.html;1454.774509950958;1456.5630310906724;1477.8004969329138;1489.8957073004888;1533.8236102848173;7840.490368126082;8135.308498777611;9057;9469.195118414935;9480.668176613819;9051.59267832949;9365.438073715583;9373.531323433817;9386.20217281532;9401.952990235039;2037.3480486244407;2062.072067569592;2069.0585783468487;2082.66677331627;2090.519180588458;1946.0658883735816;1999.8600097993146;2002.5894691588262;2005.9598808023832;2010.8190262876412;1989.3037436897116;2012.3158126237076;2021.353166986565;2023.059277435992;2023.8583299169063;1819.854411647065;1833.8441232495254;1837.4303965770655;1837.8713490055702;1838.108517369075;1860.9255629774793;1873.3536929759211;1884.2274667386444;2001.9599608007904;2014.7582290125279;9372.718818435458;9397.76505587359;9434.19809316209;9452.338336316461;9465.66870159541;4569.428821397325;4788.521147885219;4793.304970779218;4797.328374027639;4841.1845557915785;2678.638383818179;2687.796007274036;2723.72762723728;2778.8749506272266;2853.4150499147627;1059.9718273274989;1115.8884111588777;1122.119136477861;1176.4647284717378;1194.331174542259;13685.931570342085;13697;13728.176309421468;13749.931250343785;13764.518241861486;3784.1863999239745;4042.4744783178585;4121.855735049259;4166.437530933285;4207.0744436224595;11703.531858725555;11711.297322160697;11739.413029348498;11742;11786.528538858522;4678.251479763221;4821.276808478756;4823.03539292143;4826.227803551415;4859.052484765437;3695.0947017980766;3802.562705288927;4287.849925252601;4293.141371725667;4355.934660980088;3380.323935212967;3407.267437500949;3436.810975396327;3501.3872572300256;3509.0876372143425;956.2827879090682;963.7590602349412;973.3624475968111;975.5561768582537;979.1579241852002;867.3754896474528;868.0754995929315;870.6256309786852;889.7597648634833;891.7280229529925;5781.19063331134;5844.064949608042;5853.268341457318;5991.191189189491;6248.843778905563;912.1608120529129;924.4083786376724;944.244604316544;953.3088510829593;997.2570435025953;3596.3526565218513;3702;3702.5742039665242;3718.9070273243387;3766.5103536540623;11981.46083426248;12239.143259971894;12334.629961101182;12355.444005019795;12360.814587781011;12076.396180190955;12093.45579448927;12137.5144994201;12171.0871684624;12177.93911030457;8292.37807164465;8565.10066443027;8580;8593;8866.556672166365;5541.778328866801;5701.486866181897;5889.52883769306;5898.085796701518;5928.940710593011;10655.680329590125;10805;10811.35131892089;10866.293690910066;10991.120710343313;10406.375617462976;10505.212109091695;10567.154627629925;10713.785724285626;10741.24811263204;6635.568688035262;6814.114165158498;7582.734604288823;7684.462087654034;7936.682532698627;8849.20357167858;8917.242034426887;8932.330075244252;8999.415038022507;9007.459552426875;7442.367398770949;7564.9243507566425;7670;7731.7680469586;7749.07011158642;7859.921400785919;7908.4068694846965;7949.443538952218;8008.479448835807;8141.185881411811;6825.249222585412;6831.146106736658;6886.724531018703;6928.861422771617;7043.401310888632;5021.246812977936;5047.722375269395;5146.330976972969;5147.433782283871;5256.159014557652;5290.8941821164135;5305.814296499604;5390.164524498709;5410.053240682786;5478.150886612581 09:29:58 INFO - PID 21036 | |4;prototype.html;357.0662716995057;365.67272291299287;365.9707223422126;371.3946267583838;375.98684046058384;5195.1555848672;5345.839624811262;5400.405955344914;5513.566472717097;5527.447255274471;5148.922766158511;5317.46825317468;5337.893242135165;5342.332208473941;5353.678779273237;4632.559906808856;4655.976720116395;4668.463126740435;4668.813247470114;4683.601893839028;4580.633549316047;4630.907381852361;4649.163150632885;4659.860204193863;4664.8919433220735;4609;4625.144348295559;4641.419822522184;4642.632257548657;4645.420557010616;4632.7220366777765;4636;4646;4648.86053418398;4664.440267167927;4410.779461026936;4427.601515863587;4448.977755111204;4469.9776501117285;4512.052468981517;5221.660592061504;5235.293235413209;5236.8690782730355;5241.764120614544;5243.239730239094;5416.51251387377;5432.809851655173;5433.728313584305;5447.918281225785;5451.754671039813;5517.751701173417;5632.733394276364;5690.955338893672;5709.514691251248;5724.141378793172;4898.583620392271;4911.926321105187;4912.287718280865;4917.967226882359;4921.680090794087;5721.742521586539;5766.394528574481;5913.408659134096;6009.909851352233;6041.667708276043;5142.151544995108;5142.460041695568;5145.76844042019;5148.897022059613;5156.2523434102595;5242.318498595235;5268.209768534751;5282.392524859613;5293.788248470018;5297;5219.982103489859;5376.569874410038;5402.648827826179;5431.456854314537;5432.348118225838;5671.886562268732;5686.687232202268;5730.19777231188;5749.482546570829;5752.453516915886;1816.8644597126795;1833.8080247839011;1835.5135888989428;1854.935077272289;1864.8787828791085;1431.427429028397;1445.7831325301079;1462.9707405851823;1482.4440834686993;1495.5289083938742;4954.934689041874;4962.851114466572;4972.801087956441;4974.8309147351065;4976.850694479172;5870.911936320958;5878.617889837147;5887.646741195542;5889.645381562264;5891.293044834647;4828.985912958248;4945.678530895552;4980.825671101494;4983.725895075733;4986.576141028053;3103.162146220508;3105.813651180936;3111.9222019449694;3128.4681604127354;3130.8434578270994;5395.163749618815;5400.734284072997;5406.702631355313;5416.51251387377;5424.755885985141;5748.597598168088;5913.231279933581;5933.970330148321;5960.910586341209;5991.940080599225;5383.730813459311;5383.892322153535;5396.676199428047;5406.78372865081;5412.107002344726;5610.382857885547;5812.3025236971835;5832.650040997553;5872.236609240771;5880.323762767336;5576.637518561442;5729.427057294237;5731.054376027909;5751.252337196138;5821.563382746226;5441.809536666197;5446.482584154499;5447.972760136333;5454.209139674805;5454.263674403905;4658.790354433991;4698;4698.976505117453;4719.504452032625;4732.266498692708;5754.827355179351;5840.678762668094;5841.795537156179;5858.355580886014;5879;5659.9151012735665;5669.518090962314;5671.858203544944;5678.019040004031;5679.119736440858;5531.834044978657;5609;5661.603687741983;5667.121596152603;5675.716214189357;5528.087865502147;5536.197251398606;5545.9185458836755;5554.888902221852;5575.665460072409;5706.600537962303;5725.914111288251;5732.426757324234;5736.168255602997;5779.104238842986;5367.221752845893;5484.643498172606;5532.612717109763;5554;5583.8883222336135 09:29:58 INFO - PID 21036 | |5;yui.html;659.2418718473757;680.2653134614616;686.1902954513672;690.1717938473831;698.3191388396315;11446.255993360437;11784;11794.941025294862;11817.113716471267;11834.644960651172;12776.361181940889;12817.55138570148;12890.80663790044;12999.415026323815;13023.413946372413;807.56391548564;812.8536863364607;837.0541288344162;839.3788596438652;840.8990921089447;836.0469065265602;851.4933614499363;853.0190281176665;854.1287886355914;855.2345650642671;861.0184389795638;870.5516658920661;878.2183856367838;879.8592225243963;881.9029906710289;852.5012867472536;867.0505796153202;873.8863947686792;874;874.5059041641516;642.8907085795425;643.6813777180279;643.6910283064109;644.577801539992;646.0632083478974;13850.584482465541;14090.365933532914;14102.92948535261;14114.505992290324;14326.140431574138;12639.747205055939;14333.513114001007;14392;14407.927960360235;14442.566722998326;10739;10809.35143891369;10811.243212975094;11043.503042363112;11064.17018723599;3186.139742269574;3203.19920019995;3213.614366276062;3238.4008958342783;3321.285923526453;62438.37561624371;63082.684586577234;63386;63660;63962.36037639657;10387.532561034772;10425.426601536989;10598.523066462029;10753.892461075446;10758.031777140095;18450.26198952027;18698.34555790541;20695.58608827845;20755.68866467004;21054.473638158863;6618.702158402787;6728.226253980757;6785.084013658093;6843.144606924135;6894.482913781487;3403.5745531808525;3530.046887340455;3535.9292814143573;3543.2382037861985;3546.1489242581592;2861.7138286171635;2871.5836203750755;2881.4957382458406;2898.7970842040854;2916.9270768230963;472.39297502708854;475.3487721821127;482.2163983526769;487.7049385122021;489.1928318274804;607.4502575169479;637.8915584350672;640.1869625575494;650.9308905288563;672.3142394757418;8766.956165219133;9429.546175817135;9478.146966773023;9492;9673.758156046015;594.7264258441079;626.4080443980395;631.9368063193643;638.2755572425331;639.5395315372924;4320.719153255029;4410.42664453619;4447.399601053881;4494;4496.57282558163;60574.69712651408;61030.38969610336;61897;61929.59387233746;63439.36560634427;49180.262296065586;55457.44542554604;56407;56672.43327566671;58053.4194658048;47940;48059.75970120127;48062.5193748058;48350;49431.75284123628;8407.159284071544;8612.48325100496;8638.740837774876;8658.177473140042;8813.735587932373;34764.30471390609;35452.64547354596;35804;36110.638893610725;36442.63557364392;40458.39312410375;40874.79562602168;41436.792816035726;42281;43253.13493730171;9835.950820245853;9948.850767238642;9986.60053597848;10103.949480252551;10440.895591043993;11299.774004519699;11374.772504549697;11377.203595748219;11407.486663100346;11581.594644187413;10893.945530272298;10988;11165.218434709492;11176.664700059011;11431.771364572829;9943.552540135566;9973;10134.746631334276;10216.335938163995;10464.843027354747;10456.477176141314;10522.947385263025;10878.184136189975;11055.668329950115;11429.142814289127;8939.59771810257;9082.909170908471;9225.49259790718;9352;9498.620055197714;8499.235068843835;9430.10414010668;9784.510774461107;10471.738206544593;10659.25385223058 09:29:58 INFO - PID 21036 | __end_tp_report 09:29:58 INFO - PID 21036 | __start_cc_report 09:29:58 INFO - PID 21036 | _x_x_mozilla_cycle_collect,376 09:29:58 INFO - PID 21036 | __end_cc_report 09:29:58 INFO - PID 21036 | __startTimestamp1489681798586__endTimestamp 09:29:58 INFO - PID 21036 | 09:29:58 INFO - PID 21036 | ------- Summary: start ------- 09:29:58 INFO - PID 21036 | Number of tests: 6 09:29:58 INFO - PID 21036 | 09:29:58 INFO - PID 21036 | [#0] dojo.html Cycles:165 Average:4829.29 Median:4929.65 stddev:506.28 (10.3%) stddev-sans-first:477.55 09:29:58 INFO - PID 21036 | Values: 2630.5 2649.4 2659.3 2687.6 2690.9 4784.3 4881.0 4900.2 4933.2 4945.8 4857.3 4863.4 4899.3 4942.2 4942.4 4492.3 4538.2 4546.0 4552.4 4570.8 4573.0 4591.3 4592.0 4596.0 4599.1 4426.9 4463.6 4476.9 4509.1 4517.4 4530.9 4535.8 4544.9 4571.5 4587.0 4488.7 4492.1 4502.9 4508.3 4529.4 4739.8 4758.7 4836.7 4901.9 4907.5 5192.2 5228.2 5235.9 5262.0 5314.7 5229.2 5264.5 5264.6 5271.9 5275.8 4805.8 4929.7 4931.3 4933.7 4985.7 5229.0 5361.0 5399.6 5404.2 5411.2 5014.5 5067.8 5137.0 5140.7 5150.2 5210.5 5253.5 5257.3 5271.2 5291.3 5080.2 5200.7 5210.6 5224.9 5254.0 5145.7 5213.1 5235.7 5239.0 5271.4 4471.6 4480.9 4489.6 4491.3 4524.1 3851.3 3856.3 3870.9 3895.6 3896.7 4477.9 4513.6 4543.5 4574.5 4579.4 5237.4 5258.9 5261.0 5264.3 5266.7 4550.0 4565.3 4573.2 4574.2 4610.1 4649.0 4679.6 4682.3 4683.3 4792.9 4794.8 4869.0 4908.6 4911.6 4920.1 5253.3 5268.8 5271.2 5277.5 5301.6 4836.8 4882.2 4903.2 4906.6 4943.9 5058.9 5068.4 5100.6 5117.1 5122.7 5214.3 5231.9 5239.2 5247.9 5278.4 4987.5 5026.8 5071.6 5083.4 5100.4 5037.6 5106.8 5112.2 5144.0 5144.4 4832.2 4853.3 4861.6 4873.1 4990.4 5047.6 5050.9 5074.8 5077.2 5079.2 4881.1 4918.7 4942.5 4943.7 4950.5 09:29:58 INFO - PID 21036 | 09:29:58 INFO - PID 21036 | [#1] ext.html Cycles:180 Average:34769.34 Median:3318.64 stddev:53192.25 (1602.8%) stddev-sans-first:53279.68 09:29:58 INFO - PID 21036 | Values: 622.7 670.9 693.4 829.4 844.5 33857.3 34828.5 34946.8 34949.5 35016.6 31211.2 31503.2 31526.8 31537.8 31557.5 6364.6 6524.2 9047.5 9246.7 9281.7 9172.7 9267.4 9289.2 9312.7 9323.0 9479.7 9607.7 9620.6 9622.6 9625.1 9475.5 9510.9 9554.4 9565.3 9566.0 9341.0 9359.3 9393.0 9412.5 9426.5 35082.8 35514.5 35647.6 35689.6 35700.8 3329.4 3439.7 3462.6 3486.3 3518.8 24882.8 25046.0 25080.2 25368.7 25790.9 3307.9 3354.7 3380.8 3389.3 3441.6 113699.4 114385.4 160265.0 161822.2 161937.0 37727.0 37904.6 38098.0 38134.6 38143.6 39229.8 39323.2 39382.4 39396.6 39410.8 11839.9 11987.3 11987.4 12000.3 12049.7 10376.9 10401.5 10466.8 10480.5 10488.1 13080.7 13102.5 13189.2 13197.6 13346.1 5556.9 5625.4 5644.6 5679.7 5695.2 853.2 883.1 890.2 895.2 912.2 23613.5 24448.3 24534.3 24583.5 24733.0 942.6 944.8 952.0 955.1 967.3 12708.4 13018.4 13225.5 13232.7 13239.5 230026.0 231067.0 232057.0 232068.0 232435.0 44418.6 44877.8 45010.8 45058.8 45152.0 1205.9 1246.7 1250.2 1250.6 1252.1 22885.5 23095.9 23845.8 24152.9 24156.5 36087.1 36214.0 36342.2 36452.0 36452.0 225880.0 226559.0 227173.0 227432.0 228129.0 29011.7 29025.3 29044.0 29094.6 29114.1 34754.7 34812.3 35210.5 35576.3 36093.5 29648.0 29807.9 29834.3 30256.0 30677.8 30532.8 30611.8 31615.8 31881.0 32155.2 30555.2 30580.2 30615.4 30784.8 31529.2 25978.0 28058.4 28183.3 28233.4 28748.3 21766.5 21881.3 21884.0 22045.4 22156.7 09:29:58 INFO - PID 21036 | 09:29:58 INFO - PID 21036 | [#2] jquery.html Cycles:180 Average:29298.80 Median:28920.35 stddev:76684.80 (265.2%) stddev-sans-first:76869.20 09:29:58 INFO - PID 21036 | Values: 392.2 407.0 424.4 434.5 437.3 18425.4 18790.6 19383.1 19455.9 19621.9 18168.2 18475.8 19202.0 19415.6 19516.7 5519.8 6472.2 6741.8 6778.2 6788.5 6509.4 6636.4 6683.7 6718.9 6755.9 6793.9 6810.1 6812.3 6817.1 6820.2 6567.3 6741.8 6827.2 6865.9 6896.8 6484.7 6568.5 6678.2 6682.1 6697.0 22451.7 22918.7 23408.0 23409.5 23564.9 2228.0 2335.6 2353.3 2416.0 2539.8 13670.5 14081.6 14342.3 14567.3 14646.2 1871.7 1873.4 1876.2 1877.6 1880.1 85988.6 87106.4 87619.0 87857.0 89018.6 16305.8 16597.4 16655.3 16683.0 16894.0 28794.4 28813.0 29027.7 29038.9 29101.7 8265.1 8477.1 8539.9 8739.7 8843.8 6963.3 7036.2 7067.4 7127.9 7224.5 8712.5 8793.1 8865.9 8922.4 8968.6 3429.6 3490.3 3624.9 3643.4 3721.4 827.3 842.8 849.4 852.7 877.3 17818.2 18658.0 18929.4 19115.1 19161.5 847.0 854.0 869.7 878.7 887.1 9730.2 10557.8 10746.6 10792.7 10797.0 445280.0 451557.0 470568.0 478444.0 478459.0 87067.0 89120.6 89735.6 89893.0 90511.0 1061.6 1095.3 1111.0 1116.8 1125.7 15805.8 16013.4 16481.3 16595.7 16677.0 41972.8 41994.4 42084.8 42261.8 42376.8 54160.2 55031.2 55042.0 55609.0 56344.0 11038.3 11081.7 11222.6 11242.8 11737.9 12241.3 12596.1 13123.0 13256.6 13350.5 10529.8 10928.4 11055.3 11071.4 11130.2 10654.1 10894.7 10908.5 10937.9 11265.3 11947.9 11967.4 12109.3 12304.1 12340.7 11188.1 11252.4 11307.2 11338.9 11769.8 10426.6 10441.6 10484.6 10733.3 10929.1 09:29:58 INFO - PID 21036 | 09:29:58 INFO - PID 21036 | [#3] mootools.html Cycles:180 Average:5867.66 Median:4623.84 stddev:3757.94 (81.3%) stddev-sans-first:3753.86 09:29:58 INFO - PID 21036 | Values: 1454.8 1456.6 1477.8 1489.9 1533.8 7840.5 8135.3 9057.0 9469.2 9480.7 9051.6 9365.4 9373.5 9386.2 9402.0 2037.3 2062.1 2069.1 2082.7 2090.5 1946.1 1999.9 2002.6 2006.0 2010.8 1989.3 2012.3 2021.4 2023.1 2023.9 1819.9 1833.8 1837.4 1837.9 1838.1 1860.9 1873.4 1884.2 2002.0 2014.8 9372.7 9397.8 9434.2 9452.3 9465.7 4569.4 4788.5 4793.3 4797.3 4841.2 2678.6 2687.8 2723.7 2778.9 2853.4 1060.0 1115.9 1122.1 1176.5 1194.3 13685.9 13697.0 13728.2 13749.9 13764.5 3784.2 4042.5 4121.9 4166.4 4207.1 11703.5 11711.3 11739.4 11742.0 11786.5 4678.3 4821.3 4823.0 4826.2 4859.1 3695.1 3802.6 4287.8 4293.1 4355.9 3380.3 3407.3 3436.8 3501.4 3509.1 956.3 963.8 973.4 975.6 979.2 867.4 868.1 870.6 889.8 891.7 5781.2 5844.1 5853.3 5991.2 6248.8 912.2 924.4 944.2 953.3 997.3 3596.4 3702.0 3702.6 3718.9 3766.5 11981.5 12239.1 12334.6 12355.4 12360.8 12076.4 12093.5 12137.5 12171.1 12177.9 8292.4 8565.1 8580.0 8593.0 8866.6 5541.8 5701.5 5889.5 5898.1 5928.9 10655.7 10805.0 10811.4 10866.3 10991.1 10406.4 10505.2 10567.2 10713.8 10741.2 6635.6 6814.1 7582.7 7684.5 7936.7 8849.2 8917.2 8932.3 8999.4 9007.5 7442.4 7564.9 7670.0 7731.8 7749.1 7859.9 7908.4 7949.4 8008.5 8141.2 6825.2 6831.1 6886.7 6928.9 7043.4 5021.2 5047.7 5146.3 5147.4 5256.2 5290.9 5305.8 5390.2 5410.1 5478.2 09:29:58 INFO - PID 21036 | 09:29:58 INFO - PID 21036 | [#4] prototype.html Cycles:180 Average:4924.21 Median:5383.81 stddev:1258.44 (23.4%) stddev-sans-first:1214.39 09:29:58 INFO - PID 21036 | Values: 357.1 365.7 366.0 371.4 376.0 5195.2 5345.8 5400.4 5513.6 5527.4 5148.9 5317.5 5337.9 5342.3 5353.7 4632.6 4656.0 4668.5 4668.8 4683.6 4580.6 4630.9 4649.2 4659.9 4664.9 4609.0 4625.1 4641.4 4642.6 4645.4 4632.7 4636.0 4646.0 4648.9 4664.4 4410.8 4427.6 4449.0 4470.0 4512.1 5221.7 5235.3 5236.9 5241.8 5243.2 5416.5 5432.8 5433.7 5447.9 5451.8 5517.8 5632.7 5691.0 5709.5 5724.1 4898.6 4911.9 4912.3 4918.0 4921.7 5721.7 5766.4 5913.4 6009.9 6041.7 5142.2 5142.5 5145.8 5148.9 5156.3 5242.3 5268.2 5282.4 5293.8 5297.0 5220.0 5376.6 5402.6 5431.5 5432.3 5671.9 5686.7 5730.2 5749.5 5752.5 1816.9 1833.8 1835.5 1854.9 1864.9 1431.4 1445.8 1463.0 1482.4 1495.5 4954.9 4962.9 4972.8 4974.8 4976.9 5870.9 5878.6 5887.6 5889.6 5891.3 4829.0 4945.7 4980.8 4983.7 4986.6 3103.2 3105.8 3111.9 3128.5 3130.8 5395.2 5400.7 5406.7 5416.5 5424.8 5748.6 5913.2 5934.0 5960.9 5991.9 5383.7 5383.9 5396.7 5406.8 5412.1 5610.4 5812.3 5832.7 5872.2 5880.3 5576.6 5729.4 5731.1 5751.3 5821.6 5441.8 5446.5 5448.0 5454.2 5454.3 4658.8 4698.0 4699.0 4719.5 4732.3 5754.8 5840.7 5841.8 5858.4 5879.0 5659.9 5669.5 5671.9 5678.0 5679.1 5531.8 5609.0 5661.6 5667.1 5675.7 5528.1 5536.2 5545.9 5554.9 5575.7 5706.6 5725.9 5732.4 5736.2 5779.1 5367.2 5484.6 5532.6 5554.0 5583.9 09:29:58 INFO - PID 21036 | 09:29:58 INFO - PID 21036 | [#5] yui.html Cycles:180 Average:14391.66 Median:48061.14 stddev:17627.39 (36.7%) stddev-sans-first:17646.68 09:29:58 INFO - PID 21036 | Values: 659.2 680.3 686.2 690.2 698.3 11446.3 11784.0 11794.9 11817.1 11834.6 12776.4 12817.6 12890.8 12999.4 13023.4 807.6 812.9 837.1 839.4 840.9 836.0 851.5 853.0 854.1 855.2 861.0 870.6 878.2 879.9 881.9 852.5 867.1 873.9 874.0 874.5 642.9 643.7 643.7 644.6 646.1 13850.6 14090.4 14102.9 14114.5 14326.1 12639.7 14333.5 14392.0 14407.9 14442.6 10739.0 10809.4 10811.2 11043.5 11064.2 3186.1 3203.2 3213.6 3238.4 3321.3 62438.4 63082.7 63386.0 63660.0 63962.4 10387.5 10425.4 10598.5 10753.9 10758.0 18450.3 18698.3 20695.6 20755.7 21054.5 6618.7 6728.2 6785.1 6843.1 6894.5 3403.6 3530.0 3535.9 3543.2 3546.1 2861.7 2871.6 2881.5 2898.8 2916.9 472.4 475.3 482.2 487.7 489.2 607.5 637.9 640.2 650.9 672.3 8767.0 9429.5 9478.1 9492.0 9673.8 594.7 626.4 631.9 638.3 639.5 4320.7 4410.4 4447.4 4494.0 4496.6 60574.7 61030.4 61897.0 61929.6 63439.4 49180.3 55457.4 56407.0 56672.4 58053.4 47940.0 48059.8 48062.5 48350.0 49431.8 8407.2 8612.5 8638.7 8658.2 8813.7 34764.3 35452.6 35804.0 36110.6 36442.6 40458.4 40874.8 41436.8 42281.0 43253.1 9836.0 9948.9 9986.6 10103.9 10440.9 11299.8 11374.8 11377.2 11407.5 11581.6 10893.9 10988.0 11165.2 11176.7 11431.8 9943.6 9973.0 10134.7 10216.3 10464.8 10456.5 10522.9 10878.2 11055.7 11429.1 8939.6 9082.9 9225.5 9352.0 9498.6 8499.2 9430.1 9784.5 10471.7 10659.3 09:29:58 INFO - PID 21036 | -------- Summary: end -------- 09:29:58 INFO - PID 21036 | 09:29:59 INFO - TEST-INFO | 21036: exit 0 09:29:59 INFO - TEST-OK | dromaeo_css | took 1120366ms 09:29:59 INFO - TEST-START | kraken 09:29:59 INFO - operating with platform_type : linux_ 09:29:59 INFO - Initialising browser for kraken test... 09:29:59 INFO - TEST-INFO | started process 21418 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdHaUWi/profile http://localhost:51373/getInfo.html) 09:30:04 INFO - PID 21418 | __metrics Screen width/height:1600/1200 09:30:04 INFO - PID 21418 | colorDepth:24 09:30:04 INFO - PID 21418 | Browser inner width/height: 1024/697 09:30:04 INFO - PID 21418 | __metrics 09:30:04 ERROR - PID 21418 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 09:30:05 INFO - TEST-INFO | 21418: exit 0 09:30:05 INFO - Browser initialized. 09:30:05 INFO - Running cycle 1/1 for kraken test... 09:30:05 INFO - TEST-INFO | started process 21547 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdHaUWi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:30:18 INFO - PID 21547 | RSS: Main: 154882048 09:30:18 INFO - PID 21547 | 09:30:20 INFO - PID 21547 | Cycle 1(1): loaded http://localhost:51373/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51373/tests/kraken/driver.html?testName=audio-beat-detection) 09:30:21 INFO - PID 21547 | RSS: Main: 160817152 09:30:21 INFO - PID 21547 | 09:30:24 INFO - PID 21547 | Cycle 1(1): loaded http://localhost:51373/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51373/tests/kraken/driver.html?testName=audio-dft) 09:30:25 INFO - PID 21547 | RSS: Main: 159916032 09:30:25 INFO - PID 21547 | 09:30:28 INFO - PID 21547 | Cycle 1(1): loaded http://localhost:51373/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51373/tests/kraken/driver.html?testName=audio-fft) 09:30:28 INFO - PID 21547 | RSS: Main: 156737536 09:30:28 INFO - PID 21547 | 09:30:30 INFO - PID 21547 | Cycle 1(1): loaded http://localhost:51373/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51373/tests/kraken/driver.html?testName=audio-oscillator) 09:30:31 INFO - PID 21547 | RSS: Main: 157630464 09:30:31 INFO - PID 21547 | 09:30:33 INFO - PID 21547 | Cycle 1(1): loaded http://localhost:51373/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51373/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:30:34 INFO - PID 21547 | RSS: Main: 158810112 09:30:34 INFO - PID 21547 | 09:30:37 INFO - PID 21547 | Cycle 1(1): loaded http://localhost:51373/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51373/tests/kraken/driver.html?testName=imaging-darkroom) 09:30:38 INFO - PID 21547 | RSS: Main: 163336192 09:30:38 INFO - PID 21547 | 09:30:41 INFO - PID 21547 | Cycle 1(1): loaded http://localhost:51373/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51373/tests/kraken/driver.html?testName=imaging-desaturate) 09:30:42 INFO - PID 21547 | RSS: Main: 160858112 09:30:42 INFO - PID 21547 | 09:30:45 INFO - PID 21547 | Cycle 1(1): loaded http://localhost:51373/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51373/tests/kraken/driver.html?testName=json-parse-financial) 09:30:45 INFO - PID 21547 | RSS: Main: 161730560 09:30:45 INFO - PID 21547 | 09:30:47 INFO - PID 21547 | Cycle 1(1): loaded http://localhost:51373/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51373/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:30:48 INFO - PID 21547 | RSS: Main: 162926592 09:30:48 INFO - PID 21547 | 09:30:50 INFO - PID 21547 | Cycle 1(1): loaded http://localhost:51373/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51373/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:30:50 INFO - PID 21547 | RSS: Main: 162295808 09:30:50 INFO - PID 21547 | 09:30:53 INFO - PID 21547 | Cycle 1(1): loaded http://localhost:51373/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51373/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:30:53 INFO - PID 21547 | RSS: Main: 162750464 09:30:53 INFO - PID 21547 | 09:30:56 INFO - PID 21547 | Cycle 1(1): loaded http://localhost:51373/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51373/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:30:57 INFO - PID 21547 | RSS: Main: 163389440 09:30:57 INFO - PID 21547 | 09:31:00 INFO - PID 21547 | Cycle 1(1): loaded http://localhost:51373/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51373/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:31:01 INFO - PID 21547 | RSS: Main: 163401728 09:31:01 INFO - PID 21547 | 09:31:02 INFO - PID 21547 | Cycle 1(1): loaded http://localhost:51373/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51373/tests/kraken/driver.html?testName=ai-astar) 09:31:03 INFO - PID 21547 | RSS: Main: 165294080 09:31:03 INFO - PID 21547 | 09:31:03 INFO - PID 21547 | __start_tp_report 09:31:03 INFO - PID 21547 | _x_x_mozilla_page_load 09:31:03 INFO - PID 21547 | _x_x_mozilla_page_load_details 09:31:03 INFO - PID 21547 | |i|pagename|runs| 09:31:03 INFO - PID 21547 | |0;ai-astar;110;119;118;119;118;118;118;118;123;117 09:31:03 INFO - PID 21547 | |1;audio-beat-detection;158;127;126;174;125;128;127;127;152;126 09:31:03 INFO - PID 21547 | |2;audio-dft;164;164;166;168;165;168;164;166;166;164 09:31:03 INFO - PID 21547 | |3;audio-fft;78;75;76;116;76;77;75;75;89;75 09:31:03 INFO - PID 21547 | |4;audio-oscillator;85;85;125;85;88;85;100;87;90;110 09:31:03 INFO - PID 21547 | |5;imaging-gaussian-blur;114;114;114;114;114;115;116;114;115;114 09:31:03 INFO - PID 21547 | |6;imaging-darkroom;151;151;150;151;150;151;151;151;150;151 09:31:03 INFO - PID 21547 | |7;imaging-desaturate;95;94;94;94;95;95;94;96;95;95 09:31:03 INFO - PID 21547 | |8;json-parse-financial;67;67;68;68;69;69;68;69;68;70 09:31:03 INFO - PID 21547 | |9;json-stringify-tinderbox;57;57;60;57;57;57;57;56;57;57 09:31:03 INFO - PID 21547 | |10;stanford-crypto-aes;83;83;84;92;83;84;83;83;84;82 09:31:03 INFO - PID 21547 | |11;stanford-crypto-ccm;138;134;150;137;134;142;139;132;189;149 09:31:03 INFO - PID 21547 | |12;stanford-crypto-pbkdf2;189;199;194;184;198;193;195;196;193;192 09:31:03 INFO - PID 21547 | |13;stanford-crypto-sha256-iterative;74;74;75;71;73;72;73;75;74;71 09:31:03 INFO - PID 21547 | __end_tp_report 09:31:03 INFO - PID 21547 | __start_cc_report 09:31:03 INFO - PID 21547 | _x_x_mozilla_cycle_collect,869 09:31:03 INFO - PID 21547 | __end_cc_report 09:31:03 INFO - PID 21547 | __startTimestamp1489681863203__endTimestamp 09:31:03 INFO - PID 21547 | 09:31:03 INFO - PID 21547 | ------- Summary: start ------- 09:31:03 INFO - PID 21547 | Number of tests: 14 09:31:03 INFO - PID 21547 | 09:31:03 INFO - PID 21547 | [#0] ai-astar Cycles:10 Average:117.80 Median:118.00 stddev:3.19 (2.7%) stddev-sans-first:1.73 09:31:03 INFO - PID 21547 | Values: 110.0 119.0 118.0 119.0 118.0 118.0 118.0 118.0 123.0 117.0 09:31:03 INFO - PID 21547 | 09:31:03 INFO - PID 21547 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:127.50 stddev:17.64 (13.8%) stddev-sans-first:17.00 09:31:03 INFO - PID 21547 | Values: 158.0 127.0 126.0 174.0 125.0 128.0 127.0 127.0 152.0 126.0 09:31:03 INFO - PID 21547 | 09:31:03 INFO - PID 21547 | [#2] audio-dft Cycles:10 Average:165.50 Median:166.00 stddev:1.58 (1.0%) stddev-sans-first:1.58 09:31:03 INFO - PID 21547 | Values: 164.0 164.0 166.0 168.0 165.0 168.0 164.0 166.0 166.0 164.0 09:31:03 INFO - PID 21547 | 09:31:03 INFO - PID 21547 | [#3] audio-fft Cycles:10 Average:81.20 Median:76.00 stddev:12.94 (17.0%) stddev-sans-first:13.68 09:31:03 INFO - PID 21547 | Values: 78.0 75.0 76.0 116.0 76.0 77.0 75.0 75.0 89.0 75.0 09:31:03 INFO - PID 21547 | 09:31:03 INFO - PID 21547 | [#4] audio-oscillator Cycles:10 Average:94.00 Median:85.00 stddev:13.65 (16.1%) stddev-sans-first:14.09 09:31:03 INFO - PID 21547 | Values: 85.0 85.0 125.0 85.0 88.0 85.0 100.0 87.0 90.0 110.0 09:31:03 INFO - PID 21547 | 09:31:03 INFO - PID 21547 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 09:31:03 INFO - PID 21547 | Values: 114.0 114.0 114.0 114.0 114.0 115.0 116.0 114.0 115.0 114.0 09:31:03 INFO - PID 21547 | 09:31:03 INFO - PID 21547 | [#6] imaging-darkroom Cycles:10 Average:150.70 Median:151.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 09:31:03 INFO - PID 21547 | Values: 151.0 151.0 150.0 151.0 150.0 151.0 151.0 151.0 150.0 151.0 09:31:03 INFO - PID 21547 | 09:31:03 INFO - PID 21547 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 09:31:03 INFO - PID 21547 | Values: 95.0 94.0 94.0 94.0 95.0 95.0 94.0 96.0 95.0 95.0 09:31:03 INFO - PID 21547 | 09:31:03 INFO - PID 21547 | [#8] json-parse-financial Cycles:10 Average:68.30 Median:68.50 stddev:0.95 (1.4%) stddev-sans-first:0.88 09:31:03 INFO - PID 21547 | Values: 67.0 67.0 68.0 68.0 69.0 69.0 68.0 69.0 68.0 70.0 09:31:03 INFO - PID 21547 | 09:31:03 INFO - PID 21547 | [#9] json-stringify-tinderbox Cycles:10 Average:57.20 Median:57.00 stddev:1.03 (1.8%) stddev-sans-first:1.09 09:31:03 INFO - PID 21547 | Values: 57.0 57.0 60.0 57.0 57.0 57.0 57.0 56.0 57.0 57.0 09:31:03 INFO - PID 21547 | 09:31:03 INFO - PID 21547 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:83.50 stddev:2.85 (3.4%) stddev-sans-first:2.99 09:31:03 INFO - PID 21547 | Values: 83.0 83.0 84.0 92.0 83.0 84.0 83.0 83.0 84.0 82.0 09:31:03 INFO - PID 21547 | 09:31:03 INFO - PID 21547 | [#11] stanford-crypto-ccm Cycles:10 Average:144.40 Median:140.50 stddev:16.81 (12.0%) stddev-sans-first:17.67 09:31:03 INFO - PID 21547 | Values: 138.0 134.0 150.0 137.0 134.0 142.0 139.0 132.0 189.0 149.0 09:31:03 INFO - PID 21547 | 09:31:03 INFO - PID 21547 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.30 Median:194.50 stddev:4.37 (2.2%) stddev-sans-first:4.35 09:31:03 INFO - PID 21547 | Values: 189.0 199.0 194.0 184.0 198.0 193.0 195.0 196.0 193.0 192.0 09:31:03 INFO - PID 21547 | 09:31:03 INFO - PID 21547 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.20 Median:74.00 stddev:1.48 (2.0%) stddev-sans-first:1.54 09:31:03 INFO - PID 21547 | Values: 74.0 74.0 75.0 71.0 73.0 72.0 73.0 75.0 74.0 71.0 09:31:03 INFO - PID 21547 | -------- Summary: end -------- 09:31:03 INFO - PID 21547 | 09:31:04 INFO - TEST-INFO | 21547: exit 0 09:31:04 INFO - TEST-OK | kraken | took 64916ms 09:31:04 INFO - SUITE-END | took 1185s 09:31:04 INFO - Completed test suite (00:19:45) 09:31:04 INFO - javascript benchmark 09:31:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6784.551143067005, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2630.4739052189566, 2649.4303724699184, 2659.2952867490117, 2687.5834245691913, 2690.87891044903, 4784.282357646355, 4880.999395123996, 4900.2404627282785, 4933.210686290186, 4945.826896058638, 4857.319975203474, 4863.416390033192, 4899.265110233467, 4942.209246520558, 4942.431620363659, 4492.326151077332, 4538.183127037132, 4545.977155140084, 4552.408186935711, 4570.81716731332, 4573.039661671053, 4591.265397536395, 4592.035672508777, 4595.954040459603, 4599.103174880899, 4426.911461770746, 4463.553644635543, 4476.910461790746, 4509.143262780061, 4517.435320584927, 4530.8867278317975, 4535.841245556389, 4544.8863778405475, 4571.474280457756, 4586.954130458686, 4488.708233964782, 4492.14649216651, 4502.932456013163, 4508.301213311942, 4529.365888775574, 4739.810407583693, 4758.666893317469, 4836.733979631119, 4901.852944411673, 4907.509249075099, 5192.247124167013, 5228.163493840967, 5235.947640523584, 5262.0, 5314.73426328682, 5229.2156176573435, 5264.46931717384, 5264.6051546134195, 5271.86820329491, 5275.762590683428, 4805.831795887197, 4929.654924155347, 4931.260310953386, 4933.654644174945, 4985.651004429691, 5228.9738551307, 5361.035013697494, 5399.649022813504, 5404.216388623706, 5411.215373770781, 5014.548690617789, 5067.771950262247, 5136.998285334399, 5140.691558506502, 5150.227465880073, 5210.452902445189, 5253.50091741284, 5257.316548848626, 5271.20931860224, 5291.285676433709, 5080.212567052113, 5200.713960732196, 5210.583153347723, 5224.947750522446, 5254.028004819121, 5145.716985565755, 5213.113770658997, 5235.738213089407, 5239.0, 5271.393789714232, 4471.61991230749, 4480.910381792346, 4489.551044895549, 4491.326301054868, 4524.072565124142, 3851.2682590308314, 3856.325143099946, 3870.9225815484097, 3895.590962948906, 3896.7467114637457, 4477.865664030084, 4513.638908887281, 4543.477500087466, 4574.4968053514085, 4579.38178345919, 5237.423883372826, 5258.947410525846, 5260.973695131576, 5264.315638966987, 5266.710330931835, 4549.977250113794, 4565.338025986213, 4573.1646379245085, 4574.245249533857, 4610.05493873755, 4648.9772250104925, 4679.625629949664, 4682.274247491644, 4683.297505374153, 4792.904141917142, 4794.832180873653, 4869.0, 4908.582770464551, 4911.607071434348, 4920.065187614342, 5253.343332083489, 5268.81559145428, 5271.235670827632, 5277.498637629419, 5301.628885977944, 4836.830710925101, 4882.170031094652, 4903.215485522299, 4906.582940450102, 4943.876403089952, 5058.873528161826, 5068.417132029864, 5100.642954993264, 5117.130087884994, 5122.667026643257, 5214.270002199771, 5231.947680523298, 5239.161734122522, 5247.868803279795, 5278.4193738687945, 4987.451380348086, 5026.773795179152, 5071.644984851025, 5083.415407228068, 5100.438951715382, 5037.571806396498, 5106.7701953412925, 5112.156494178419, 5144.022635699355, 5144.356955380577, 4832.1543729846435, 4853.344798452164, 4861.562459378673, 4873.074115917964, 4990.4011518618, 5047.646664733434, 5050.898982020412, 5074.771635276347, 5077.162268225701, 5079.161938280143, 4881.145799485005, 4918.6556941015215, 4942.505749425029, 4943.65394422387, 4950.504949505021], "value": 4796.241224004551, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.7477871462056, 670.926198118207, 693.3551796828947, 829.394541984351, 844.4933040175891, 33857.322853542915, 34828.47757283637, 34946.82526587367, 34949.47575786362, 35016.64983350166, 31211.219719507022, 31503.21241968952, 31526.84236578814, 31537.842310788415, 31557.526637100404, 6364.554481186319, 6524.184476940382, 9047.547622618875, 9246.67636632718, 9281.675141370053, 9172.6789562365, 9267.351285410025, 9289.21041711452, 9312.72061838146, 9323.0, 9479.668211612596, 9607.711768646917, 9620.56707448163, 9622.566984485715, 9625.133737963582, 9475.47884866332, 9510.857337139949, 9554.426734395924, 9565.330426870121, 9566.043395660448, 9341.0, 9359.29805264608, 9392.953035234848, 9412.529373531295, 9426.481543515103, 35082.82458587716, 35514.46728299077, 35647.64352356469, 35689.643103568895, 35700.82149589261, 3329.3674201901563, 3439.673231043047, 3462.6191118976894, 3486.2678837443914, 3518.7712798668003, 24882.751172488224, 25046.0, 25080.247592572254, 25368.74631253701, 25790.871045644653, 3307.9173020674434, 3354.7483938704695, 3380.8309584520675, 3389.254364039907, 3441.5525981622477, 113699.43150284195, 114385.42807285994, 160265.0, 161822.19088904597, 161937.0, 37727.0, 37904.62095379066, 38098.0, 38134.61865381366, 38143.61856381456, 39229.80385098056, 39323.213535729694, 39382.40926386107, 39396.60603393929, 39410.80294598566, 11839.940800296115, 11987.280763154238, 11987.400629968466, 12000.279983201035, 12049.698757531132, 10376.948115259527, 10401.53193106312, 10466.842997355046, 10480.528376223088, 10488.05607495329, 13080.672983175307, 13102.475900964046, 13189.208647481182, 13197.604071877859, 13346.13250138738, 5556.91664625031, 5625.424881033317, 5644.633098848643, 5679.716014199274, 5695.202671625976, 853.185208126238, 883.1257055515123, 890.17213990989, 895.2256298302052, 912.224609082288, 23613.527729445657, 24448.266552003468, 24534.263972080866, 24583.508329833305, 24733.0, 942.5946842857637, 944.8110377924305, 951.9762005949768, 955.0544960489066, 967.2890425537224, 12708.364581770875, 13018.414171362312, 13225.53710620143, 13232.669183270495, 13239.470421183238, 230026.0, 231067.0, 232057.0, 232068.0, 232435.0, 44418.55581444209, 44877.775611122386, 45010.77494612506, 45058.774706126256, 45152.0, 1205.873383294759, 1246.7132559511363, 1250.2186133666457, 1250.6185613388004, 1252.1485389934749, 22885.54228915379, 23095.88452057729, 23845.761542384353, 24152.87923560371, 24156.51686966286, 36087.09782255465, 36214.0, 36342.18525035981, 36452.0, 36452.0, 225880.0, 226559.0, 227173.0, 227432.0, 228129.0, 29011.709882901745, 29025.274368140967, 29044.0, 29094.56358154587, 29114.126576202747, 34754.652453476156, 34812.303753925284, 35210.47184292186, 35576.288474229856, 36093.45859812157, 29648.0, 29807.850960745058, 29834.254143646584, 30256.0, 30677.846610766803, 30532.84733576407, 30611.846940766045, 31615.841920790248, 31881.043568692978, 32155.196120097185, 30555.2361190972, 30580.235494112825, 30615.387692246477, 30784.846075769477, 31529.21176970594, 25978.0, 28058.370368888904, 28183.295417614725, 28233.43533129367, 28748.281292967007, 21766.45583860416, 21881.343559693236, 21884.0, 22045.448863778533, 22156.66764998494], "value": 15767.389073593511, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.21165457430556, 406.9572694867041, 424.3782858112865, 434.5415586556184, 437.29813649093205, 18425.447236582924, 18790.62418751626, 19383.127759250834, 19455.902720486414, 19621.901890490564, 18168.182431790567, 18475.815241847577, 19202.0, 19415.611687766235, 19516.707249391235, 5519.834404967857, 6472.223333200022, 6741.797746067626, 6778.22050464198, 6788.456923446112, 6509.414152726254, 6636.440436497443, 6683.665816709169, 6718.899216511756, 6755.864882702343, 6793.864122717543, 6810.114685090931, 6812.250652428229, 6817.113775209215, 6820.181578210621, 6567.277599464079, 6741.831454213635, 6827.180738311409, 6865.897011544831, 6896.827579310558, 6484.708188131545, 6568.474522038226, 6678.198616166092, 6682.131322927989, 6696.957427984463, 22451.663225051638, 22918.65622015671, 23408.0, 23409.531809363718, 23564.93655824732, 2227.9777202227933, 2335.591271527476, 2353.3410645019253, 2416.0, 2539.7841183499427, 13670.52153174634, 14081.577552673334, 14342.282885855666, 14567.344469498898, 14646.194459304734, 1871.6818140916077, 1873.4192400355796, 1876.2307453943954, 1877.5963167918962, 1880.0881572437356, 85988.5700571493, 87106.38680839648, 87619.0, 87857.0, 89018.5549072257, 16305.836941630669, 16597.423244791724, 16655.3337866484, 16683.0, 16894.0, 28794.424111517652, 28813.0, 29027.709722902924, 29038.85480572626, 29101.708982910324, 8265.132161123116, 8477.109903460048, 8539.871901921477, 8739.737807865775, 8843.778905527413, 6963.293992971754, 7036.226015138331, 7067.36393724557, 7127.857442851114, 7224.4942854000765, 8712.477251364939, 8793.120687931156, 8865.867011994826, 8922.420042697204, 8968.596413161424, 3429.5713035870517, 3490.2844916791996, 3624.8550057997913, 3643.3806252937065, 3721.3859713147567, 827.2596026556198, 842.8482873082904, 849.4054162086564, 852.6589364254348, 877.3332267476636, 17818.198181081883, 18658.0, 18929.43211703651, 19115.097011402933, 19161.52096197606, 847.0386111763194, 854.0, 869.7216890595075, 878.7495563764296, 887.1439061305871, 9730.17293530058, 10557.77688924421, 10746.570137194425, 10792.730181745519, 10796.974287442681, 445280.0, 451557.0, 470568.0, 478444.0, 478459.0, 87067.0, 89120.55439722759, 89735.55132224297, 89893.0, 90511.0, 1061.5647584490323, 1095.2552264460085, 1110.9500072496758, 1116.771061932302, 1125.6960620632383, 15805.841941580436, 16013.439529616408, 16481.34074637001, 16595.693636418084, 16677.0, 41972.79013604912, 41994.37008444937, 42084.789576051924, 42261.78869105635, 42376.78811605922, 54160.187597185286, 55031.17453238284, 55042.0, 55609.0, 56344.0, 11038.282511636718, 11081.722956926142, 11222.55109795599, 11242.831357529807, 11737.896293926939, 12241.265524068584, 12596.118271720892, 13123.0, 13256.602301930958, 13350.532731354357, 10529.78940421172, 10928.398938058483, 11055.336679799238, 11071.391073491035, 11130.16523760705, 10654.094401975608, 10894.67315980522, 10908.454577271263, 10937.890621094006, 11265.324080555192, 11947.880521195024, 11967.401629918295, 12109.333986630467, 12304.138710290194, 12340.691482713004, 11188.10495160369, 11252.381119038531, 11307.208495405243, 11338.943305283421, 11769.764604707687, 10426.582936682447, 10441.58233670675, 10484.580616775243, 10733.302335348177, 10929.125669946545], "value": 10121.10026136322, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1454.774509950958, 1456.5630310906724, 1477.8004969329138, 1489.8957073004888, 1533.8236102848173, 7840.490368126082, 8135.308498777611, 9057.0, 9469.195118414935, 9480.668176613819, 9051.59267832949, 9365.438073715583, 9373.531323433817, 9386.20217281532, 9401.952990235039, 2037.3480486244407, 2062.072067569592, 2069.0585783468487, 2082.66677331627, 2090.519180588458, 1946.0658883735816, 1999.8600097993146, 2002.5894691588262, 2005.9598808023832, 2010.8190262876412, 1989.3037436897116, 2012.3158126237076, 2021.353166986565, 2023.059277435992, 2023.8583299169063, 1819.854411647065, 1833.8441232495254, 1837.4303965770655, 1837.8713490055702, 1838.108517369075, 1860.9255629774793, 1873.3536929759211, 1884.2274667386444, 2001.9599608007904, 2014.7582290125279, 9372.718818435458, 9397.76505587359, 9434.19809316209, 9452.338336316461, 9465.66870159541, 4569.428821397325, 4788.521147885219, 4793.304970779218, 4797.328374027639, 4841.1845557915785, 2678.638383818179, 2687.796007274036, 2723.72762723728, 2778.8749506272266, 2853.4150499147627, 1059.9718273274989, 1115.8884111588777, 1122.119136477861, 1176.4647284717378, 1194.331174542259, 13685.931570342085, 13697.0, 13728.176309421468, 13749.931250343785, 13764.518241861486, 3784.1863999239745, 4042.4744783178585, 4121.855735049259, 4166.437530933285, 4207.0744436224595, 11703.531858725555, 11711.297322160697, 11739.413029348498, 11742.0, 11786.528538858522, 4678.251479763221, 4821.276808478756, 4823.03539292143, 4826.227803551415, 4859.052484765437, 3695.0947017980766, 3802.562705288927, 4287.849925252601, 4293.141371725667, 4355.934660980088, 3380.323935212967, 3407.267437500949, 3436.810975396327, 3501.3872572300256, 3509.0876372143425, 956.2827879090682, 963.7590602349412, 973.3624475968111, 975.5561768582537, 979.1579241852002, 867.3754896474528, 868.0754995929315, 870.6256309786852, 889.7597648634833, 891.7280229529925, 5781.19063331134, 5844.064949608042, 5853.268341457318, 5991.191189189491, 6248.843778905563, 912.1608120529129, 924.4083786376724, 944.244604316544, 953.3088510829593, 997.2570435025953, 3596.3526565218513, 3702.0, 3702.5742039665242, 3718.9070273243387, 3766.5103536540623, 11981.46083426248, 12239.143259971894, 12334.629961101182, 12355.444005019795, 12360.814587781011, 12076.396180190955, 12093.45579448927, 12137.5144994201, 12171.0871684624, 12177.93911030457, 8292.37807164465, 8565.10066443027, 8580.0, 8593.0, 8866.556672166365, 5541.778328866801, 5701.486866181897, 5889.52883769306, 5898.085796701518, 5928.940710593011, 10655.680329590125, 10805.0, 10811.35131892089, 10866.293690910066, 10991.120710343313, 10406.375617462976, 10505.212109091695, 10567.154627629925, 10713.785724285626, 10741.24811263204, 6635.568688035262, 6814.114165158498, 7582.734604288823, 7684.462087654034, 7936.682532698627, 8849.20357167858, 8917.242034426887, 8932.330075244252, 8999.415038022507, 9007.459552426875, 7442.367398770949, 7564.9243507566425, 7670.0, 7731.7680469586, 7749.07011158642, 7859.921400785919, 7908.4068694846965, 7949.443538952218, 8008.479448835807, 8141.185881411811, 6825.249222585412, 6831.146106736658, 6886.724531018703, 6928.861422771617, 7043.401310888632, 5021.246812977936, 5047.722375269395, 5146.330976972969, 5147.433782283871, 5256.159014557652, 5290.8941821164135, 5305.814296499604, 5390.164524498709, 5410.053240682786, 5478.150886612581], "value": 4466.735615605633, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.0662716995057, 365.67272291299287, 365.9707223422126, 371.3946267583838, 375.98684046058384, 5195.1555848672, 5345.839624811262, 5400.405955344914, 5513.566472717097, 5527.447255274471, 5148.922766158511, 5317.46825317468, 5337.893242135165, 5342.332208473941, 5353.678779273237, 4632.559906808856, 4655.976720116395, 4668.463126740435, 4668.813247470114, 4683.601893839028, 4580.633549316047, 4630.907381852361, 4649.163150632885, 4659.860204193863, 4664.8919433220735, 4609.0, 4625.144348295559, 4641.419822522184, 4642.632257548657, 4645.420557010616, 4632.7220366777765, 4636.0, 4646.0, 4648.86053418398, 4664.440267167927, 4410.779461026936, 4427.601515863587, 4448.977755111204, 4469.9776501117285, 4512.052468981517, 5221.660592061504, 5235.293235413209, 5236.8690782730355, 5241.764120614544, 5243.239730239094, 5416.51251387377, 5432.809851655173, 5433.728313584305, 5447.918281225785, 5451.754671039813, 5517.751701173417, 5632.733394276364, 5690.955338893672, 5709.514691251248, 5724.141378793172, 4898.583620392271, 4911.926321105187, 4912.287718280865, 4917.967226882359, 4921.680090794087, 5721.742521586539, 5766.394528574481, 5913.408659134096, 6009.909851352233, 6041.667708276043, 5142.151544995108, 5142.460041695568, 5145.76844042019, 5148.897022059613, 5156.2523434102595, 5242.318498595235, 5268.209768534751, 5282.392524859613, 5293.788248470018, 5297.0, 5219.982103489859, 5376.569874410038, 5402.648827826179, 5431.456854314537, 5432.348118225838, 5671.886562268732, 5686.687232202268, 5730.19777231188, 5749.482546570829, 5752.453516915886, 1816.8644597126795, 1833.8080247839011, 1835.5135888989428, 1854.935077272289, 1864.8787828791085, 1431.427429028397, 1445.7831325301079, 1462.9707405851823, 1482.4440834686993, 1495.5289083938742, 4954.934689041874, 4962.851114466572, 4972.801087956441, 4974.8309147351065, 4976.850694479172, 5870.911936320958, 5878.617889837147, 5887.646741195542, 5889.645381562264, 5891.293044834647, 4828.985912958248, 4945.678530895552, 4980.825671101494, 4983.725895075733, 4986.576141028053, 3103.162146220508, 3105.813651180936, 3111.9222019449694, 3128.4681604127354, 3130.8434578270994, 5395.163749618815, 5400.734284072997, 5406.702631355313, 5416.51251387377, 5424.755885985141, 5748.597598168088, 5913.231279933581, 5933.970330148321, 5960.910586341209, 5991.940080599225, 5383.730813459311, 5383.892322153535, 5396.676199428047, 5406.78372865081, 5412.107002344726, 5610.382857885547, 5812.3025236971835, 5832.650040997553, 5872.236609240771, 5880.323762767336, 5576.637518561442, 5729.427057294237, 5731.054376027909, 5751.252337196138, 5821.563382746226, 5441.809536666197, 5446.482584154499, 5447.972760136333, 5454.209139674805, 5454.263674403905, 4658.790354433991, 4698.0, 4698.976505117453, 4719.504452032625, 4732.266498692708, 5754.827355179351, 5840.678762668094, 5841.795537156179, 5858.355580886014, 5879.0, 5659.9151012735665, 5669.518090962314, 5671.858203544944, 5678.019040004031, 5679.119736440858, 5531.834044978657, 5609.0, 5661.603687741983, 5667.121596152603, 5675.716214189357, 5528.087865502147, 5536.197251398606, 5545.9185458836755, 5554.888902221852, 5575.665460072409, 5706.600537962303, 5725.914111288251, 5732.426757324234, 5736.168255602997, 5779.104238842986, 5367.221752845893, 5484.643498172606, 5532.612717109763, 5554.0, 5583.8883222336135], "value": 4551.22732205307, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.2418718473757, 680.2653134614616, 686.1902954513672, 690.1717938473831, 698.3191388396315, 11446.255993360437, 11784.0, 11794.941025294862, 11817.113716471267, 11834.644960651172, 12776.361181940889, 12817.55138570148, 12890.80663790044, 12999.415026323815, 13023.413946372413, 807.56391548564, 812.8536863364607, 837.0541288344162, 839.3788596438652, 840.8990921089447, 836.0469065265602, 851.4933614499363, 853.0190281176665, 854.1287886355914, 855.2345650642671, 861.0184389795638, 870.5516658920661, 878.2183856367838, 879.8592225243963, 881.9029906710289, 852.5012867472536, 867.0505796153202, 873.8863947686792, 874.0, 874.5059041641516, 642.8907085795425, 643.6813777180279, 643.6910283064109, 644.577801539992, 646.0632083478974, 13850.584482465541, 14090.365933532914, 14102.92948535261, 14114.505992290324, 14326.140431574138, 12639.747205055939, 14333.513114001007, 14392.0, 14407.927960360235, 14442.566722998326, 10739.0, 10809.35143891369, 10811.243212975094, 11043.503042363112, 11064.17018723599, 3186.139742269574, 3203.19920019995, 3213.614366276062, 3238.4008958342783, 3321.285923526453, 62438.37561624371, 63082.684586577234, 63386.0, 63660.0, 63962.36037639657, 10387.532561034772, 10425.426601536989, 10598.523066462029, 10753.892461075446, 10758.031777140095, 18450.26198952027, 18698.34555790541, 20695.58608827845, 20755.68866467004, 21054.473638158863, 6618.702158402787, 6728.226253980757, 6785.084013658093, 6843.144606924135, 6894.482913781487, 3403.5745531808525, 3530.046887340455, 3535.9292814143573, 3543.2382037861985, 3546.1489242581592, 2861.7138286171635, 2871.5836203750755, 2881.4957382458406, 2898.7970842040854, 2916.9270768230963, 472.39297502708854, 475.3487721821127, 482.2163983526769, 487.7049385122021, 489.1928318274804, 607.4502575169479, 637.8915584350672, 640.1869625575494, 650.9308905288563, 672.3142394757418, 8766.956165219133, 9429.546175817135, 9478.146966773023, 9492.0, 9673.758156046015, 594.7264258441079, 626.4080443980395, 631.9368063193643, 638.2755572425331, 639.5395315372924, 4320.719153255029, 4410.42664453619, 4447.399601053881, 4494.0, 4496.57282558163, 60574.69712651408, 61030.38969610336, 61897.0, 61929.59387233746, 63439.36560634427, 49180.262296065586, 55457.44542554604, 56407.0, 56672.43327566671, 58053.4194658048, 47940.0, 48059.75970120127, 48062.5193748058, 48350.0, 49431.75284123628, 8407.159284071544, 8612.48325100496, 8638.740837774876, 8658.177473140042, 8813.735587932373, 34764.30471390609, 35452.64547354596, 35804.0, 36110.638893610725, 36442.63557364392, 40458.39312410375, 40874.79562602168, 41436.792816035726, 42281.0, 43253.13493730171, 9835.950820245853, 9948.850767238642, 9986.60053597848, 10103.949480252551, 10440.895591043993, 11299.774004519699, 11374.772504549697, 11377.203595748219, 11407.486663100346, 11581.594644187413, 10893.945530272298, 10988.0, 11165.218434709492, 11176.664700059011, 11431.771364572829, 9943.552540135566, 9973.0, 10134.746631334276, 10216.335938163995, 10464.843027354747, 10456.477176141314, 10522.947385263025, 10878.184136189975, 11055.668329950115, 11429.142814289127, 8939.59771810257, 9082.909170908471, 9225.49259790718, 9352.0, 9498.620055197714, 8499.235068843835, 9430.10414010668, 9784.510774461107, 10471.738206544593, 10659.25385223058], "value": 6267.301009034677, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1575.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 119.0, 118.0, 119.0, 118.0, 118.0, 118.0, 118.0, 123.0, 117.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 127.0, 126.0, 174.0, 125.0, 128.0, 127.0, 127.0, 152.0, 126.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 166.0, 168.0, 165.0, 168.0, 164.0, 166.0, 166.0, 164.0], "value": 165.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 76.0, 116.0, 76.0, 77.0, 75.0, 75.0, 89.0, 75.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 125.0, 85.0, 88.0, 85.0, 100.0, 87.0, 90.0, 110.0], "value": 94.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 115.0, 116.0, 114.0, 115.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 150.0, 151.0, 150.0, 151.0, 151.0, 151.0, 150.0, 151.0], "value": 150.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 96.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 68.0, 69.0, 69.0, 68.0, 69.0, 68.0, 70.0], "value": 68.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 60.0, 57.0, 57.0, 57.0, 57.0, 56.0, 57.0, 57.0], "value": 57.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 92.0, 83.0, 84.0, 83.0, 83.0, 84.0, 82.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 134.0, 150.0, 137.0, 134.0, 142.0, 139.0, 132.0, 189.0, 149.0], "value": 144.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 199.0, 194.0, 184.0, 198.0, 193.0, 195.0, 196.0, 193.0, 192.0], "value": 193.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 71.0, 73.0, 72.0, 73.0, 75.0, 74.0, 71.0], "value": 73.2, "unit": "score"}]}]} 09:31:04 INFO - Return code: 0 09:31:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:31:05 ERROR - # TBPL SUCCESS # 09:31:05 INFO - Running post-action listener: _resource_record_post_action 09:31:05 INFO - [mozharness: 2017-03-16 16:31:05.014013Z] Finished run-tests step (success) 09:31:05 INFO - Running post-run listener: _resource_record_post_run 09:31:05 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 374976512; Read time: 8; Write time: 258656 09:31:05 INFO - TinderboxPrint: CPU usage
13.3% 09:31:05 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 09:31:05 INFO - TinderboxPrint: I/O write bytes / time
374,976,512 / 258,656 09:31:05 INFO - TinderboxPrint: CPU idle
8,306.4 (86.7%) 09:31:05 INFO - TinderboxPrint: CPU user
1,220.1 (12.7%) 09:31:05 INFO - TinderboxPrint: Swap in / out
0 / 0 09:31:05 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 117764096; Read time: 0; Write time: 68304 09:31:05 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 257212416; Read time: 8; Write time: 190352 09:31:05 INFO - Running post-run listener: _upload_blobber_files 09:31:05 INFO - Blob upload gear active. 09:31:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:31:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:31:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:31:06 INFO - (blobuploader) - INFO - Open directory for files ... 09:31:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:31:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:31:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:07 INFO - (blobuploader) - INFO - Done attempting. 09:31:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:31:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:31:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:10 INFO - (blobuploader) - INFO - Done attempting. 09:31:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:31:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:31:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:11 INFO - (blobuploader) - INFO - Done attempting. 09:31:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:31:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:31:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:12 INFO - (blobuploader) - INFO - Done attempting. 09:31:12 INFO - (blobuploader) - INFO - Iteration through files over. 09:31:12 INFO - Return code: 0 09:31:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:31:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:31:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2d547ee9ac01cf939d18838ad6eb3ff90905089a550c504f6007cf3a7e216ad047a5acf1b5d27e69197a3bc2ff3263e4368ab5eb2ce0c29b35c7b962b69d96f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/02afacb726c7909e84f798c50f2cd6710863d3b84b823a3b593f48bf3f25c62dead04ae75f27c6b12ce0831cd28749c2b3002db72728f30bcf6801f2c6b12af3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5120ab90e205eafcc78a8693a1db792db3d9a47937989b7fadb12b4572a1d0ff158ecf0f4ff9224f1b8d00736bef180362c96e4ea2b3d1fc8fd3d9a6faf5eca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dba4f372c9dd8eadc9f2858bbf0084a0bb14c2a94c8514de15be1ae11d48958555546235a49f0f823acbf5e3730d0f973a71eabc4f2fc360dc3f523bfbd50772"} 09:31:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:31:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:31:12 INFO - Contents: 09:31:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2d547ee9ac01cf939d18838ad6eb3ff90905089a550c504f6007cf3a7e216ad047a5acf1b5d27e69197a3bc2ff3263e4368ab5eb2ce0c29b35c7b962b69d96f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/02afacb726c7909e84f798c50f2cd6710863d3b84b823a3b593f48bf3f25c62dead04ae75f27c6b12ce0831cd28749c2b3002db72728f30bcf6801f2c6b12af3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5120ab90e205eafcc78a8693a1db792db3d9a47937989b7fadb12b4572a1d0ff158ecf0f4ff9224f1b8d00736bef180362c96e4ea2b3d1fc8fd3d9a6faf5eca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dba4f372c9dd8eadc9f2858bbf0084a0bb14c2a94c8514de15be1ae11d48958555546235a49f0f823acbf5e3730d0f973a71eabc4f2fc360dc3f523bfbd50772"} 09:31:12 INFO - Running post-run listener: copy_logs_to_upload_dir 09:31:12 INFO - Copying logs to upload dir... 09:31:12 INFO - mkdir: /builds/slave/test/build/upload/logs 09:31:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.684881 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-03-16 09:31:11.908094) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-16 09:31:11.913019) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/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/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1489680608.599323-2080363727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2d547ee9ac01cf939d18838ad6eb3ff90905089a550c504f6007cf3a7e216ad047a5acf1b5d27e69197a3bc2ff3263e4368ab5eb2ce0c29b35c7b962b69d96f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/02afacb726c7909e84f798c50f2cd6710863d3b84b823a3b593f48bf3f25c62dead04ae75f27c6b12ce0831cd28749c2b3002db72728f30bcf6801f2c6b12af3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5120ab90e205eafcc78a8693a1db792db3d9a47937989b7fadb12b4572a1d0ff158ecf0f4ff9224f1b8d00736bef180362c96e4ea2b3d1fc8fd3d9a6faf5eca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dba4f372c9dd8eadc9f2858bbf0084a0bb14c2a94c8514de15be1ae11d48958555546235a49f0f823acbf5e3730d0f973a71eabc4f2fc360dc3f523bfbd50772"} build_url:https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011403 build_url: 'https://queue.taskcluster.net/v1/task/QhLHFfA6STCaHx-DR6J_Ew/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2d547ee9ac01cf939d18838ad6eb3ff90905089a550c504f6007cf3a7e216ad047a5acf1b5d27e69197a3bc2ff3263e4368ab5eb2ce0c29b35c7b962b69d96f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/02afacb726c7909e84f798c50f2cd6710863d3b84b823a3b593f48bf3f25c62dead04ae75f27c6b12ce0831cd28749c2b3002db72728f30bcf6801f2c6b12af3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5120ab90e205eafcc78a8693a1db792db3d9a47937989b7fadb12b4572a1d0ff158ecf0f4ff9224f1b8d00736bef180362c96e4ea2b3d1fc8fd3d9a6faf5eca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dba4f372c9dd8eadc9f2858bbf0084a0bb14c2a94c8514de15be1ae11d48958555546235a49f0f823acbf5e3730d0f973a71eabc4f2fc360dc3f523bfbd50772"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-16 09:31:11.937698) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:31:11.938022) ========= rm -f oauth.txt in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1489680608.599323-2080363727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004479 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:31:12.020510) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-16 09:31:12.020837) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-16 09:31:12.021128) ========= ========= Total master_lag: 0.16 =========