builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-014 starttime: 1502321301.88 results: success (0) revision: 411fe4772f31d9ec41fa95fc2e5e15c8df1c7133 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-09 16:28:21.879681) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-09 16:28:21.880225) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-09 16:28:21.880543) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-09 16:28:21.897700) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 16:28:21.898096) ========= 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=31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-09 16:28:22-- 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% 222M=0s 2017-08-09 16:28:22 (222 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129530 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 16:28:22.042494) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 16:28:22.042837) ========= 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=31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019935 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 16:28:22.093991) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-08-09 16:28:22.095000) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 411fe4772f31d9ec41fa95fc2e5e15c8df1c7133 --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 411fe4772f31d9ec41fa95fc2e5e15c8df1c7133 --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=31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-09 16:28:22,395 truncating revision to first 12 chars 2017-08-09 16:28:22,395 Setting DEBUG logging. 2017-08-09 16:28:22,395 attempt 1/10 2017-08-09 16:28:22,395 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/411fe4772f31?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-09 16:28:22,899 attempt 1/10 2017-08-09 16:28:23,663 current task status: no status available at this point. state: STARTED 2017-08-09 16:28:23,664 sleeping for 9.00s (attempt 1/10) 2017-08-09 16:28:32,673 attempt 2/10 2017-08-09 16:28:33,000 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-09 16:28:33,181 unpacking tar archive at: mozilla-central-411fe4772f31/testing/mozharness/ program finished with exit code 0 elapsedTime=11.118027 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-08-09 16:28:33.241693) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-09 16:28:33.242081) ========= script_repo_revision: 411fe4772f31d9ec41fa95fc2e5e15c8df1c7133 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-09 16:28:33.242581) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-09 16:28:33.242944) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-09 16:28:33.256120) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-09 16:28:33.256426) ========= /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=31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:28:33 INFO - MultiFileLogger online at 20170809 16:28:33 in /builds/slave/test 16:28:33 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 16:28:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:28:33 INFO - {'append_to_log': False, 16:28:33 INFO - 'base_work_dir': '/builds/slave/test', 16:28:33 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:28:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:28:33 INFO - 'branch': 'Firefox-Non-PGO', 16:28:33 INFO - 'buildbot_json_path': 'buildprops.json', 16:28:33 INFO - 'code_coverage': False, 16:28:33 INFO - 'config_files': ('talos/linux_config.py',), 16:28:33 INFO - 'default_actions': ('clobber', 16:28:33 INFO - 'read-buildbot-config', 16:28:33 INFO - 'download-and-extract', 16:28:33 INFO - 'populate-webroot', 16:28:33 INFO - 'create-virtualenv', 16:28:33 INFO - 'install', 16:28:33 INFO - 'setup-mitmproxy', 16:28:33 INFO - 'run-tests'), 16:28:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:28:33 INFO - 'disable_ccov_upload': False, 16:28:33 INFO - 'download_minidump_stackwalk': True, 16:28:33 INFO - 'download_symbols': 'ondemand', 16:28:33 INFO - 'enable_stylo': False, 16:28:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:28:33 INFO - 'tooltool.py': '/tools/tooltool.py', 16:28:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:28:33 INFO - '/tools/misc-python/virtualenv.py')}, 16:28:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:28:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:28:33 INFO - 'gecko_profile': False, 16:28:33 INFO - 'gecko_profile_interval': 0, 16:28:33 INFO - 'installer_path': 'installer.exe', 16:28:33 INFO - 'log_level': 'info', 16:28:33 INFO - 'log_name': 'talos', 16:28:33 INFO - 'log_to_console': True, 16:28:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:28:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:28:33 INFO - 'opt_config_files': (), 16:28:33 INFO - 'pip_index': False, 16:28:33 INFO - 'suite': 'dromaeojs-e10s', 16:28:33 INFO - 'system_bits': '32', 16:28:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:28:33 INFO - 'title': 'talos-linux64-ix-014', 16:28:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:28:33 INFO - 'use_talos_json': True, 16:28:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:28:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:28:33 INFO - 'work_dir': 'build'} 16:28:33 INFO - [mozharness: 2017-08-09 23:28:33.653618Z] Running clobber step. 16:28:33 INFO - Running pre-action listener: _resource_record_pre_action 16:28:33 INFO - Running main action method: clobber 16:28:33 INFO - rmtree: /builds/slave/test/build 16:28:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:28:35 INFO - Running post-action listener: _resource_record_post_action 16:28:35 INFO - [mozharness: 2017-08-09 23:28:35.229222Z] Finished clobber step (success) 16:28:35 INFO - [mozharness: 2017-08-09 23:28:35.229325Z] Running read-buildbot-config step. 16:28:35 INFO - Running pre-action listener: _resource_record_pre_action 16:28:35 INFO - Running main action method: read_buildbot_config 16:28:35 INFO - Using buildbot properties: 16:28:35 INFO - { 16:28:35 INFO - "project": "", 16:28:35 INFO - "product": "firefox", 16:28:35 INFO - "who": "ryanvm@gmail.com", 16:28:35 INFO - "installer_path": "public/build/target.tar.bz2", 16:28:35 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:28:35 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:28:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 16:28:35 INFO - "stage_platform": "linux64", 16:28:35 INFO - "basedir": "/builds/slave/test", 16:28:35 INFO - "buildnumber": 1069, 16:28:35 INFO - "platform": "ubuntu64_hw", 16:28:35 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:28:35 INFO - "slavebuilddir": "test", 16:28:35 INFO - "taskId": "LC3PyfMURSWLiFPqjOJiGg", 16:28:35 INFO - "branch": "mozilla-central", 16:28:35 INFO - "script_repo_revision": "production", 16:28:35 INFO - "revision": "411fe4772f31d9ec41fa95fc2e5e15c8df1c7133", 16:28:35 INFO - "slavename": "talos-linux64-ix-014", 16:28:35 INFO - "repo_path": "mozilla-central" 16:28:35 INFO - } 16:28:35 INFO - Finding installer, test and symbols from parent task. 16:28:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LC3PyfMURSWLiFPqjOJiGg'}, attempt #1 16:28:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LC3PyfMURSWLiFPqjOJiGg'}, attempt #1 16:28:36 INFO - Task dependencies: fgg_B4utSheUt0nkQmwdwQ 16:28:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ'}, attempt #1 16:28:36 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.tar.bz2 16:28:36 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.test_packages.json 16:28:36 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.crashreporter-symbols.zip 16:28:36 INFO - Running post-action listener: _resource_record_post_action 16:28:36 INFO - [mozharness: 2017-08-09 23:28:36.554370Z] Finished read-buildbot-config step (success) 16:28:36 INFO - [mozharness: 2017-08-09 23:28:36.554591Z] Running download-and-extract step. 16:28:36 INFO - Running pre-action listener: _resource_record_pre_action 16:28:36 INFO - Running main action method: download_and_extract 16:28:36 INFO - mkdir: /builds/slave/test/build/tests 16:28:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:28:36 INFO - trying https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.test_packages.json 16:28:36 INFO - Downloading https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:28:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:28:37 INFO - Downloaded 989 bytes. 16:28:37 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:28:37 INFO - Using the following test package requirements: 16:28:37 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:28:37 INFO - u'common': [u'target.common.tests.zip'], 16:28:37 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:28:37 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:28:37 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:28:37 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:28:37 INFO - u'mozbase': [u'target.common.tests.zip'], 16:28:37 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:28:37 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:28:37 INFO - u'web-platform': [u'target.common.tests.zip', 16:28:37 INFO - u'target.web-platform.tests.tar.gz'], 16:28:37 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:28:37 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:28:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.common.tests.zip 16:28:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:28:37 INFO - Fetch https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.common.tests.zip into memory 16:28:38 INFO - Content-Length response header: 54284130 16:28:38 INFO - Bytes received: 54284130 16:28:49 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:28:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.common.tests.zip 16:28:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:28:49 INFO - Fetch https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.common.tests.zip into memory 16:28:51 INFO - Content-Length response header: 54284130 16:28:51 INFO - Bytes received: 54284130 16:29:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.talos.tests.zip 16:29:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:29:03 INFO - Fetch https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.talos.tests.zip into memory 16:29:03 INFO - Content-Length response header: 14496008 16:29:03 INFO - Bytes received: 14496008 16:29:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:04 INFO - trying https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.tar.bz2 16:29:04 INFO - Downloading https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:29:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:29:07 INFO - Downloaded 61918055 bytes. 16:29:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.tar.bz2 16:29:07 INFO - mkdir: /builds/slave/test/properties 16:29:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:29:07 INFO - Writing to file /builds/slave/test/properties/build_url 16:29:07 INFO - Contents: 16:29:07 INFO - build_url:https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.tar.bz2 16:29:07 INFO - Running post-action listener: _resource_record_post_action 16:29:07 INFO - Running post-action listener: set_extra_try_arguments 16:29:07 INFO - [mozharness: 2017-08-09 23:29:07.424784Z] Finished download-and-extract step (success) 16:29:07 INFO - [mozharness: 2017-08-09 23:29:07.425017Z] Running populate-webroot step. 16:29:07 INFO - Running pre-action listener: _resource_record_pre_action 16:29:07 INFO - Running main action method: populate_webroot 16:29:07 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:29:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:29:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:29:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:29:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:29:07 INFO - 'tests': ['damp', 'tps']}, 16:29:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:29:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:29:07 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:29:07 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:29:07 INFO - 'other-e10s': {'tests': ['a11yr', 16:29:07 INFO - 'ts_paint', 16:29:07 INFO - 'tpaint', 16:29:07 INFO - 'sessionrestore', 16:29:07 INFO - 'sessionrestore_many_windows', 16:29:07 INFO - 'sessionrestore_no_auto_restore', 16:29:07 INFO - 'tabpaint']}, 16:29:07 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:29:07 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:29:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:29:07 INFO - 'tsvgr_opacity', 16:29:07 INFO - 'tart', 16:29:07 INFO - 'tscrollx', 16:29:07 INFO - 'tsvg_static']}, 16:29:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:29:07 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:29:07 INFO - 'talos_options': ['--mitmproxy', 16:29:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:29:07 INFO - '--firstNonBlankPaint'], 16:29:07 INFO - 'tests': ['tp6_google', 16:29:07 INFO - 'tp6_youtube', 16:29:07 INFO - 'tp6_amazon', 16:29:07 INFO - 'tp6_facebook']}, 16:29:07 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:29:07 INFO - 'talos_options': ['--stylo', 16:29:07 INFO - '--mitmproxy', 16:29:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:29:07 INFO - '--firstNonBlankPaint'], 16:29:07 INFO - 'tests': ['tp6_google', 16:29:07 INFO - 'tp6_youtube', 16:29:07 INFO - 'tp6_amazon', 16:29:07 INFO - 'tp6_facebook']}, 16:29:07 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:29:07 INFO - 'talos_options': ['--stylo', 16:29:07 INFO - '--stylo-threads=1', 16:29:07 INFO - '--mitmproxy', 16:29:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:29:07 INFO - '--firstNonBlankPaint'], 16:29:07 INFO - 'tests': ['tp6_google', 16:29:07 INFO - 'tp6_youtube', 16:29:07 INFO - 'tp6_amazon', 16:29:07 INFO - 'tp6_facebook']}, 16:29:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:29:07 INFO - 'talos_options': ['--xperf_path', 16:29:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:29:07 INFO - 'tests': ['tp5n']}}} 16:29:07 INFO - Running post-action listener: _resource_record_post_action 16:29:07 INFO - [mozharness: 2017-08-09 23:29:07.436233Z] Finished populate-webroot step (success) 16:29:07 INFO - [mozharness: 2017-08-09 23:29:07.436323Z] Running create-virtualenv step. 16:29:07 INFO - Running pre-action listener: _resource_record_pre_action 16:29:07 INFO - Running main action method: create_virtualenv 16:29:07 INFO - Creating virtualenv /builds/slave/test/build/venv 16:29:07 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 16:29:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:29:07 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:29:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:29:07 INFO - Using real prefix '/usr' 16:29:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:29:08 INFO - Installing distribute.............................................................................................................................................................................................done. 16:29:10 INFO - Installing pip.................done. 16:29:10 INFO - Return code: 0 16:29:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:29:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:29:10 INFO - Reading from file tmpfile_stdout 16:29:10 INFO - Output received: 16:29:10 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) 16:29:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:10 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/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 0x24b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2825f00>, '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 0x2820ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', '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 16:29:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:29:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:29:10 INFO - Using env: {'DISPLAY': ':0', 16:29:10 INFO - 'HOME': '/home/cltbld', 16:29:10 INFO - 'LANG': 'en_US.UTF-8', 16:29:10 INFO - 'LANGUAGE': 'en_US:en', 16:29:10 INFO - 'LOGNAME': 'cltbld', 16:29:10 INFO - 'MAIL': '/var/mail/cltbld', 16:29:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:10 INFO - 'MOZ_NO_REMOTE': '1', 16:29:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:10 INFO - 'NO_EM_RESTART': '1', 16:29:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:10 INFO - 'PWD': '/builds/slave/test', 16:29:10 INFO - 'SHELL': '/bin/bash', 16:29:10 INFO - 'SHLVL': '1', 16:29:10 INFO - 'TERM': 'linux', 16:29:10 INFO - 'TMOUT': '86400', 16:29:10 INFO - 'USER': 'cltbld', 16:29:10 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', 16:29:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:10 INFO - '_': '/tools/buildbot/bin/python'} 16:29:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:10 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 16:29:10 INFO - Cleaning up... 16:29:10 INFO - Return code: 0 16:29:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:10 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/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 0x24b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2825f00>, '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 0x2820ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', '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 16:29:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:29:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:29:10 INFO - Using env: {'DISPLAY': ':0', 16:29:10 INFO - 'HOME': '/home/cltbld', 16:29:10 INFO - 'LANG': 'en_US.UTF-8', 16:29:10 INFO - 'LANGUAGE': 'en_US:en', 16:29:10 INFO - 'LOGNAME': 'cltbld', 16:29:10 INFO - 'MAIL': '/var/mail/cltbld', 16:29:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:10 INFO - 'MOZ_NO_REMOTE': '1', 16:29:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:10 INFO - 'NO_EM_RESTART': '1', 16:29:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:10 INFO - 'PWD': '/builds/slave/test', 16:29:10 INFO - 'SHELL': '/bin/bash', 16:29:10 INFO - 'SHLVL': '1', 16:29:10 INFO - 'TERM': 'linux', 16:29:10 INFO - 'TMOUT': '86400', 16:29:10 INFO - 'USER': 'cltbld', 16:29:10 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', 16:29:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:10 INFO - '_': '/tools/buildbot/bin/python'} 16:29:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:10 INFO - Downloading/unpacking psutil>=3.1.1 16:29:10 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 16:29:10 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 16:29:10 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 16:29:10 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 16:29:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:29:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:11 INFO - Installing collected packages: psutil 16:29:11 INFO - Running setup.py install for psutil 16:29:11 INFO - building 'psutil._psutil_linux' extension 16:29:11 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 16:29:12 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 16:29:12 INFO - building 'psutil._psutil_posix' extension 16:29:12 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 16:29:12 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 16:29:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:12 INFO - Successfully installed psutil 16:29:12 INFO - Cleaning up... 16:29:12 INFO - Return code: 0 16:29:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:12 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/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 0x24b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2825f00>, '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 0x2820ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', '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 16:29:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:29:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:29:12 INFO - Using env: {'DISPLAY': ':0', 16:29:12 INFO - 'HOME': '/home/cltbld', 16:29:12 INFO - 'LANG': 'en_US.UTF-8', 16:29:12 INFO - 'LANGUAGE': 'en_US:en', 16:29:12 INFO - 'LOGNAME': 'cltbld', 16:29:12 INFO - 'MAIL': '/var/mail/cltbld', 16:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:12 INFO - 'MOZ_NO_REMOTE': '1', 16:29:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:12 INFO - 'NO_EM_RESTART': '1', 16:29:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:12 INFO - 'PWD': '/builds/slave/test', 16:29:12 INFO - 'SHELL': '/bin/bash', 16:29:12 INFO - 'SHLVL': '1', 16:29:12 INFO - 'TERM': 'linux', 16:29:12 INFO - 'TMOUT': '86400', 16:29:12 INFO - 'USER': 'cltbld', 16:29:12 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', 16:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:12 INFO - '_': '/tools/buildbot/bin/python'} 16:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:29:12 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 16:29:12 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 16:29:12 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 16:29:12 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 16:29:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:29:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:29:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:29:13 INFO - Installing collected packages: mozsystemmonitor 16:29:13 INFO - Running setup.py install for mozsystemmonitor 16:29:13 INFO - Successfully installed mozsystemmonitor 16:29:13 INFO - Cleaning up... 16:29:13 INFO - Return code: 0 16:29:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:29:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:13 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/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 0x24b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2825f00>, '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 0x2820ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', '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 16:29:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:29:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:29:13 INFO - Using env: {'DISPLAY': ':0', 16:29:13 INFO - 'HOME': '/home/cltbld', 16:29:13 INFO - 'LANG': 'en_US.UTF-8', 16:29:13 INFO - 'LANGUAGE': 'en_US:en', 16:29:13 INFO - 'LOGNAME': 'cltbld', 16:29:13 INFO - 'MAIL': '/var/mail/cltbld', 16:29:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:13 INFO - 'MOZ_NO_REMOTE': '1', 16:29:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:13 INFO - 'NO_EM_RESTART': '1', 16:29:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:13 INFO - 'PWD': '/builds/slave/test', 16:29:13 INFO - 'SHELL': '/bin/bash', 16:29:13 INFO - 'SHLVL': '1', 16:29:13 INFO - 'TERM': 'linux', 16:29:13 INFO - 'TMOUT': '86400', 16:29:13 INFO - 'USER': 'cltbld', 16:29:13 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', 16:29:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:13 INFO - '_': '/tools/buildbot/bin/python'} 16:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:14 INFO - Downloading/unpacking jsonschema==2.5.1 16:29:14 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 16:29:14 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 16:29:14 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 16:29:14 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 16:29:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:29:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:15 INFO - Downloading functools32-3.2.3-2.tar.gz 16:29:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:29:15 INFO - warning: no files found matching '*.txt' 16:29:15 INFO - no previously-included directories found matching 'build' 16:29:15 INFO - no previously-included directories found matching 'dist' 16:29:15 INFO - no previously-included directories found matching '.git*' 16:29:15 INFO - Installing collected packages: jsonschema, functools32 16:29:15 INFO - Running setup.py install for functools32 16:29:15 INFO - warning: no files found matching '*.txt' 16:29:15 INFO - no previously-included directories found matching 'build' 16:29:15 INFO - no previously-included directories found matching 'dist' 16:29:15 INFO - no previously-included directories found matching '.git*' 16:29:15 INFO - Successfully installed jsonschema functools32 16:29:15 INFO - Cleaning up... 16:29:15 INFO - Return code: 0 16:29:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:29:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:15 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/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 0x24b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2825f00>, '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 0x2820ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', '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 16:29:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:29:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:29:15 INFO - Using env: {'DISPLAY': ':0', 16:29:15 INFO - 'HOME': '/home/cltbld', 16:29:15 INFO - 'LANG': 'en_US.UTF-8', 16:29:15 INFO - 'LANGUAGE': 'en_US:en', 16:29:15 INFO - 'LOGNAME': 'cltbld', 16:29:15 INFO - 'MAIL': '/var/mail/cltbld', 16:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:15 INFO - 'MOZ_NO_REMOTE': '1', 16:29:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:15 INFO - 'NO_EM_RESTART': '1', 16:29:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:15 INFO - 'PWD': '/builds/slave/test', 16:29:15 INFO - 'SHELL': '/bin/bash', 16:29:15 INFO - 'SHLVL': '1', 16:29:15 INFO - 'TERM': 'linux', 16:29:15 INFO - 'TMOUT': '86400', 16:29:15 INFO - 'USER': 'cltbld', 16:29:15 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', 16:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:15 INFO - '_': '/tools/buildbot/bin/python'} 16:29:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:29:15 INFO - Cleaning up... 16:29:15 INFO - Return code: 0 16:29:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:29:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:15 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/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 0x24b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2825f00>, '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 0x2820ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', '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 16:29:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:29:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:29:15 INFO - Using env: {'DISPLAY': ':0', 16:29:15 INFO - 'HOME': '/home/cltbld', 16:29:15 INFO - 'LANG': 'en_US.UTF-8', 16:29:15 INFO - 'LANGUAGE': 'en_US:en', 16:29:15 INFO - 'LOGNAME': 'cltbld', 16:29:15 INFO - 'MAIL': '/var/mail/cltbld', 16:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:15 INFO - 'MOZ_NO_REMOTE': '1', 16:29:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:15 INFO - 'NO_EM_RESTART': '1', 16:29:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:15 INFO - 'PWD': '/builds/slave/test', 16:29:15 INFO - 'SHELL': '/bin/bash', 16:29:15 INFO - 'SHLVL': '1', 16:29:15 INFO - 'TERM': 'linux', 16:29:15 INFO - 'TMOUT': '86400', 16:29:15 INFO - 'USER': 'cltbld', 16:29:15 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', 16:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:15 INFO - '_': '/tools/buildbot/bin/python'} 16:29:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:16 INFO - Downloading/unpacking blobuploader==1.2.4 16:29:16 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 16:29:16 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 16:29:16 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 16:29:16 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 16:29:17 INFO - Downloading blobuploader-1.2.4.tar.gz 16:29:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:29:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:29:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:17 INFO - Downloading docopt-0.6.1.tar.gz 16:29:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:29:17 INFO - Installing collected packages: blobuploader, requests, docopt 16:29:17 INFO - Running setup.py install for blobuploader 16:29:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:29:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:29:17 INFO - Running setup.py install for requests 16:29:18 INFO - Running setup.py install for docopt 16:29:18 INFO - Successfully installed blobuploader requests docopt 16:29:18 INFO - Cleaning up... 16:29:18 INFO - Return code: 0 16:29:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:18 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/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 0x24b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2825f00>, '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 0x2820ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', '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 16:29:18 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:29:18 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:29:18 INFO - Using env: {'DISPLAY': ':0', 16:29:18 INFO - 'HOME': '/home/cltbld', 16:29:18 INFO - 'LANG': 'en_US.UTF-8', 16:29:18 INFO - 'LANGUAGE': 'en_US:en', 16:29:18 INFO - 'LOGNAME': 'cltbld', 16:29:18 INFO - 'MAIL': '/var/mail/cltbld', 16:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:18 INFO - 'MOZ_NO_REMOTE': '1', 16:29:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:18 INFO - 'NO_EM_RESTART': '1', 16:29:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:18 INFO - 'PWD': '/builds/slave/test', 16:29:18 INFO - 'SHELL': '/bin/bash', 16:29:18 INFO - 'SHLVL': '1', 16:29:18 INFO - 'TERM': 'linux', 16:29:18 INFO - 'TMOUT': '86400', 16:29:18 INFO - 'USER': 'cltbld', 16:29:18 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', 16:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:18 INFO - '_': '/tools/buildbot/bin/python'} 16:29:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:18 INFO - Running setup.py (path:/tmp/pip-8Q8zxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:18 INFO - Running setup.py (path:/tmp/pip-bHxI8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:18 INFO - Running setup.py (path:/tmp/pip-0z2wEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:18 INFO - Running setup.py (path:/tmp/pip-RdMCsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:18 INFO - Running setup.py (path:/tmp/pip-UE5imq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:18 INFO - Running setup.py (path:/tmp/pip-rY0YYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:18 INFO - Running setup.py (path:/tmp/pip-6e37ww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:19 INFO - Running setup.py (path:/tmp/pip-aAE0U8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:19 INFO - Running setup.py (path:/tmp/pip-n0XwL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:19 INFO - Running setup.py (path:/tmp/pip-URAjh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:19 INFO - Running setup.py (path:/tmp/pip-2RQkLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:19 INFO - Running setup.py (path:/tmp/pip-NpPrQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:19 INFO - Running setup.py (path:/tmp/pip-IkNGNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:19 INFO - Running setup.py (path:/tmp/pip-80vx2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:19 INFO - Running setup.py (path:/tmp/pip-2o4NvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:19 INFO - Running setup.py (path:/tmp/pip-dIvJRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:19 INFO - Running setup.py (path:/tmp/pip-9Lfusd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:29:19 INFO - Running setup.py install for manifestparser 16:29:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:29:19 INFO - Running setup.py install for mozcrash 16:29:19 INFO - Running setup.py install for mozdebug 16:29:20 INFO - Running setup.py install for mozdevice 16:29:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:29:20 INFO - Running setup.py install for mozfile 16:29:20 INFO - Running setup.py install for mozhttpd 16:29:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:29:20 INFO - Running setup.py install for mozinfo 16:29:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:29:20 INFO - Running setup.py install for mozInstall 16:29:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:29:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:29:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:29:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:29:20 INFO - Running setup.py install for mozleak 16:29:20 INFO - Running setup.py install for mozlog 16:29:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:29:20 INFO - Running setup.py install for moznetwork 16:29:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:29:20 INFO - Running setup.py install for mozprocess 16:29:21 INFO - Running setup.py install for mozprofile 16:29:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:29:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:29:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:29:21 INFO - Running setup.py install for mozrunner 16:29:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:29:21 INFO - Running setup.py install for mozscreenshot 16:29:21 INFO - Running setup.py install for moztest 16:29:21 INFO - Running setup.py install for mozversion 16:29:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:29:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:29:21 INFO - Cleaning up... 16:29:21 INFO - Return code: 0 16:29:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:21 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/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 0x24b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2825f00>, '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 0x2820ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', '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 16:29:21 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:29:21 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:29:21 INFO - Using env: {'DISPLAY': ':0', 16:29:21 INFO - 'HOME': '/home/cltbld', 16:29:21 INFO - 'LANG': 'en_US.UTF-8', 16:29:21 INFO - 'LANGUAGE': 'en_US:en', 16:29:21 INFO - 'LOGNAME': 'cltbld', 16:29:21 INFO - 'MAIL': '/var/mail/cltbld', 16:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:21 INFO - 'MOZ_NO_REMOTE': '1', 16:29:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:21 INFO - 'NO_EM_RESTART': '1', 16:29:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:21 INFO - 'PWD': '/builds/slave/test', 16:29:21 INFO - 'SHELL': '/bin/bash', 16:29:21 INFO - 'SHLVL': '1', 16:29:21 INFO - 'TERM': 'linux', 16:29:21 INFO - 'TMOUT': '86400', 16:29:21 INFO - 'USER': 'cltbld', 16:29:21 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', 16:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:21 INFO - '_': '/tools/buildbot/bin/python'} 16:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:21 INFO - Running setup.py (path:/tmp/pip-bTSw88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:21 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)) 16:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:21 INFO - Running setup.py (path:/tmp/pip-2Fi_SH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:21 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)) 16:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:21 INFO - Running setup.py (path:/tmp/pip-2WGXha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:22 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)) 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:22 INFO - Running setup.py (path:/tmp/pip-unZEjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:22 INFO - Running setup.py (path:/tmp/pip-wvHQu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:22 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)) 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:22 INFO - Running setup.py (path:/tmp/pip-7SnXGd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:22 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)) 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:22 INFO - Running setup.py (path:/tmp/pip-griEPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:22 INFO - Running setup.py (path:/tmp/pip-iCZJp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:22 INFO - Running setup.py (path:/tmp/pip-dheH5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:22 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)) 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:22 INFO - Running setup.py (path:/tmp/pip-8xdTrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 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)) 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:22 INFO - Running setup.py (path:/tmp/pip-_1iGHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:22 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)) 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:22 INFO - Running setup.py (path:/tmp/pip-OdznMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:22 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)) 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:22 INFO - Running setup.py (path:/tmp/pip-YqCc10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:22 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)) 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:22 INFO - Running setup.py (path:/tmp/pip-XPcWdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:22 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)) 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:22 INFO - Running setup.py (path:/tmp/pip-biVXqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:22 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)) 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:22 INFO - Running setup.py (path:/tmp/pip-sHtptp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:22 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)) 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:22 INFO - Running setup.py (path:/tmp/pip-Ji1vYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:23 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)) 16:29:23 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)) 16:29:23 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)) 16:29:23 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)) 16:29:23 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:29:23 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:29:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:29:23 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 16:29:23 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 16:29:23 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 16:29:23 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 16:29:24 INFO - Downloading blessings-1.6.tar.gz 16:29:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:29:24 INFO - Installing collected packages: blessings 16:29:24 INFO - Running setup.py install for blessings 16:29:24 INFO - Successfully installed blessings 16:29:24 INFO - Cleaning up... 16:29:24 INFO - Return code: 0 16:29:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:29:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:24 INFO - Reading from file tmpfile_stdout 16:29:24 INFO - Current package versions: 16:29:24 INFO - argparse == 1.2.1 16:29:24 INFO - blessings == 1.6 16:29:24 INFO - blobuploader == 1.2.4 16:29:24 INFO - docopt == 0.6.1 16:29:24 INFO - functools32 == 3.2.3-2 16:29:24 INFO - jsonschema == 2.5.1 16:29:24 INFO - manifestparser == 1.1 16:29:24 INFO - mozInstall == 1.13 16:29:24 INFO - mozcrash == 1.0 16:29:24 INFO - mozdebug == 0.1 16:29:24 INFO - mozdevice == 0.51 16:29:24 INFO - mozfile == 1.2 16:29:24 INFO - mozhttpd == 0.7 16:29:24 INFO - mozinfo == 0.10 16:29:24 INFO - mozleak == 0.1 16:29:24 INFO - mozlog == 3.5 16:29:24 INFO - moznetwork == 0.27 16:29:24 INFO - mozprocess == 0.25 16:29:24 INFO - mozprofile == 0.28 16:29:24 INFO - mozrunner == 6.13 16:29:24 INFO - mozscreenshot == 0.1 16:29:24 INFO - mozsystemmonitor == 0.3 16:29:24 INFO - moztest == 0.8 16:29:24 INFO - mozversion == 1.4 16:29:24 INFO - psutil == 3.1.1 16:29:24 INFO - requests == 1.2.3 16:29:24 INFO - wsgiref == 0.1.2 16:29:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:24 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/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 0x24b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2825f00>, '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 0x2820ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', '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 16:29:24 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:29:24 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:29:24 INFO - Using env: {'DISPLAY': ':0', 16:29:24 INFO - 'HOME': '/home/cltbld', 16:29:24 INFO - 'LANG': 'en_US.UTF-8', 16:29:24 INFO - 'LANGUAGE': 'en_US:en', 16:29:24 INFO - 'LOGNAME': 'cltbld', 16:29:24 INFO - 'MAIL': '/var/mail/cltbld', 16:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:24 INFO - 'MOZ_NO_REMOTE': '1', 16:29:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:24 INFO - 'NO_EM_RESTART': '1', 16:29:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:24 INFO - 'PWD': '/builds/slave/test', 16:29:24 INFO - 'SHELL': '/bin/bash', 16:29:24 INFO - 'SHLVL': '1', 16:29:24 INFO - 'TERM': 'linux', 16:29:24 INFO - 'TMOUT': '86400', 16:29:24 INFO - 'USER': 'cltbld', 16:29:24 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', 16:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:24 INFO - '_': '/tools/buildbot/bin/python'} 16:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:24 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)) 16:29:24 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)) 16:29:24 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)) 16:29:24 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)) 16:29:24 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)) 16:29:24 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)) 16:29:24 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)) 16:29:24 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)) 16:29:24 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)) 16:29:24 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:29:24 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 16:29:24 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 16:29:24 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 16:29:24 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 16:29:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:29:25 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)) 16:29:25 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)) 16:29:25 INFO - Installing collected packages: simplejson 16:29:25 INFO - Running setup.py install for simplejson 16:29:26 INFO - building 'simplejson._speedups' extension 16:29:26 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 16:29:26 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 16:29:26 INFO - Successfully installed simplejson 16:29:26 INFO - Cleaning up... 16:29:27 INFO - Return code: 0 16:29:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:27 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/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 0x24b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2825f00>, '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 0x2820ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', '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 16:29:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:29:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:29:27 INFO - Using env: {'DISPLAY': ':0', 16:29:27 INFO - 'HOME': '/home/cltbld', 16:29:27 INFO - 'LANG': 'en_US.UTF-8', 16:29:27 INFO - 'LANGUAGE': 'en_US:en', 16:29:27 INFO - 'LOGNAME': 'cltbld', 16:29:27 INFO - 'MAIL': '/var/mail/cltbld', 16:29:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:27 INFO - 'MOZ_NO_REMOTE': '1', 16:29:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:27 INFO - 'NO_EM_RESTART': '1', 16:29:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:27 INFO - 'PWD': '/builds/slave/test', 16:29:27 INFO - 'SHELL': '/bin/bash', 16:29:27 INFO - 'SHLVL': '1', 16:29:27 INFO - 'TERM': 'linux', 16:29:27 INFO - 'TMOUT': '86400', 16:29:27 INFO - 'USER': 'cltbld', 16:29:27 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', 16:29:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:27 INFO - '_': '/tools/buildbot/bin/python'} 16:29:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:29:27 INFO - Cleaning up... 16:29:27 INFO - Return code: 0 16:29:27 INFO - Running post-action listener: _resource_record_post_action 16:29:27 INFO - Running post-action listener: _start_resource_monitoring 16:29:27 INFO - Starting resource monitoring. 16:29:27 INFO - [mozharness: 2017-08-09 23:29:27.302647Z] Finished create-virtualenv step (success) 16:29:27 INFO - [mozharness: 2017-08-09 23:29:27.303170Z] Running install step. 16:29:27 INFO - Running pre-action listener: _resource_record_pre_action 16:29:27 INFO - Running main action method: install 16:29:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:27 INFO - Reading from file tmpfile_stdout 16:29:27 INFO - Detecting whether we're running mozinstall >=1.0... 16:29:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:29:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:29:27 INFO - Reading from file tmpfile_stdout 16:29:27 INFO - Output received: 16:29:27 INFO - Usage: mozinstall [options] installer 16:29:27 INFO - Options: 16:29:27 INFO - -h, --help show this help message and exit 16:29:27 INFO - -d DEST, --destination=DEST 16:29:27 INFO - Directory to install application into. [default: 16:29:27 INFO - "/builds/slave/test"] 16:29:27 INFO - --app=APP Application being installed. [default: firefox] 16:29:27 INFO - mkdir: /builds/slave/test/build/application 16:29:27 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'] 16:29:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:29:45 INFO - Reading from file tmpfile_stdout 16:29:45 INFO - Output received: 16:29:45 INFO - /builds/slave/test/build/application/firefox/firefox 16:29:45 INFO - Running post-action listener: _resource_record_post_action 16:29:45 INFO - [mozharness: 2017-08-09 23:29:45.632920Z] Finished install step (success) 16:29:45 INFO - [mozharness: 2017-08-09 23:29:45.633267Z] Running setup-mitmproxy step. 16:29:45 INFO - Running pre-action listener: _resource_record_pre_action 16:29:45 INFO - Running main action method: setup_mitmproxy 16:29:45 INFO - Skipping: mitmproxy is not required 16:29:45 INFO - Running post-action listener: _resource_record_post_action 16:29:45 INFO - [mozharness: 2017-08-09 23:29:45.634971Z] Finished setup-mitmproxy step (success) 16:29:45 INFO - [mozharness: 2017-08-09 23:29:45.635285Z] Running run-tests step. 16:29:45 INFO - Running pre-action listener: _resource_record_pre_action 16:29:45 INFO - Running pre-action listener: _set_gcov_prefix 16:29:45 INFO - Running main action method: run_tests 16:29:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:29:45 INFO - Python 2.7.3 16:29:45 INFO - Return code: 0 16:29:45 INFO - grabbing minidump binary from tooltool 16:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:29:45 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 0x2825f00>, '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 0x2820ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:29:45 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 16:29:45 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 16:29:45 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 16:29:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:29:45 INFO - Return code: 0 16:29:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:29:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:29:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:29:45 INFO - ENV: RUST_BACKTRACE is now full 16:29:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:29:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:29:45 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/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 16:29:45 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/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 16:29:45 INFO - Using env: {'DISPLAY': ':0', 16:29:45 INFO - 'HOME': '/home/cltbld', 16:29:45 INFO - 'LANG': 'en_US.UTF-8', 16:29:45 INFO - 'LANGUAGE': 'en_US:en', 16:29:45 INFO - 'LOGNAME': 'cltbld', 16:29:45 INFO - 'MAIL': '/var/mail/cltbld', 16:29:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:29:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:45 INFO - 'MOZ_NO_REMOTE': '1', 16:29:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:29:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:45 INFO - 'NO_EM_RESTART': '1', 16:29:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:45 INFO - 'PWD': '/builds/slave/test', 16:29:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:29:45 INFO - 'RUST_BACKTRACE': 'full', 16:29:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:29:45 INFO - 'SHELL': '/bin/bash', 16:29:45 INFO - 'SHLVL': '1', 16:29:45 INFO - 'TERM': 'linux', 16:29:45 INFO - 'TMOUT': '86400', 16:29:45 INFO - 'USER': 'cltbld', 16:29:45 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020', 16:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:45 INFO - '_': '/tools/buildbot/bin/python'} 16:29:45 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/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 16:29:45 INFO - mozversion application_buildid: 20170809224353 16:29:45 INFO - mozversion application_changeset: 411fe4772f31d9ec41fa95fc2e5e15c8df1c7133 16:29:45 INFO - mozversion application_display_name: Nightly 16:29:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:29:46 INFO - mozversion application_name: Firefox 16:29:46 INFO - mozversion application_remotingname: firefox 16:29:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:29:46 INFO - mozversion application_vendor: Mozilla 16:29:46 INFO - mozversion application_version: 57.0a1 16:29:46 INFO - mozversion platform_buildid: 20170809224353 16:29:46 INFO - mozversion platform_changeset: 411fe4772f31d9ec41fa95fc2e5e15c8df1c7133 16:29:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:29:46 INFO - mozversion platform_version: 57.0a1 16:29:46 INFO - using testdate: 1502321385 16:29:46 INFO - actual date: 1502321385 16:29:46 INFO - starting webserver on 'localhost:37604' 16:29:46 INFO - SUITE-START | Running 2 tests 16:29:46 INFO - TEST-START | dromaeo_css 16:29:46 INFO - Initialising browser for dromaeo_css test... 16:29:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37604/getInfo.html -profile /tmp/tmpCnJn4h/profile 16:29:46 INFO - TEST-INFO | started process 26105 (/builds/slave/test/build/application/firefox/firefox http://localhost:37604/getInfo.html) 16:29:51 INFO - TEST-INFO | 26105: exit 0 16:29:51 INFO - Browser initialized. 16:29:51 INFO - Running cycle 1/1 for dromaeo_css test... 16:29:51 INFO - TEST-INFO | started process 26322 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCnJn4h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:29:52 INFO - PID 26322 | 16:29:52 INFO - PID 26322 | (/builds/slave/test/build/application/firefox/firefox:26371): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:29:52 INFO - PID 26322 | 16:30:03 INFO - PID 26322 | RSS: Main: 168321024 16:30:03 INFO - PID 26322 | 16:33:03 INFO - PID 26322 | Cycle 1(1): loaded http://localhost:37604/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37604/tests/dromaeo/cssquery-ext.html) 16:33:04 INFO - PID 26322 | RSS: Main: 172974080 16:33:04 INFO - PID 26322 | 16:36:05 INFO - PID 26322 | Cycle 1(1): loaded http://localhost:37604/tests/dromaeo/cssquery-ext.html (next: http://localhost:37604/tests/dromaeo/cssquery-jquery.html) 16:36:06 INFO - PID 26322 | RSS: Main: 170704896 16:36:06 INFO - PID 26322 | 16:39:08 INFO - PID 26322 | Cycle 1(1): loaded http://localhost:37604/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37604/tests/dromaeo/cssquery-mootools.html) 16:39:08 INFO - PID 26322 | RSS: Main: 172183552 16:39:08 INFO - PID 26322 | 16:42:10 INFO - PID 26322 | Cycle 1(1): loaded http://localhost:37604/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37604/tests/dromaeo/cssquery-prototype.html) 16:42:11 INFO - PID 26322 | RSS: Main: 169291776 16:42:11 INFO - PID 26322 | 16:45:14 INFO - PID 26322 | Cycle 1(1): loaded http://localhost:37604/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37604/tests/dromaeo/cssquery-yui.html) 16:45:15 INFO - PID 26322 | RSS: Main: 174006272 16:45:15 INFO - PID 26322 | 16:48:17 INFO - PID 26322 | Cycle 1(1): loaded http://localhost:37604/tests/dromaeo/cssquery-yui.html (next: http://localhost:37604/tests/dromaeo/cssquery-dojo.html) 16:48:17 INFO - PID 26322 | RSS: Main: 174563328 16:48:17 INFO - PID 26322 | 16:48:17 INFO - PID 26322 | __start_tp_report 16:48:17 INFO - PID 26322 | _x_x_mozilla_page_load 16:48:17 INFO - PID 26322 | _x_x_mozilla_page_load_details 16:48:17 INFO - PID 26322 | |i|pagename|runs| 16:48:17 INFO - PID 26322 | |0;dojo.html;3120.9531857022134;3133.9059828205154;3133.968660313398;3154.0695494829024;3208.0536241808663;6193.256809182904;6238.563300568962;6277.309495955447;6304.959681652527;6402.487800975922;6182.381761823815;6217;6231.844203894905;6241.063840423927;6266.279377871535;5711.200431939511;5751.597388182819;5770.682612456313;5787.189793428924;5814.7092645367775;5792.073268277077;5795.2466179396615;5797.913031304533;5803.941960580382;5832;5747.482726554609;5799.884002319972;5837.1536127261525;5845.532357411418;5865.58940874139;5703.0589952657765;5753.856153596152;5803.21656576361;5821.388754180793;5851.824445266606;5327.920081198785;5570.136628822539;5702.657840529581;5715.142728590703;5723.971380143114;6174.228221472316;6192.380761923817;6231.096491008823;6296.874062518724;6310.400511951358;6698.665066746643;6702.4302934250645;6798.04827324175;6807.455403567752;6824.556403833735;6625.602463852184;6669.099671544376;6702.463802895805;6803.65981700913;6815.420689241371;6264.373562643745;6272.902242107613;6276.058591211355;6276.340984196685;6277.937220627827;6937.618430986243;6974.58152510851;6976.895346569806;6992.860142797217;6994.825129371807;6545.574537655038;6556.606603603799;6557.672116394161;6619.966900165468;6658.600483970978;6641.1366522352755;6663.800085997428;6762.729490820312;6789.456843452512;6791.117154769849;6516.283208847023;6603.70283337251;6639.070530125786;6639.070530125786;6719.563228390237;6637.86724265512;6663.933360666331;6680.899786503206;6688.7324507019175;6698.933010669928;5458.208559758813;5483.040467918098;5486;5489.478499542617;5493.066178749542;4612.892905702607;4669.696469729457;4687.882802929886;4696.107739529547;4711.246200607886;5730.1404789281105;5822.505087067562;5836.474717275466;5836.737346819404;5868.2077919480325;6744.460443164535;6769.289224631441;6772.966135169293;6776.085228494188;6778;5791.247137872049;5803.100519419497;5813.709314534341;5815.73829177688;5826.650400975955;5988.251468566429;5990.73041713124;6003.969980150158;6005.549583781234;6011.6693581853415;6485.286618471964;6493.837654058686;6576.704048317837;6579.901301480481;6588.4729221662155;6658.833529161616;6731.663416829237;6738.292479289604;6829.146356705412;6841.452683785185;6485.805425837232;6500.317466666121;6502.642354670538;6507.804765857032;6518.674066296571;6634.4692424604955;6635.402813746786;6635.8009259722285;6642.767503137367;6660.200775906728;6756.831079222862;6776.322367763184;6792.796216113525;6801.0138529913875;6805.319468053155;6480.157579514633;6502.089707440868;6574.211094668671;6575.506836987341;6581.407673309425;6480.476688435942;6482.935170648233;6581.572197807127;6583.144191255106;6594.868102638016;6338.144350512806;6377.96811015942;6426.260979987174;6481.708323125565;6490.48076153916;6568.211814582281;6577.2436169841085;6627.536072474881;6629.3039230882;6637.435817955527;6226.62640241587;6246.812595622138;6319.842003949938;6324.335944725737;6422.550421470452 16:48:17 INFO - PID 26322 | |1;ext.html;827.5034979012593;856.7729551668809;882.580774132287;887.5251740318927;1191.6603767926142;31422;32041.35917281656;43045.56954430455;43370;43798.562014379844;43826;44386;44408.1118377633;44443.77778111105;44474;7318.085239345082;7764.8835267470895;7925.682972681086;7934.206579342049;7963.482373645724;7760.340371068466;7875.527468351888;7876.173001834811;7879.566623835687;7885.526868387915;7812.101608315032;7886.6451009704415;7915.960420197891;7922.405819563527;7922.405819563527;7817.374610031184;7839.568823714693;7847.78359354301;7850.097238817523;7860.882086768732;7744.186860379636;7756.689732410697;7795.532268063935;7827.3346765525;7831;38555;38613;38922.80538597317;38963;39029.21941561153;3550.0947258449187;3592.7125829933543;3593.13764696471;3593.586737525192;3594.532710747586;31844.363112737614;31849;31910.680893191235;31966.84016579902;31993.200169995704;5127.948720512784;5168.741562921877;5169.741512924376;5203.16749320107;5219.478052194788;194943;195691;195927;196367;197090;48436.515634843905;48504.7574762131;48623.75688121537;48800.511994880304;48852;51091.233631495554;51116.488835111915;51125.23312150321;51135;51269.743651281504;14062.648433789238;14374.568762937128;14413.927930360282;14417.351219195161;14426.278686065654;10891.727706807234;11046.779064418666;11538.19232653723;12793.040521960891;13098.279594622387;14153.858461415253;14192.645183870485;14367.497137600343;14471.927640361731;14522.273886305642;5731.369549349568;5732.484076433141;5740.425957404226;5790.247267855236;5803.448672376117;973.2749101919115;1000.3897622450299;1014.9441780701984;1015.8374660054503;1027.157730660851;23707.051717931434;24070.51858962846;24208.359563136288;29041.41917161645;29663.55504667432;1072.9302595331278;1075.0700643942932;1075.2795626929976;1085.7448499602588;1094.141099237095;12372.752544949051;12725.363731813371;12765.61703148907;12823.871761282455;12844.614661560168;231911;233557;233940;233976;234202;53004.46995530072;53346.46653533493;53385.46614533805;53385.466145338825;53542.19686704702;1455.2869094144005;1466.8386477487468;1489.4265707702612;1496.880249580031;1497.3486533358025;22958.311250661838;22960.081596735945;23040.539189216455;23124.190653327052;23157.305280841603;39003.80498097491;39099;39187.80406097951;39227.215455691294;39306.60693393029;199266;200533;200597;200692;200764;34549.827250863586;34934;37331.81334093312;39294.214115718096;39439.60560394359;46314.76842615765;48402.75798620984;48443.27335090047;48930.5106948926;48973.75513122412;34743.305133896676;35239.11902202466;35264;35291.470627940085;35315.823420882734;34166.658333416344;34810.82594587011;34929.65070349366;35191.824040879634;35677;38823.805880970416;39156.60843391644;39244.2151156981;39277.60722392739;39335.40996885106;33477.83261083679;33967.49048764221;34045.48931766075;34098.48852267268;34426;25384.365390864637;25433.237002889942;25503.617445737957;25917.870410647825;26160.60759088653 16:48:17 INFO - PID 26322 | |2;jquery.html;575.6488541989388;579.9623024503406;585.3911931591146;587.7825204674269;594.7710131599334;22674.65988010179;23389.649155262643;23414.88292558537;23437.179698710548;23441;25603.23190304289;26497.86751066247;27879.303017424576;28038;28211.435771284614;6914.481413893953;7090;7093.964530177368;7094.042304288907;7103.147622285333;6414.262359828609;7046.7181312747425;7070.752523661673;7105.751298704547;7112.288771122872;7023.578585284899;7046.1192350956135;7049.295070492961;7052.04797352356;7058.929410705879;7048.400885924702;7052.33002864727;7052.330028647296;7065.43476521877;7075.469339799484;6904.4131248843905;6916.515843890929;6920.653967301665;6942.479314051467;6953.269906659779;30702.53946190809;32204;32647.83676081628;32763.83618081918;33091.50362744561;1986.7516560429947;2032.8881911494861;2034.9796502035085;2035.1045539962518;2035.6335859545277;21089.261875834425;21444.785552144436;21477.89261053685;21481.140754369808;21496.140154393805;2345.9875060067275;2378.5718570657273;2447.290285817111;2568.8201825872197;2586.4697736963876;142073;142398;146333;146663;146810.26594867065;19718.816870987786;21554.29758089475;22283.10867565279;22544.436389090075;22620;23649.52700946006;24805.379865503506;24809.87595062013;24826.5034699305;43900.56099439029;9893.554790034466;9921.50392480373;10031.297809153435;10117.797644047077;10222.437765922945;8845.867311990325;8915.063918288615;8916.866247006301;8976.775580610538;9010.729678109667;9237.491937943478;9316.48759318244;9481;9500.762480938032;9532.857007144898;2597.2597809624544;2664.493746188218;2695.2992222022417;2716.904908328199;2756.517609418344;950.2445555783169;970.9271804614682;976.4092723902079;979.2508730820928;982.3516479123743;19989.100490477962;20165.19339226444;20168.19327226924;20513.692294615594;20742.58514829695;1021.7803172317987;1023.2376879224876;1024.8155332040155;1043.4574021508772;1045.7647029418472;11347.092232621535;13165.53920612793;13351.933240333736;14060.22668753208;14227.28863556818;490217;503008;507987;511018;527392;118157;118772.40613797048;122159.38920305342;122179;123324;1275.3368248510722;1280.6606249343931;1282.1217466035796;1301.056733867957;1305.3538498443522;21265.57468850645;21450.785492144878;21562.137514499245;21577.352679419644;21854.235101771363;45697.31454028126;55899.720501397234;56948.430515694316;59735.40264597299;61331.386686134356;65394.34605653882;65976.34023659895;66383.33616663772;66502.66748666226;66568.66715666391;14695;15091.622709431913;15241.23793810283;15267.847321526642;15278.312475938832;23310.76689233086;23372.415689607897;23534.176303829285;23714.170004049774;23864.40338991539;18366.806157599713;18547.44357669272;18594.907025465327;18774.530636733645;18823.623527529646;16287.267072981982;16357;16453.09507977025;16487.917560412123;17062.48812535626;18434.446966591022;18849.717254240924;18948.242070317032;20298.69551956692;20329.49176270546;15080.924595376953;15140.621484462976;15281.847181528043;15342.15618141013;15436.30536625877;18847.90576047111;18923.337683181024;19264.61470770605;19273.807261927202;19285.517862053563 16:48:17 INFO - PID 26322 | |3;mootools.html;1371.3006366752954;1378.214417781864;1415.4833485777694;1417.305520295055;1424.480064776357;13722.45110195591;14260.786088208684;14335.713285734279;14338.784918226222;14358;13801.861981380183;14223.431062757503;14712.337944792484;14727.190004549746;14747.48383806567;2720.22473595025;2783.9443211135767;2785.6239407679905;2787.749102580767;2790.9581356279673;2602.596597527386;2670.212287375229;2700.2439316991176;2760.792940529458;2802.7897907656907;2592.2093761402766;2616.24129002589;2626.7373262673773;2634.920952371432;2638.1557901471538;2645.9470810583775;2665.267051560817;2665.493556224321;2673.104509989146;2686;2092.5710229402935;2104.1793700456683;2128.063651993118;2128.30829980257;2141.796529329711;13675.384607692678;13972.790408143785;13982.860171398257;13993.580192594238;14005.159690418608;7496.325330720261;7816.179301173409;7817.570033648147;7830.138684744674;7833.177516360757;5014.824481143179;5110.361204849394;5237.895242095136;5297.549708274802;5314.5216930476445;1826.7990521042675;1842.253887175696;1842.9631407371778;1868.504846215754;1887.8867267963828;22719.318420447413;22787;22803.201887933843;22815.771842281698;22836.31491055271;6088.6042407243385;6241.439640089978;6410.294867564564;6502.024696295499;6526.934730652728;15760;15814.9209253953;15837.68324633501;15900.250972393027;15911.840881591268;7322.670479828421;7539.132999704994;7549.73575924851;7551.471397002267;7561.924380756122;6833;6987.89518157228;7071.116110486189;7182.7126914923865;7199.3520583147765;4932.802687892444;5124.180131178993;5148.768305426265;5193.272941788153;5199.844004679866;1482.3699927530972;1513.1526345246698;1514.379104567122;1514.9015314004555;1516.226724370579;907.1472815553359;932.4917919733762;936.9953150234342;940.8212439636447;941.5762906692016;8179.591020448954;8249.505029698237;8318.251357377743;8375.288100511525;8402.915970840335;986.8125056239435;990.6823924180966;995.318207028188;1006.808706345792;1034.250168627739;4993.575546078624;5185.325907631984;5280.498352656491;5320.334958130234;5529.281193444826;19052.428427147206;19447.416577502696;19724.802751972296;19767.209311627663;19775.40673779789;18258.178381972844;19159.561873874547;19196;19285.4214373569;19409.611807763766;10125.240606954361;10874.945625271823;11153.944230278797;11167.720806979729;11264.380459074666;8045.87931181021;8103.473274237156;8260.752177434686;8266.256036956704;8317.500949943023;16269.430569929995;16811.495655130366;16970.23633936451;16989.83010169932;17152.48542543726;17366.82633173652;17394.304227830744;17516.91241543784;17519.386821461696;17599.560010999314;10536.157107431234;10592.8940710595;10675.89324106749;10706.571737130427;10719.678409647722;13058.869411305765;13161.60515184546;13294.20234785916;13307.135036222615;13323;10581.62964296246;10681.359118452918;10685.465726713788;10843.457827108456;10908.181886358714;10515.789684206426;10526.578936842441;10604.787904242026;10623.203259755704;10630.25588208818;9410.482423466501;9609.9039009609;9612.951935240279;9616.182624476998;9618.759531011781;7142.5714457132735;7142.750003749844;7170.103737032871;7234.74678386254;7254.419646428168;7398.926010739824;7443.441741869273;7498.550086994798;7514.774556763306;7536.1333446654335 16:48:17 INFO - PID 26322 | |4;prototype.html;503.83121654245826;508.48642870700627;522.4096770649165;528.9603279754018;533.8451848963798;6154.938450615493;6171.9382806171925;6176.598521096125;6215.658138802364;6267.623942563449;6078.361772013941;6101.420365065323;6120.47975922046;6169.691515424233;6180.2583689957155;4802.807887684488;4953.405591329046;4987.576056035241;5036.194208926563;5038.84883453497;4968.807486203321;4969.428515720684;4972.179590367585;4979.705276628067;5000.549950504454;4955.628327875405;4956.3804524434445;4959.677620954644;4971.552560269575;4995.925061124086;4927.457979622239;4962.851114466572;4963.181075122637;4977.701337919718;4999.450060493343;4489.169503641804;4489.518458908585;4496.820127194908;4503.684742068057;4530.161920044802;5931;5969.820905372802;5987.461128498456;6011;6013.939860601382;5592.664440133605;5616.52259557938;5655.462731040545;5659.094544872801;5705;6423.582467139668;6454.515911306671;6501.512386571026;6503.739850405978;6517.543771935966;4826.0830442215865;4834.3473631059705;4846.36997190363;4872.269159626049;4873.975630121862;6520.836979075513;6675.399214070757;6716.697748601374;6809.727610895558;6911.067005954182;5326.733663316819;5427.402985671573;5453.318335208099;5455.645383050089;5462.880109577527;5481.123020316731;5517.99269398179;5541.639793413416;5564.4991950724625;5571.052921003439;5891.823245302648;5981.760729570856;5993.430624090704;5995.340512543617;5996.790112346134;6565.409113179837;6583.539152259392;6588.4729221662155;6602.7689030883685;6628.376047868303;1894.602133551942;1949.2300541286397;1960.9117589708496;1965.862389632712;1969.9606007879763;1401.6425811418023;1425.2588653899913;1429.171080773149;1441.740486712402;1476.247113971882;5647.915281270784;5652.576056795757;5711.857203569944;5728.684922329312;5741.45456181662;6504;6526.4452521536205;6573.244076931214;6595.8021259362295;6605.70274337656;5636.689982050944;5674.886502270014;5702.517345490202;5707.828765136969;5757.453041961091;3496.230829217568;3510.4734289856724;3517.577890653138;3528.4883691865048;3532.187596852687;6361.872762544724;6375.139356186855;6406.455451286693;6406.67966601668;6472.223333199952;6740.62926539045;6811.182658080963;6841.863162736817;6900.274931267183;6906.412954898789;6425.361532809197;6469.417752402306;6478.578892371981;6482.611043337415;6490.318516555693;6629.370209830058;6642.43539299165;6645.833854153491;6659.700313485808;6704.329567043452;6477.838054048687;6489.4808415325715;6590.94544872818;6621.9337806621315;6656.7670131545165;6378.712957916998;6389.616623002635;6409.230892292955;6440.264646504922;6447.355264473703;5389.8113566025;5425.674459532441;5435.510804027656;5441.809536666356;5462.562994960314;6380.138681278154;6527.281998980204;6539.509536784665;6631.916158986095;6635.834104147435;6322.620642761449;6373.330800265905;6393.904091438539;6428;6458.773942911976;6316.494680425647;6428.935710643021;6437;6443.291237963914;6462.321456247027;6298.826904717865;6344.777932772331;6349.14286571307;6397.232332119989;6429.935700642934;6400.45596124316;6459.709313081016;6486.416222539994;6487.9026814598765;6535.771248006297;6172.321044684992;6210.347913469202;6255.374462553708;6301.684915754286;6311.96844015777 16:48:17 INFO - PID 26322 | |5;yui.html;906.5421961909236;924.2467389077901;925.2459245714742;933.2953620016887;948.7011591348725;18111.184996675147;19065;19067.523311917208;19069.14188861501;19098.522536936583;18682.346117885878;19023;19191.712124318146;19211.615767684638;19238.807611923876;1037.1858091398253;1051.0698032241444;1057.9259451838375;1060.867391576053;1062.044160255772;966;1050.3435352904435;1050.3487837540736;1051.9684409467727;1067.6049861551198;1104.4256986367084;1107.0258172807917;1107.3466654673741;1109.8557187565646;1114.9275297105662;1100.2628239079836;1105.9612913548028;1106.905912997392;1107.5126944144552;1116.4640972333284;803.8030682482771;805.3355981315439;806.1817255485687;808.4300568099476;817.6606708216083;18016.099195040195;18032.729509057244;18048.458546243633;18634.81365186358;19499.122539485757;21416;21426.5714685707;22095.558088838294;22114.77885221143;22342;15142.242887855673;15266.542003739794;15520.301586428637;15545;16173.867829251956;3581.7492775505725;3604.098975256186;3605.188832512664;3617.7467577269604;3629.4555816627717;125958;126254.5309101821;127628;129461;130943;15967.36130554788;16446.177691115634;16508.83491165097;16578.08820514859;16641.251143698562;31078.68921310803;31600;31627.36745265082;31678.683213168035;32320.676793232236;7808.726694565777;7822.882656760039;7830.8433831323055;7842.686292548348;7902.328302094272;3641.10792855752;3714.7399682022;4025.456563363967;4169.041120542292;4194.370844373308;5284.4187139414635;5373.194020896896;5398.838034858961;5401.459854014567;5403.9459605403445;1044.5926088825365;1076.7092884921028;1087.065123993365;1100.1638754546443;1101.658485869383;644.6196743921109;655.1090516897016;657.9638119903356;670.2560158224367;689.9068625735462;12373.195742276723;12374.555195713858;12434.440450179764;12558.120931534888;13350.198988060747;673.9865202695919;685.7325642999234;694.7394726977384;697.5396238482679;714.610537257196;5708.229389032427;5717.656940583495;5822.388649191774;5879.882402351929;5943.465088142088;116393.83606163999;116624.41687791506;117142;117898;118307;94893;95163;97098;97956.51021744846;99118.5044074775;83168;84834.15165848385;85599.5720021396;86527;87390;11188.888111118784;11469.139814514112;11496.367699776592;11504.769904602028;11634.825477618011;37354.252914941004;37401.81299093487;37501;38761;39246.21507569776;48803.75598121987;49604.75197624133;49663.50336496589;51692.74153629208;52000.95998079942;16187.10970896612;16299.511014669104;16473.67052658964;16498.67002659964;16622.085785281924;18147.54631134232;21654.8917255419;21687.891560542095;21695.783042169376;22312.10751569919;18852.622947541247;19204.327848525234;19207;19515.414537563895;19574.314898978468;19201.80798192056;19729.506762330482;19836.50408739793;19883.60232795365;20091.296804611768;18326.358577449726;19080.522986925436;19129.23483060662;19191.808081919004;19286;14283.500077497238;14661.560153195422;14700.191489468181;14708.117512949257;14758.926205368904;14648.633784155481;14669;14770.261486925396;14868.925655371653;15080 16:48:17 INFO - PID 26322 | __end_tp_report 16:48:17 INFO - PID 26322 | __start_cc_report 16:48:17 INFO - PID 26322 | _x_x_mozilla_cycle_collect,381 16:48:17 INFO - PID 26322 | __end_cc_report 16:48:17 INFO - PID 26322 | __startTimestamp1502322497429__endTimestamp 16:48:17 INFO - PID 26322 | 16:48:17 INFO - PID 26322 | ------- Summary: start ------- 16:48:17 INFO - PID 26322 | Number of tests: 6 16:48:17 INFO - PID 26322 | 16:48:17 INFO - PID 26322 | [#0] dojo.html Cycles:165 Average:6189.62 Median:6426.26 stddev:729.67 (11.4%) stddev-sans-first:691.05 16:48:17 INFO - PID 26322 | Values: 3121.0 3133.9 3134.0 3154.1 3208.1 6193.3 6238.6 6277.3 6305.0 6402.5 6182.4 6217.0 6231.8 6241.1 6266.3 5711.2 5751.6 5770.7 5787.2 5814.7 5792.1 5795.2 5797.9 5803.9 5832.0 5747.5 5799.9 5837.2 5845.5 5865.6 5703.1 5753.9 5803.2 5821.4 5851.8 5327.9 5570.1 5702.7 5715.1 5724.0 6174.2 6192.4 6231.1 6296.9 6310.4 6698.7 6702.4 6798.0 6807.5 6824.6 6625.6 6669.1 6702.5 6803.7 6815.4 6264.4 6272.9 6276.1 6276.3 6277.9 6937.6 6974.6 6976.9 6992.9 6994.8 6545.6 6556.6 6557.7 6620.0 6658.6 6641.1 6663.8 6762.7 6789.5 6791.1 6516.3 6603.7 6639.1 6639.1 6719.6 6637.9 6663.9 6680.9 6688.7 6698.9 5458.2 5483.0 5486.0 5489.5 5493.1 4612.9 4669.7 4687.9 4696.1 4711.2 5730.1 5822.5 5836.5 5836.7 5868.2 6744.5 6769.3 6773.0 6776.1 6778.0 5791.2 5803.1 5813.7 5815.7 5826.7 5988.3 5990.7 6004.0 6005.5 6011.7 6485.3 6493.8 6576.7 6579.9 6588.5 6658.8 6731.7 6738.3 6829.1 6841.5 6485.8 6500.3 6502.6 6507.8 6518.7 6634.5 6635.4 6635.8 6642.8 6660.2 6756.8 6776.3 6792.8 6801.0 6805.3 6480.2 6502.1 6574.2 6575.5 6581.4 6480.5 6482.9 6581.6 6583.1 6594.9 6338.1 6378.0 6426.3 6481.7 6490.5 6568.2 6577.2 6627.5 6629.3 6637.4 6226.6 6246.8 6319.8 6324.3 6422.6 16:48:17 INFO - PID 26322 | 16:48:17 INFO - PID 26322 | [#1] ext.html Cycles:180 Average:38635.58 Median:3592.93 stddev:54562.56 (1518.6%) stddev-sans-first:54641.76 16:48:17 INFO - PID 26322 | Values: 827.5 856.8 882.6 887.5 1191.7 31422.0 32041.4 43045.6 43370.0 43798.6 43826.0 44386.0 44408.1 44443.8 44474.0 7318.1 7764.9 7925.7 7934.2 7963.5 7760.3 7875.5 7876.2 7879.6 7885.5 7812.1 7886.6 7916.0 7922.4 7922.4 7817.4 7839.6 7847.8 7850.1 7860.9 7744.2 7756.7 7795.5 7827.3 7831.0 38555.0 38613.0 38922.8 38963.0 39029.2 3550.1 3592.7 3593.1 3593.6 3594.5 31844.4 31849.0 31910.7 31966.8 31993.2 5127.9 5168.7 5169.7 5203.2 5219.5 194943.0 195691.0 195927.0 196367.0 197090.0 48436.5 48504.8 48623.8 48800.5 48852.0 51091.2 51116.5 51125.2 51135.0 51269.7 14062.6 14374.6 14413.9 14417.4 14426.3 10891.7 11046.8 11538.2 12793.0 13098.3 14153.9 14192.6 14367.5 14471.9 14522.3 5731.4 5732.5 5740.4 5790.2 5803.4 973.3 1000.4 1014.9 1015.8 1027.2 23707.1 24070.5 24208.4 29041.4 29663.6 1072.9 1075.1 1075.3 1085.7 1094.1 12372.8 12725.4 12765.6 12823.9 12844.6 231911.0 233557.0 233940.0 233976.0 234202.0 53004.5 53346.5 53385.5 53385.5 53542.2 1455.3 1466.8 1489.4 1496.9 1497.3 22958.3 22960.1 23040.5 23124.2 23157.3 39003.8 39099.0 39187.8 39227.2 39306.6 199266.0 200533.0 200597.0 200692.0 200764.0 34549.8 34934.0 37331.8 39294.2 39439.6 46314.8 48402.8 48443.3 48930.5 48973.8 34743.3 35239.1 35264.0 35291.5 35315.8 34166.7 34810.8 34929.7 35191.8 35677.0 38823.8 39156.6 39244.2 39277.6 39335.4 33477.8 33967.5 34045.5 34098.5 34426.0 25384.4 25433.2 25503.6 25917.9 26160.6 16:48:17 INFO - PID 26322 | 16:48:17 INFO - PID 26322 | [#2] jquery.html Cycles:180 Average:36312.93 Median:12940.08 stddev:85532.17 (661.0%) stddev-sans-first:85730.02 16:48:17 INFO - PID 26322 | Values: 575.6 580.0 585.4 587.8 594.8 22674.7 23389.6 23414.9 23437.2 23441.0 25603.2 26497.9 27879.3 28038.0 28211.4 6914.5 7090.0 7094.0 7094.0 7103.1 6414.3 7046.7 7070.8 7105.8 7112.3 7023.6 7046.1 7049.3 7052.0 7058.9 7048.4 7052.3 7052.3 7065.4 7075.5 6904.4 6916.5 6920.7 6942.5 6953.3 30702.5 32204.0 32647.8 32763.8 33091.5 1986.8 2032.9 2035.0 2035.1 2035.6 21089.3 21444.8 21477.9 21481.1 21496.1 2346.0 2378.6 2447.3 2568.8 2586.5 142073.0 142398.0 146333.0 146663.0 146810.3 19718.8 21554.3 22283.1 22544.4 22620.0 23649.5 24805.4 24809.9 24826.5 43900.6 9893.6 9921.5 10031.3 10117.8 10222.4 8845.9 8915.1 8916.9 8976.8 9010.7 9237.5 9316.5 9481.0 9500.8 9532.9 2597.3 2664.5 2695.3 2716.9 2756.5 950.2 970.9 976.4 979.3 982.4 19989.1 20165.2 20168.2 20513.7 20742.6 1021.8 1023.2 1024.8 1043.5 1045.8 11347.1 13165.5 13351.9 14060.2 14227.3 490217.0 503008.0 507987.0 511018.0 527392.0 118157.0 118772.4 122159.4 122179.0 123324.0 1275.3 1280.7 1282.1 1301.1 1305.4 21265.6 21450.8 21562.1 21577.4 21854.2 45697.3 55899.7 56948.4 59735.4 61331.4 65394.3 65976.3 66383.3 66502.7 66568.7 14695.0 15091.6 15241.2 15267.8 15278.3 23310.8 23372.4 23534.2 23714.2 23864.4 18366.8 18547.4 18594.9 18774.5 18823.6 16287.3 16357.0 16453.1 16487.9 17062.5 18434.4 18849.7 18948.2 20298.7 20329.5 15080.9 15140.6 15281.8 15342.2 15436.3 18847.9 18923.3 19264.6 19273.8 19285.5 16:48:17 INFO - PID 26322 | 16:48:17 INFO - PID 26322 | [#3] mootools.html Cycles:180 Average:8745.42 Median:22826.04 stddev:5927.88 (26.0%) stddev-sans-first:5918.61 16:48:17 INFO - PID 26322 | Values: 1371.3 1378.2 1415.5 1417.3 1424.5 13722.5 14260.8 14335.7 14338.8 14358.0 13801.9 14223.4 14712.3 14727.2 14747.5 2720.2 2783.9 2785.6 2787.7 2791.0 2602.6 2670.2 2700.2 2760.8 2802.8 2592.2 2616.2 2626.7 2634.9 2638.2 2645.9 2665.3 2665.5 2673.1 2686.0 2092.6 2104.2 2128.1 2128.3 2141.8 13675.4 13972.8 13982.9 13993.6 14005.2 7496.3 7816.2 7817.6 7830.1 7833.2 5014.8 5110.4 5237.9 5297.5 5314.5 1826.8 1842.3 1843.0 1868.5 1887.9 22719.3 22787.0 22803.2 22815.8 22836.3 6088.6 6241.4 6410.3 6502.0 6526.9 15760.0 15814.9 15837.7 15900.3 15911.8 7322.7 7539.1 7549.7 7551.5 7561.9 6833.0 6987.9 7071.1 7182.7 7199.4 4932.8 5124.2 5148.8 5193.3 5199.8 1482.4 1513.2 1514.4 1514.9 1516.2 907.1 932.5 937.0 940.8 941.6 8179.6 8249.5 8318.3 8375.3 8402.9 986.8 990.7 995.3 1006.8 1034.3 4993.6 5185.3 5280.5 5320.3 5529.3 19052.4 19447.4 19724.8 19767.2 19775.4 18258.2 19159.6 19196.0 19285.4 19409.6 10125.2 10874.9 11153.9 11167.7 11264.4 8045.9 8103.5 8260.8 8266.3 8317.5 16269.4 16811.5 16970.2 16989.8 17152.5 17366.8 17394.3 17516.9 17519.4 17599.6 10536.2 10592.9 10675.9 10706.6 10719.7 13058.9 13161.6 13294.2 13307.1 13323.0 10581.6 10681.4 10685.5 10843.5 10908.2 10515.8 10526.6 10604.8 10623.2 10630.3 9410.5 9609.9 9613.0 9616.2 9618.8 7142.6 7142.8 7170.1 7234.7 7254.4 7398.9 7443.4 7498.6 7514.8 7536.1 16:48:17 INFO - PID 26322 | 16:48:17 INFO - PID 26322 | [#4] prototype.html Cycles:180 Average:5514.33 Median:6089.89 stddev:1481.60 (24.3%) stddev-sans-first:1437.23 16:48:17 INFO - PID 26322 | Values: 503.8 508.5 522.4 529.0 533.8 6154.9 6171.9 6176.6 6215.7 6267.6 6078.4 6101.4 6120.5 6169.7 6180.3 4802.8 4953.4 4987.6 5036.2 5038.8 4968.8 4969.4 4972.2 4979.7 5000.5 4955.6 4956.4 4959.7 4971.6 4995.9 4927.5 4962.9 4963.2 4977.7 4999.5 4489.2 4489.5 4496.8 4503.7 4530.2 5931.0 5969.8 5987.5 6011.0 6013.9 5592.7 5616.5 5655.5 5659.1 5705.0 6423.6 6454.5 6501.5 6503.7 6517.5 4826.1 4834.3 4846.4 4872.3 4874.0 6520.8 6675.4 6716.7 6809.7 6911.1 5326.7 5427.4 5453.3 5455.6 5462.9 5481.1 5518.0 5541.6 5564.5 5571.1 5891.8 5981.8 5993.4 5995.3 5996.8 6565.4 6583.5 6588.5 6602.8 6628.4 1894.6 1949.2 1960.9 1965.9 1970.0 1401.6 1425.3 1429.2 1441.7 1476.2 5647.9 5652.6 5711.9 5728.7 5741.5 6504.0 6526.4 6573.2 6595.8 6605.7 5636.7 5674.9 5702.5 5707.8 5757.5 3496.2 3510.5 3517.6 3528.5 3532.2 6361.9 6375.1 6406.5 6406.7 6472.2 6740.6 6811.2 6841.9 6900.3 6906.4 6425.4 6469.4 6478.6 6482.6 6490.3 6629.4 6642.4 6645.8 6659.7 6704.3 6477.8 6489.5 6590.9 6621.9 6656.8 6378.7 6389.6 6409.2 6440.3 6447.4 5389.8 5425.7 5435.5 5441.8 5462.6 6380.1 6527.3 6539.5 6631.9 6635.8 6322.6 6373.3 6393.9 6428.0 6458.8 6316.5 6428.9 6437.0 6443.3 6462.3 6298.8 6344.8 6349.1 6397.2 6429.9 6400.5 6459.7 6486.4 6487.9 6535.8 6172.3 6210.3 6255.4 6301.7 6312.0 16:48:17 INFO - PID 26322 | 16:48:17 INFO - PID 26322 | [#5] yui.html Cycles:180 Average:23391.25 Median:19225.21 stddev:32111.39 (167.0%) stddev-sans-first:32157.08 16:48:17 INFO - PID 26322 | Values: 906.5 924.2 925.2 933.3 948.7 18111.2 19065.0 19067.5 19069.1 19098.5 18682.3 19023.0 19191.7 19211.6 19238.8 1037.2 1051.1 1057.9 1060.9 1062.0 966.0 1050.3 1050.3 1052.0 1067.6 1104.4 1107.0 1107.3 1109.9 1114.9 1100.3 1106.0 1106.9 1107.5 1116.5 803.8 805.3 806.2 808.4 817.7 18016.1 18032.7 18048.5 18634.8 19499.1 21416.0 21426.6 22095.6 22114.8 22342.0 15142.2 15266.5 15520.3 15545.0 16173.9 3581.7 3604.1 3605.2 3617.7 3629.5 125958.0 126254.5 127628.0 129461.0 130943.0 15967.4 16446.2 16508.8 16578.1 16641.3 31078.7 31600.0 31627.4 31678.7 32320.7 7808.7 7822.9 7830.8 7842.7 7902.3 3641.1 3714.7 4025.5 4169.0 4194.4 5284.4 5373.2 5398.8 5401.5 5403.9 1044.6 1076.7 1087.1 1100.2 1101.7 644.6 655.1 658.0 670.3 689.9 12373.2 12374.6 12434.4 12558.1 13350.2 674.0 685.7 694.7 697.5 714.6 5708.2 5717.7 5822.4 5879.9 5943.5 116393.8 116624.4 117142.0 117898.0 118307.0 94893.0 95163.0 97098.0 97956.5 99118.5 83168.0 84834.2 85599.6 86527.0 87390.0 11188.9 11469.1 11496.4 11504.8 11634.8 37354.3 37401.8 37501.0 38761.0 39246.2 48803.8 49604.8 49663.5 51692.7 52001.0 16187.1 16299.5 16473.7 16498.7 16622.1 18147.5 21654.9 21687.9 21695.8 22312.1 18852.6 19204.3 19207.0 19515.4 19574.3 19201.8 19729.5 19836.5 19883.6 20091.3 18326.4 19080.5 19129.2 19191.8 19286.0 14283.5 14661.6 14700.2 14708.1 14758.9 14648.6 14669.0 14770.3 14868.9 15080.0 16:48:17 INFO - PID 26322 | -------- Summary: end -------- 16:48:17 INFO - PID 26322 | 16:48:18 INFO - TEST-INFO | 26322: exit 0 16:48:18 INFO - TEST-OK | dromaeo_css | took 1112106ms 16:48:18 INFO - TEST-START | kraken 16:48:18 INFO - Initialising browser for kraken test... 16:48:18 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37604/getInfo.html -profile /tmp/tmpJQtRZb/profile 16:48:18 INFO - TEST-INFO | started process 26683 (/builds/slave/test/build/application/firefox/firefox http://localhost:37604/getInfo.html) 16:48:23 INFO - TEST-INFO | 26683: exit 0 16:48:23 INFO - Browser initialized. 16:48:23 INFO - Running cycle 1/1 for kraken test... 16:48:23 INFO - TEST-INFO | started process 26899 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJQtRZb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:48:24 INFO - PID 26899 | 16:48:24 INFO - PID 26899 | (/builds/slave/test/build/application/firefox/firefox:26947): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:48:24 INFO - PID 26899 | 16:48:35 INFO - PID 26899 | RSS: Main: 166322176 16:48:35 INFO - PID 26899 | 16:48:37 INFO - PID 26899 | Cycle 1(1): loaded http://localhost:37604/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37604/tests/kraken/driver.html?testName=audio-beat-detection) 16:48:38 INFO - PID 26899 | RSS: Main: 173182976 16:48:38 INFO - PID 26899 | 16:48:41 INFO - PID 26899 | Cycle 1(1): loaded http://localhost:37604/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37604/tests/kraken/driver.html?testName=audio-dft) 16:48:42 INFO - PID 26899 | RSS: Main: 173252608 16:48:42 INFO - PID 26899 | 16:48:45 INFO - PID 26899 | Cycle 1(1): loaded http://localhost:37604/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37604/tests/kraken/driver.html?testName=audio-fft) 16:48:45 INFO - PID 26899 | RSS: Main: 167559168 16:48:45 INFO - PID 26899 | 16:48:47 INFO - PID 26899 | Cycle 1(1): loaded http://localhost:37604/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37604/tests/kraken/driver.html?testName=audio-oscillator) 16:48:48 INFO - PID 26899 | RSS: Main: 168345600 16:48:48 INFO - PID 26899 | 16:48:50 INFO - PID 26899 | Cycle 1(1): loaded http://localhost:37604/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37604/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:48:51 INFO - PID 26899 | RSS: Main: 168550400 16:48:51 INFO - PID 26899 | 16:48:54 INFO - PID 26899 | Cycle 1(1): loaded http://localhost:37604/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37604/tests/kraken/driver.html?testName=imaging-darkroom) 16:48:54 INFO - PID 26899 | RSS: Main: 170835968 16:48:54 INFO - PID 26899 | 16:48:58 INFO - PID 26899 | Cycle 1(1): loaded http://localhost:37604/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37604/tests/kraken/driver.html?testName=imaging-desaturate) 16:48:59 INFO - PID 26899 | RSS: Main: 170909696 16:48:59 INFO - PID 26899 | 16:49:01 INFO - PID 26899 | Cycle 1(1): loaded http://localhost:37604/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37604/tests/kraken/driver.html?testName=json-parse-financial) 16:49:02 INFO - PID 26899 | RSS: Main: 171585536 16:49:02 INFO - PID 26899 | 16:49:04 INFO - PID 26899 | Cycle 1(1): loaded http://localhost:37604/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37604/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:49:04 INFO - PID 26899 | RSS: Main: 171765760 16:49:04 INFO - PID 26899 | 16:49:06 INFO - PID 26899 | Cycle 1(1): loaded http://localhost:37604/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37604/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:49:07 INFO - PID 26899 | RSS: Main: 171761664 16:49:07 INFO - PID 26899 | 16:49:09 INFO - PID 26899 | Cycle 1(1): loaded http://localhost:37604/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37604/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:49:10 INFO - PID 26899 | RSS: Main: 171773952 16:49:10 INFO - PID 26899 | 16:49:12 INFO - PID 26899 | Cycle 1(1): loaded http://localhost:37604/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37604/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:49:13 INFO - PID 26899 | RSS: Main: 172236800 16:49:13 INFO - PID 26899 | 16:49:16 INFO - PID 26899 | Cycle 1(1): loaded http://localhost:37604/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37604/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:49:17 INFO - PID 26899 | RSS: Main: 172609536 16:49:17 INFO - PID 26899 | 16:49:18 INFO - PID 26899 | Cycle 1(1): loaded http://localhost:37604/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37604/tests/kraken/driver.html?testName=ai-astar) 16:49:19 INFO - PID 26899 | RSS: Main: 174231552 16:49:19 INFO - PID 26899 | 16:49:19 INFO - PID 26899 | __start_tp_report 16:49:19 INFO - PID 26899 | _x_x_mozilla_page_load 16:49:19 INFO - PID 26899 | _x_x_mozilla_page_load_details 16:49:19 INFO - PID 26899 | |i|pagename|runs| 16:49:19 INFO - PID 26899 | |0;ai-astar;99;107;101;109;107;116;111;101;110;112 16:49:19 INFO - PID 26899 | |1;audio-beat-detection;146;119;144;172;120;145;142;118;177;120 16:49:19 INFO - PID 26899 | |2;audio-dft;163;162;163;161;161;163;162;167;167;165 16:49:19 INFO - PID 26899 | |3;audio-fft;75;74;74;182;75;77;77;74;152;78 16:49:19 INFO - PID 26899 | |4;audio-oscillator;80;79;126;79;80;79;134;80;78;126 16:49:19 INFO - PID 26899 | |5;imaging-gaussian-blur;114;113;117;114;114;114;114;114;114;116 16:49:19 INFO - PID 26899 | |6;imaging-darkroom;157;151;149;150;150;151;150;151;152;150 16:49:19 INFO - PID 26899 | |7;imaging-desaturate;94;94;95;95;94;95;94;95;95;94 16:49:19 INFO - PID 26899 | |8;json-parse-financial;55;57;57;55;56;55;55;55;55;55 16:49:19 INFO - PID 26899 | |9;json-stringify-tinderbox;53;53;54;54;54;53;54;54;53;53 16:49:19 INFO - PID 26899 | |10;stanford-crypto-aes;90;88;81;90;96;87;79;88;89;77 16:49:19 INFO - PID 26899 | |11;stanford-crypto-ccm;134;132;122;122;129;134;126;138;124;164 16:49:19 INFO - PID 26899 | |12;stanford-crypto-pbkdf2;176;171;176;178;181;180;171;180;190;175 16:49:19 INFO - PID 26899 | |13;stanford-crypto-sha256-iterative;72;69;68;67;67;68;67;68;67;66 16:49:19 INFO - PID 26899 | __end_tp_report 16:49:19 INFO - PID 26899 | __start_cc_report 16:49:19 INFO - PID 26899 | _x_x_mozilla_cycle_collect,971 16:49:19 INFO - PID 26899 | __end_cc_report 16:49:19 INFO - PID 26899 | __startTimestamp1502322559175__endTimestamp 16:49:19 INFO - PID 26899 | 16:49:19 INFO - PID 26899 | ------- Summary: start ------- 16:49:19 INFO - PID 26899 | Number of tests: 14 16:49:19 INFO - PID 26899 | 16:49:19 INFO - PID 26899 | [#0] ai-astar Cycles:10 Average:107.30 Median:110.50 stddev:5.48 (5.0%) stddev-sans-first:4.92 16:49:19 INFO - PID 26899 | Values: 99.0 107.0 101.0 109.0 107.0 116.0 111.0 101.0 110.0 112.0 16:49:19 INFO - PID 26899 | 16:49:19 INFO - PID 26899 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:144.50 stddev:21.60 (14.9%) stddev-sans-first:22.81 16:49:19 INFO - PID 26899 | Values: 146.0 119.0 144.0 172.0 120.0 145.0 142.0 118.0 177.0 120.0 16:49:19 INFO - PID 26899 | 16:49:19 INFO - PID 26899 | [#2] audio-dft Cycles:10 Average:163.40 Median:163.00 stddev:2.22 (1.4%) stddev-sans-first:2.35 16:49:19 INFO - PID 26899 | Values: 163.0 162.0 163.0 161.0 161.0 163.0 162.0 167.0 167.0 165.0 16:49:19 INFO - PID 26899 | 16:49:19 INFO - PID 26899 | [#3] audio-fft Cycles:10 Average:93.80 Median:75.00 stddev:39.25 (52.3%) stddev-sans-first:41.03 16:49:19 INFO - PID 26899 | Values: 75.0 74.0 74.0 182.0 75.0 77.0 77.0 74.0 152.0 78.0 16:49:19 INFO - PID 26899 | 16:49:19 INFO - PID 26899 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:79.00 stddev:23.96 (30.3%) stddev-sans-first:24.86 16:49:19 INFO - PID 26899 | Values: 80.0 79.0 126.0 79.0 80.0 79.0 134.0 80.0 78.0 126.0 16:49:19 INFO - PID 26899 | 16:49:19 INFO - PID 26899 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.00 stddev:1.17 (1.0%) stddev-sans-first:1.24 16:49:19 INFO - PID 26899 | Values: 114.0 113.0 117.0 114.0 114.0 114.0 114.0 114.0 114.0 116.0 16:49:19 INFO - PID 26899 | 16:49:19 INFO - PID 26899 | [#6] imaging-darkroom Cycles:10 Average:151.10 Median:151.00 stddev:2.23 (1.5%) stddev-sans-first:0.88 16:49:19 INFO - PID 26899 | Values: 157.0 151.0 149.0 150.0 150.0 151.0 150.0 151.0 152.0 150.0 16:49:19 INFO - PID 26899 | 16:49:19 INFO - PID 26899 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 16:49:19 INFO - PID 26899 | Values: 94.0 94.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 94.0 16:49:19 INFO - PID 26899 | 16:49:19 INFO - PID 26899 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:55.00 stddev:0.85 (1.5%) stddev-sans-first:0.88 16:49:19 INFO - PID 26899 | Values: 55.0 57.0 57.0 55.0 56.0 55.0 55.0 55.0 55.0 55.0 16:49:19 INFO - PID 26899 | 16:49:19 INFO - PID 26899 | [#9] json-stringify-tinderbox Cycles:10 Average:53.50 Median:54.00 stddev:0.53 (1.0%) stddev-sans-first:0.53 16:49:19 INFO - PID 26899 | Values: 53.0 53.0 54.0 54.0 54.0 53.0 54.0 54.0 53.0 53.0 16:49:19 INFO - PID 26899 | 16:49:19 INFO - PID 26899 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:88.50 stddev:5.80 (6.6%) stddev-sans-first:6.01 16:49:19 INFO - PID 26899 | Values: 90.0 88.0 81.0 90.0 96.0 87.0 79.0 88.0 89.0 77.0 16:49:19 INFO - PID 26899 | 16:49:19 INFO - PID 26899 | [#11] stanford-crypto-ccm Cycles:10 Average:132.50 Median:133.00 stddev:12.36 (9.3%) stddev-sans-first:13.10 16:49:19 INFO - PID 26899 | Values: 134.0 132.0 122.0 122.0 129.0 134.0 126.0 138.0 124.0 164.0 16:49:19 INFO - PID 26899 | 16:49:19 INFO - PID 26899 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.80 Median:179.00 stddev:5.53 (3.1%) stddev-sans-first:5.83 16:49:19 INFO - PID 26899 | Values: 176.0 171.0 176.0 178.0 181.0 180.0 171.0 180.0 190.0 175.0 16:49:19 INFO - PID 26899 | 16:49:19 INFO - PID 26899 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:1.66 (2.4%) stddev-sans-first:0.88 16:49:19 INFO - PID 26899 | Values: 72.0 69.0 68.0 67.0 67.0 68.0 67.0 68.0 67.0 66.0 16:49:19 INFO - PID 26899 | -------- Summary: end -------- 16:49:19 INFO - PID 26899 | 16:49:19 INFO - PID 26899 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:49:19 INFO - TEST-INFO | 26899: exit 0 16:49:19 INFO - TEST-OK | kraken | took 61845ms 16:49:19 INFO - SUITE-END | took 1173s 16:49:20 INFO - Completed test suite (00:19:34) 16:49:20 INFO - javascript benchmark 16:49:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8605.789158199426, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3120.9531857022134, 3133.9059828205154, 3133.968660313398, 3154.0695494829024, 3208.0536241808663, 6193.256809182904, 6238.563300568962, 6277.309495955447, 6304.959681652527, 6402.487800975922, 6182.381761823815, 6217.0, 6231.844203894905, 6241.063840423927, 6266.279377871535, 5711.200431939511, 5751.597388182819, 5770.682612456313, 5787.189793428924, 5814.7092645367775, 5792.073268277077, 5795.2466179396615, 5797.913031304533, 5803.941960580382, 5832.0, 5747.482726554609, 5799.884002319972, 5837.1536127261525, 5845.532357411418, 5865.58940874139, 5703.0589952657765, 5753.856153596152, 5803.21656576361, 5821.388754180793, 5851.824445266606, 5327.920081198785, 5570.136628822539, 5702.657840529581, 5715.142728590703, 5723.971380143114, 6174.228221472316, 6192.380761923817, 6231.096491008823, 6296.874062518724, 6310.400511951358, 6698.665066746643, 6702.4302934250645, 6798.04827324175, 6807.455403567752, 6824.556403833735, 6625.602463852184, 6669.099671544376, 6702.463802895805, 6803.65981700913, 6815.420689241371, 6264.373562643745, 6272.902242107613, 6276.058591211355, 6276.340984196685, 6277.937220627827, 6937.618430986243, 6974.58152510851, 6976.895346569806, 6992.860142797217, 6994.825129371807, 6545.574537655038, 6556.606603603799, 6557.672116394161, 6619.966900165468, 6658.600483970978, 6641.1366522352755, 6663.800085997428, 6762.729490820312, 6789.456843452512, 6791.117154769849, 6516.283208847023, 6603.70283337251, 6639.070530125786, 6639.070530125786, 6719.563228390237, 6637.86724265512, 6663.933360666331, 6680.899786503206, 6688.7324507019175, 6698.933010669928, 5458.208559758813, 5483.040467918098, 5486.0, 5489.478499542617, 5493.066178749542, 4612.892905702607, 4669.696469729457, 4687.882802929886, 4696.107739529547, 4711.246200607886, 5730.1404789281105, 5822.505087067562, 5836.474717275466, 5836.737346819404, 5868.2077919480325, 6744.460443164535, 6769.289224631441, 6772.966135169293, 6776.085228494188, 6778.0, 5791.247137872049, 5803.100519419497, 5813.709314534341, 5815.73829177688, 5826.650400975955, 5988.251468566429, 5990.73041713124, 6003.969980150158, 6005.549583781234, 6011.6693581853415, 6485.286618471964, 6493.837654058686, 6576.704048317837, 6579.901301480481, 6588.4729221662155, 6658.833529161616, 6731.663416829237, 6738.292479289604, 6829.146356705412, 6841.452683785185, 6485.805425837232, 6500.317466666121, 6502.642354670538, 6507.804765857032, 6518.674066296571, 6634.4692424604955, 6635.402813746786, 6635.8009259722285, 6642.767503137367, 6660.200775906728, 6756.831079222862, 6776.322367763184, 6792.796216113525, 6801.0138529913875, 6805.319468053155, 6480.157579514633, 6502.089707440868, 6574.211094668671, 6575.506836987341, 6581.407673309425, 6480.476688435942, 6482.935170648233, 6581.572197807127, 6583.144191255106, 6594.868102638016, 6338.144350512806, 6377.96811015942, 6426.260979987174, 6481.708323125565, 6490.48076153916, 6568.211814582281, 6577.2436169841085, 6627.536072474881, 6629.3039230882, 6637.435817955527, 6226.62640241587, 6246.812595622138, 6319.842003949938, 6324.335944725737, 6422.550421470452], "value": 6134.520533689473, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.5034979012593, 856.7729551668809, 882.580774132287, 887.5251740318927, 1191.6603767926142, 31422.0, 32041.35917281656, 43045.56954430455, 43370.0, 43798.562014379844, 43826.0, 44386.0, 44408.1118377633, 44443.77778111105, 44474.0, 7318.085239345082, 7764.8835267470895, 7925.682972681086, 7934.206579342049, 7963.482373645724, 7760.340371068466, 7875.527468351888, 7876.173001834811, 7879.566623835687, 7885.526868387915, 7812.101608315032, 7886.6451009704415, 7915.960420197891, 7922.405819563527, 7922.405819563527, 7817.374610031184, 7839.568823714693, 7847.78359354301, 7850.097238817523, 7860.882086768732, 7744.186860379636, 7756.689732410697, 7795.532268063935, 7827.3346765525, 7831.0, 38555.0, 38613.0, 38922.80538597317, 38963.0, 39029.21941561153, 3550.0947258449187, 3592.7125829933543, 3593.13764696471, 3593.586737525192, 3594.532710747586, 31844.363112737614, 31849.0, 31910.680893191235, 31966.84016579902, 31993.200169995704, 5127.948720512784, 5168.741562921877, 5169.741512924376, 5203.16749320107, 5219.478052194788, 194943.0, 195691.0, 195927.0, 196367.0, 197090.0, 48436.515634843905, 48504.7574762131, 48623.75688121537, 48800.511994880304, 48852.0, 51091.233631495554, 51116.488835111915, 51125.23312150321, 51135.0, 51269.743651281504, 14062.648433789238, 14374.568762937128, 14413.927930360282, 14417.351219195161, 14426.278686065654, 10891.727706807234, 11046.779064418666, 11538.19232653723, 12793.040521960891, 13098.279594622387, 14153.858461415253, 14192.645183870485, 14367.497137600343, 14471.927640361731, 14522.273886305642, 5731.369549349568, 5732.484076433141, 5740.425957404226, 5790.247267855236, 5803.448672376117, 973.2749101919115, 1000.3897622450299, 1014.9441780701984, 1015.8374660054503, 1027.157730660851, 23707.051717931434, 24070.51858962846, 24208.359563136288, 29041.41917161645, 29663.55504667432, 1072.9302595331278, 1075.0700643942932, 1075.2795626929976, 1085.7448499602588, 1094.141099237095, 12372.752544949051, 12725.363731813371, 12765.61703148907, 12823.871761282455, 12844.614661560168, 231911.0, 233557.0, 233940.0, 233976.0, 234202.0, 53004.46995530072, 53346.46653533493, 53385.46614533805, 53385.466145338825, 53542.19686704702, 1455.2869094144005, 1466.8386477487468, 1489.4265707702612, 1496.880249580031, 1497.3486533358025, 22958.311250661838, 22960.081596735945, 23040.539189216455, 23124.190653327052, 23157.305280841603, 39003.80498097491, 39099.0, 39187.80406097951, 39227.215455691294, 39306.60693393029, 199266.0, 200533.0, 200597.0, 200692.0, 200764.0, 34549.827250863586, 34934.0, 37331.81334093312, 39294.214115718096, 39439.60560394359, 46314.76842615765, 48402.75798620984, 48443.27335090047, 48930.5106948926, 48973.75513122412, 34743.305133896676, 35239.11902202466, 35264.0, 35291.470627940085, 35315.823420882734, 34166.658333416344, 34810.82594587011, 34929.65070349366, 35191.824040879634, 35677.0, 38823.805880970416, 39156.60843391644, 39244.2151156981, 39277.60722392739, 39335.40996885106, 33477.83261083679, 33967.49048764221, 34045.48931766075, 34098.48852267268, 34426.0, 25384.365390864637, 25433.237002889942, 25503.617445737957, 25917.870410647825, 26160.60759088653], "value": 17589.320018963233, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.6488541989388, 579.9623024503406, 585.3911931591146, 587.7825204674269, 594.7710131599334, 22674.65988010179, 23389.649155262643, 23414.88292558537, 23437.179698710548, 23441.0, 25603.23190304289, 26497.86751066247, 27879.303017424576, 28038.0, 28211.435771284614, 6914.481413893953, 7090.0, 7093.964530177368, 7094.042304288907, 7103.147622285333, 6414.262359828609, 7046.7181312747425, 7070.752523661673, 7105.751298704547, 7112.288771122872, 7023.578585284899, 7046.1192350956135, 7049.295070492961, 7052.04797352356, 7058.929410705879, 7048.400885924702, 7052.33002864727, 7052.330028647296, 7065.43476521877, 7075.469339799484, 6904.4131248843905, 6916.515843890929, 6920.653967301665, 6942.479314051467, 6953.269906659779, 30702.53946190809, 32204.0, 32647.83676081628, 32763.83618081918, 33091.50362744561, 1986.7516560429947, 2032.8881911494861, 2034.9796502035085, 2035.1045539962518, 2035.6335859545277, 21089.261875834425, 21444.785552144436, 21477.89261053685, 21481.140754369808, 21496.140154393805, 2345.9875060067275, 2378.5718570657273, 2447.290285817111, 2568.8201825872197, 2586.4697736963876, 142073.0, 142398.0, 146333.0, 146663.0, 146810.26594867065, 19718.816870987786, 21554.29758089475, 22283.10867565279, 22544.436389090075, 22620.0, 23649.52700946006, 24805.379865503506, 24809.87595062013, 24826.5034699305, 43900.56099439029, 9893.554790034466, 9921.50392480373, 10031.297809153435, 10117.797644047077, 10222.437765922945, 8845.867311990325, 8915.063918288615, 8916.866247006301, 8976.775580610538, 9010.729678109667, 9237.491937943478, 9316.48759318244, 9481.0, 9500.762480938032, 9532.857007144898, 2597.2597809624544, 2664.493746188218, 2695.2992222022417, 2716.904908328199, 2756.517609418344, 950.2445555783169, 970.9271804614682, 976.4092723902079, 979.2508730820928, 982.3516479123743, 19989.100490477962, 20165.19339226444, 20168.19327226924, 20513.692294615594, 20742.58514829695, 1021.7803172317987, 1023.2376879224876, 1024.8155332040155, 1043.4574021508772, 1045.7647029418472, 11347.092232621535, 13165.53920612793, 13351.933240333736, 14060.22668753208, 14227.28863556818, 490217.0, 503008.0, 507987.0, 511018.0, 527392.0, 118157.0, 118772.40613797048, 122159.38920305342, 122179.0, 123324.0, 1275.3368248510722, 1280.6606249343931, 1282.1217466035796, 1301.056733867957, 1305.3538498443522, 21265.57468850645, 21450.785492144878, 21562.137514499245, 21577.352679419644, 21854.235101771363, 45697.31454028126, 55899.720501397234, 56948.430515694316, 59735.40264597299, 61331.386686134356, 65394.34605653882, 65976.34023659895, 66383.33616663772, 66502.66748666226, 66568.66715666391, 14695.0, 15091.622709431913, 15241.23793810283, 15267.847321526642, 15278.312475938832, 23310.76689233086, 23372.415689607897, 23534.176303829285, 23714.170004049774, 23864.40338991539, 18366.806157599713, 18547.44357669272, 18594.907025465327, 18774.530636733645, 18823.623527529646, 16287.267072981982, 16357.0, 16453.09507977025, 16487.917560412123, 17062.48812535626, 18434.446966591022, 18849.717254240924, 18948.242070317032, 20298.69551956692, 20329.49176270546, 15080.924595376953, 15140.621484462976, 15281.847181528043, 15342.15618141013, 15436.30536625877, 18847.90576047111, 18923.337683181024, 19264.61470770605, 19273.807261927202, 19285.517862053563], "value": 12642.839689823233, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1371.3006366752954, 1378.214417781864, 1415.4833485777694, 1417.305520295055, 1424.480064776357, 13722.45110195591, 14260.786088208684, 14335.713285734279, 14338.784918226222, 14358.0, 13801.861981380183, 14223.431062757503, 14712.337944792484, 14727.190004549746, 14747.48383806567, 2720.22473595025, 2783.9443211135767, 2785.6239407679905, 2787.749102580767, 2790.9581356279673, 2602.596597527386, 2670.212287375229, 2700.2439316991176, 2760.792940529458, 2802.7897907656907, 2592.2093761402766, 2616.24129002589, 2626.7373262673773, 2634.920952371432, 2638.1557901471538, 2645.9470810583775, 2665.267051560817, 2665.493556224321, 2673.104509989146, 2686.0, 2092.5710229402935, 2104.1793700456683, 2128.063651993118, 2128.30829980257, 2141.796529329711, 13675.384607692678, 13972.790408143785, 13982.860171398257, 13993.580192594238, 14005.159690418608, 7496.325330720261, 7816.179301173409, 7817.570033648147, 7830.138684744674, 7833.177516360757, 5014.824481143179, 5110.361204849394, 5237.895242095136, 5297.549708274802, 5314.5216930476445, 1826.7990521042675, 1842.253887175696, 1842.9631407371778, 1868.504846215754, 1887.8867267963828, 22719.318420447413, 22787.0, 22803.201887933843, 22815.771842281698, 22836.31491055271, 6088.6042407243385, 6241.439640089978, 6410.294867564564, 6502.024696295499, 6526.934730652728, 15760.0, 15814.9209253953, 15837.68324633501, 15900.250972393027, 15911.840881591268, 7322.670479828421, 7539.132999704994, 7549.73575924851, 7551.471397002267, 7561.924380756122, 6833.0, 6987.89518157228, 7071.116110486189, 7182.7126914923865, 7199.3520583147765, 4932.802687892444, 5124.180131178993, 5148.768305426265, 5193.272941788153, 5199.844004679866, 1482.3699927530972, 1513.1526345246698, 1514.379104567122, 1514.9015314004555, 1516.226724370579, 907.1472815553359, 932.4917919733762, 936.9953150234342, 940.8212439636447, 941.5762906692016, 8179.591020448954, 8249.505029698237, 8318.251357377743, 8375.288100511525, 8402.915970840335, 986.8125056239435, 990.6823924180966, 995.318207028188, 1006.808706345792, 1034.250168627739, 4993.575546078624, 5185.325907631984, 5280.498352656491, 5320.334958130234, 5529.281193444826, 19052.428427147206, 19447.416577502696, 19724.802751972296, 19767.209311627663, 19775.40673779789, 18258.178381972844, 19159.561873874547, 19196.0, 19285.4214373569, 19409.611807763766, 10125.240606954361, 10874.945625271823, 11153.944230278797, 11167.720806979729, 11264.380459074666, 8045.87931181021, 8103.473274237156, 8260.752177434686, 8266.256036956704, 8317.500949943023, 16269.430569929995, 16811.495655130366, 16970.23633936451, 16989.83010169932, 17152.48542543726, 17366.82633173652, 17394.304227830744, 17516.91241543784, 17519.386821461696, 17599.560010999314, 10536.157107431234, 10592.8940710595, 10675.89324106749, 10706.571737130427, 10719.678409647722, 13058.869411305765, 13161.60515184546, 13294.20234785916, 13307.135036222615, 13323.0, 10581.62964296246, 10681.359118452918, 10685.465726713788, 10843.457827108456, 10908.181886358714, 10515.789684206426, 10526.578936842441, 10604.787904242026, 10623.203259755704, 10630.25588208818, 9410.482423466501, 9609.9039009609, 9612.951935240279, 9616.182624476998, 9618.759531011781, 7142.5714457132735, 7142.750003749844, 7170.103737032871, 7234.74678386254, 7254.419646428168, 7398.926010739824, 7443.441741869273, 7498.550086994798, 7514.774556763306, 7536.1333446654335], "value": 6396.781309629836, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.83121654245826, 508.48642870700627, 522.4096770649165, 528.9603279754018, 533.8451848963798, 6154.938450615493, 6171.9382806171925, 6176.598521096125, 6215.658138802364, 6267.623942563449, 6078.361772013941, 6101.420365065323, 6120.47975922046, 6169.691515424233, 6180.2583689957155, 4802.807887684488, 4953.405591329046, 4987.576056035241, 5036.194208926563, 5038.84883453497, 4968.807486203321, 4969.428515720684, 4972.179590367585, 4979.705276628067, 5000.549950504454, 4955.628327875405, 4956.3804524434445, 4959.677620954644, 4971.552560269575, 4995.925061124086, 4927.457979622239, 4962.851114466572, 4963.181075122637, 4977.701337919718, 4999.450060493343, 4489.169503641804, 4489.518458908585, 4496.820127194908, 4503.684742068057, 4530.161920044802, 5931.0, 5969.820905372802, 5987.461128498456, 6011.0, 6013.939860601382, 5592.664440133605, 5616.52259557938, 5655.462731040545, 5659.094544872801, 5705.0, 6423.582467139668, 6454.515911306671, 6501.512386571026, 6503.739850405978, 6517.543771935966, 4826.0830442215865, 4834.3473631059705, 4846.36997190363, 4872.269159626049, 4873.975630121862, 6520.836979075513, 6675.399214070757, 6716.697748601374, 6809.727610895558, 6911.067005954182, 5326.733663316819, 5427.402985671573, 5453.318335208099, 5455.645383050089, 5462.880109577527, 5481.123020316731, 5517.99269398179, 5541.639793413416, 5564.4991950724625, 5571.052921003439, 5891.823245302648, 5981.760729570856, 5993.430624090704, 5995.340512543617, 5996.790112346134, 6565.409113179837, 6583.539152259392, 6588.4729221662155, 6602.7689030883685, 6628.376047868303, 1894.602133551942, 1949.2300541286397, 1960.9117589708496, 1965.862389632712, 1969.9606007879763, 1401.6425811418023, 1425.2588653899913, 1429.171080773149, 1441.740486712402, 1476.247113971882, 5647.915281270784, 5652.576056795757, 5711.857203569944, 5728.684922329312, 5741.45456181662, 6504.0, 6526.4452521536205, 6573.244076931214, 6595.8021259362295, 6605.70274337656, 5636.689982050944, 5674.886502270014, 5702.517345490202, 5707.828765136969, 5757.453041961091, 3496.230829217568, 3510.4734289856724, 3517.577890653138, 3528.4883691865048, 3532.187596852687, 6361.872762544724, 6375.139356186855, 6406.455451286693, 6406.67966601668, 6472.223333199952, 6740.62926539045, 6811.182658080963, 6841.863162736817, 6900.274931267183, 6906.412954898789, 6425.361532809197, 6469.417752402306, 6478.578892371981, 6482.611043337415, 6490.318516555693, 6629.370209830058, 6642.43539299165, 6645.833854153491, 6659.700313485808, 6704.329567043452, 6477.838054048687, 6489.4808415325715, 6590.94544872818, 6621.9337806621315, 6656.7670131545165, 6378.712957916998, 6389.616623002635, 6409.230892292955, 6440.264646504922, 6447.355264473703, 5389.8113566025, 5425.674459532441, 5435.510804027656, 5441.809536666356, 5462.562994960314, 6380.138681278154, 6527.281998980204, 6539.509536784665, 6631.916158986095, 6635.834104147435, 6322.620642761449, 6373.330800265905, 6393.904091438539, 6428.0, 6458.773942911976, 6316.494680425647, 6428.935710643021, 6437.0, 6443.291237963914, 6462.321456247027, 6298.826904717865, 6344.777932772331, 6349.14286571307, 6397.232332119989, 6429.935700642934, 6400.45596124316, 6459.709313081016, 6486.416222539994, 6487.9026814598765, 6535.771248006297, 6172.321044684992, 6210.347913469202, 6255.374462553708, 6301.684915754286, 6311.96844015777], "value": 5094.80705570907, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [906.5421961909236, 924.2467389077901, 925.2459245714742, 933.2953620016887, 948.7011591348725, 18111.184996675147, 19065.0, 19067.523311917208, 19069.14188861501, 19098.522536936583, 18682.346117885878, 19023.0, 19191.712124318146, 19211.615767684638, 19238.807611923876, 1037.1858091398253, 1051.0698032241444, 1057.9259451838375, 1060.867391576053, 1062.044160255772, 966.0, 1050.3435352904435, 1050.3487837540736, 1051.9684409467727, 1067.6049861551198, 1104.4256986367084, 1107.0258172807917, 1107.3466654673741, 1109.8557187565646, 1114.9275297105662, 1100.2628239079836, 1105.9612913548028, 1106.905912997392, 1107.5126944144552, 1116.4640972333284, 803.8030682482771, 805.3355981315439, 806.1817255485687, 808.4300568099476, 817.6606708216083, 18016.099195040195, 18032.729509057244, 18048.458546243633, 18634.81365186358, 19499.122539485757, 21416.0, 21426.5714685707, 22095.558088838294, 22114.77885221143, 22342.0, 15142.242887855673, 15266.542003739794, 15520.301586428637, 15545.0, 16173.867829251956, 3581.7492775505725, 3604.098975256186, 3605.188832512664, 3617.7467577269604, 3629.4555816627717, 125958.0, 126254.5309101821, 127628.0, 129461.0, 130943.0, 15967.36130554788, 16446.177691115634, 16508.83491165097, 16578.08820514859, 16641.251143698562, 31078.68921310803, 31600.0, 31627.36745265082, 31678.683213168035, 32320.676793232236, 7808.726694565777, 7822.882656760039, 7830.8433831323055, 7842.686292548348, 7902.328302094272, 3641.10792855752, 3714.7399682022, 4025.456563363967, 4169.041120542292, 4194.370844373308, 5284.4187139414635, 5373.194020896896, 5398.838034858961, 5401.459854014567, 5403.9459605403445, 1044.5926088825365, 1076.7092884921028, 1087.065123993365, 1100.1638754546443, 1101.658485869383, 644.6196743921109, 655.1090516897016, 657.9638119903356, 670.2560158224367, 689.9068625735462, 12373.195742276723, 12374.555195713858, 12434.440450179764, 12558.120931534888, 13350.198988060747, 673.9865202695919, 685.7325642999234, 694.7394726977384, 697.5396238482679, 714.610537257196, 5708.229389032427, 5717.656940583495, 5822.388649191774, 5879.882402351929, 5943.465088142088, 116393.83606163999, 116624.41687791506, 117142.0, 117898.0, 118307.0, 94893.0, 95163.0, 97098.0, 97956.51021744846, 99118.5044074775, 83168.0, 84834.15165848385, 85599.5720021396, 86527.0, 87390.0, 11188.888111118784, 11469.139814514112, 11496.367699776592, 11504.769904602028, 11634.825477618011, 37354.252914941004, 37401.81299093487, 37501.0, 38761.0, 39246.21507569776, 48803.75598121987, 49604.75197624133, 49663.50336496589, 51692.74153629208, 52000.95998079942, 16187.10970896612, 16299.511014669104, 16473.67052658964, 16498.67002659964, 16622.085785281924, 18147.54631134232, 21654.8917255419, 21687.891560542095, 21695.783042169376, 22312.10751569919, 18852.622947541247, 19204.327848525234, 19207.0, 19515.414537563895, 19574.314898978468, 19201.80798192056, 19729.506762330482, 19836.50408739793, 19883.60232795365, 20091.296804611768, 18326.358577449726, 19080.522986925436, 19129.23483060662, 19191.808081919004, 19286.0, 14283.500077497238, 14661.560153195422, 14700.191489468181, 14708.117512949257, 14758.926205368904, 14648.633784155481, 14669.0, 14770.261486925396, 14868.925655371653, 15080.0], "value": 9135.933893044754, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1532.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 101.0, 109.0, 107.0, 116.0, 111.0, 101.0, 110.0, 112.0], "value": 107.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 119.0, 144.0, 172.0, 120.0, 145.0, 142.0, 118.0, 177.0, 120.0], "value": 140.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 161.0, 161.0, 163.0, 162.0, 167.0, 167.0, 165.0], "value": 163.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 182.0, 75.0, 77.0, 77.0, 74.0, 152.0, 78.0], "value": 93.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 126.0, 79.0, 80.0, 79.0, 134.0, 80.0, 78.0, 126.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 117.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 116.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 151.0, 149.0, 150.0, 150.0, 151.0, 150.0, 151.0, 152.0, 150.0], "value": 151.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 57.0, 55.0, 56.0, 55.0, 55.0, 55.0, 55.0, 55.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 54.0, 54.0, 53.0, 54.0, 54.0, 53.0, 53.0], "value": 53.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 81.0, 90.0, 96.0, 87.0, 79.0, 88.0, 89.0, 77.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 132.0, 122.0, 122.0, 129.0, 134.0, 126.0, 138.0, 124.0, 164.0], "value": 132.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 171.0, 176.0, 178.0, 181.0, 180.0, 171.0, 180.0, 190.0, 175.0], "value": 177.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 69.0, 68.0, 67.0, 67.0, 68.0, 67.0, 68.0, 67.0, 66.0], "value": 67.9, "unit": "score"}]}]} 16:49:20 INFO - Return code: 0 16:49:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:49:20 INFO - # TBPL SUCCESS # 16:49:20 INFO - Running post-action listener: _package_coverage_data 16:49:20 INFO - Running post-action listener: _resource_record_post_action 16:49:20 INFO - [mozharness: 2017-08-09 23:49:20.462438Z] Finished run-tests step (success) 16:49:20 INFO - Running post-run listener: _resource_record_post_run 16:49:20 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 439988224; Read time: 12; Write time: 231760 16:49:20 INFO - TinderboxPrint: CPU usage
13.1% 16:49:20 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:49:20 INFO - TinderboxPrint: I/O write bytes / time
439,988,224 / 231,760 16:49:20 INFO - TinderboxPrint: CPU idle
8,262.4 (86.9%) 16:49:20 INFO - TinderboxPrint: CPU user
1,201.3 (12.6%) 16:49:20 INFO - TinderboxPrint: Swap in / out
0 / 0 16:49:20 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 113618944; Read time: 0; Write time: 82264 16:49:20 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:49:20 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 326369280; Read time: 0; Write time: 149496 16:49:21 INFO - Running post-run listener: _upload_blobber_files 16:49:21 INFO - Blob upload gear active. 16:49:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:49:21 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 16:49:21 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'] 16:49:21 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 16:49:21 INFO - (blobuploader) - INFO - Open directory for files ... 16:49:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:49:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:49:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:27 INFO - (blobuploader) - INFO - Done attempting. 16:49:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:49:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:49:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:28 INFO - (blobuploader) - INFO - Done attempting. 16:49:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:49:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:49:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:29 INFO - (blobuploader) - INFO - Done attempting. 16:49:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:49:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:49:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:30 INFO - (blobuploader) - INFO - Done attempting. 16:49:30 INFO - (blobuploader) - INFO - Iteration through files over. 16:49:30 INFO - Return code: 0 16:49:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:49:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:49:30 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d4f43875da69093ef0913ceaca8aa6907b83ebe3ab3459dd23e83921eb735f3d4ebc527d3437a93d837cdb965fbe3fd11ea007fed0bdcaac8c9ec9a3a89f0a27", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d761fbc8b12c62adae3a7928ec77db20af63eee6e33c00975a30b15c48a705e7fd67cbfab873cb13309b7e8bf2e5f1a9ebbbfccaebdbdd32e69a9951a3d5b60c", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9759e1e54603a273ba1da3481ddb0a91c0e26af09d8aa627b66524def5effe7207702f8d887e5f74c3ef70fe76b17e22a558ef758cd9825ba8299922984806ec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04d74ebd203ad97264ae35dd048ab27abb315cd0187fe22271e387c98c0bb9a1ae060168177fea8665384f7807f0947e6ae756f20be25aad9ddb1653e38d8b79"} 16:49:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:49:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:49:30 INFO - Contents: 16:49:30 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d4f43875da69093ef0913ceaca8aa6907b83ebe3ab3459dd23e83921eb735f3d4ebc527d3437a93d837cdb965fbe3fd11ea007fed0bdcaac8c9ec9a3a89f0a27", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d761fbc8b12c62adae3a7928ec77db20af63eee6e33c00975a30b15c48a705e7fd67cbfab873cb13309b7e8bf2e5f1a9ebbbfccaebdbdd32e69a9951a3d5b60c", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9759e1e54603a273ba1da3481ddb0a91c0e26af09d8aa627b66524def5effe7207702f8d887e5f74c3ef70fe76b17e22a558ef758cd9825ba8299922984806ec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04d74ebd203ad97264ae35dd048ab27abb315cd0187fe22271e387c98c0bb9a1ae060168177fea8665384f7807f0947e6ae756f20be25aad9ddb1653e38d8b79"} 16:49:30 INFO - Running post-run listener: copy_logs_to_upload_dir 16:49:30 INFO - Copying logs to upload dir... 16:49:30 INFO - mkdir: /builds/slave/test/build/upload/logs 16:49:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.911369 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-09 16:49:30.219960) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-09 16:49:30.224197) ========= 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=31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d4f43875da69093ef0913ceaca8aa6907b83ebe3ab3459dd23e83921eb735f3d4ebc527d3437a93d837cdb965fbe3fd11ea007fed0bdcaac8c9ec9a3a89f0a27", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d761fbc8b12c62adae3a7928ec77db20af63eee6e33c00975a30b15c48a705e7fd67cbfab873cb13309b7e8bf2e5f1a9ebbbfccaebdbdd32e69a9951a3d5b60c", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9759e1e54603a273ba1da3481ddb0a91c0e26af09d8aa627b66524def5effe7207702f8d887e5f74c3ef70fe76b17e22a558ef758cd9825ba8299922984806ec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04d74ebd203ad97264ae35dd048ab27abb315cd0187fe22271e387c98c0bb9a1ae060168177fea8665384f7807f0947e6ae756f20be25aad9ddb1653e38d8b79"} build_url:https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011183 build_url: 'https://queue.taskcluster.net/v1/task/fgg_B4utSheUt0nkQmwdwQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d4f43875da69093ef0913ceaca8aa6907b83ebe3ab3459dd23e83921eb735f3d4ebc527d3437a93d837cdb965fbe3fd11ea007fed0bdcaac8c9ec9a3a89f0a27", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d761fbc8b12c62adae3a7928ec77db20af63eee6e33c00975a30b15c48a705e7fd67cbfab873cb13309b7e8bf2e5f1a9ebbbfccaebdbdd32e69a9951a3d5b60c", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9759e1e54603a273ba1da3481ddb0a91c0e26af09d8aa627b66524def5effe7207702f8d887e5f74c3ef70fe76b17e22a558ef758cd9825ba8299922984806ec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04d74ebd203ad97264ae35dd048ab27abb315cd0187fe22271e387c98c0bb9a1ae060168177fea8665384f7807f0947e6ae756f20be25aad9ddb1653e38d8b79"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-09 16:49:30.252434) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 16:49:30.252825) ========= 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=31acecf42b6a79ec49471d4a000001bb-1502321139.60827-1149999020 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004530 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 16:49:30.335007) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-09 16:49:30.335369) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-09 16:49:30.335719) ========= ========= Total master_lag: 0.22 =========