builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-058 starttime: 1502416616.39 results: success (0) revision: 253a8560dc34456d2e8a13065e4b3eb5ecf6704f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-10 18:56:56.390445) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-10 18:56:56.390845) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-10 18:56:56.391144) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-10 18:56:56.413653) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 18:56:56.413918) ========= 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=0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-10 18:56:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.5M=0s 2017-08-10 18:56:56 (69.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139067 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 18:56:56.566076) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 18:56:56.566385) ========= 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=0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015953 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 18:56:56.617924) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-10 18:56:56.620291) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 253a8560dc34456d2e8a13065e4b3eb5ecf6704f --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 253a8560dc34456d2e8a13065e4b3eb5ecf6704f --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=0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-10 18:56:56,683 truncating revision to first 12 chars 2017-08-10 18:56:56,683 Setting DEBUG logging. 2017-08-10 18:56:56,683 attempt 1/10 2017-08-10 18:56:56,683 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/253a8560dc34?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-10 18:56:57,664 attempt 1/10 2017-08-10 18:56:58,251 current task status: no status available at this point. state: STARTED 2017-08-10 18:56:58,251 sleeping for 10.00s (attempt 1/10) 2017-08-10 18:57:08,262 attempt 2/10 2017-08-10 18:57:08,700 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-10 18:57:08,937 unpacking tar archive at: mozilla-central-253a8560dc34/testing/mozharness/ program finished with exit code 0 elapsedTime=12.543355 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-10 18:57:09.175401) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-10 18:57:09.175746) ========= script_repo_revision: 253a8560dc34456d2e8a13065e4b3eb5ecf6704f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-10 18:57:09.179154) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-10 18:57:09.179431) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-10 18:57:09.192371) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-08-10 18:57:09.192733) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:57:09 INFO - MultiFileLogger online at 20170810 18:57:09 in /builds/slave/test 18:57:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:57:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:57:09 INFO - {'append_to_log': False, 18:57:09 INFO - 'base_work_dir': '/builds/slave/test', 18:57:09 INFO - 'blob_upload_branch': 'Firefox', 18:57:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:57:09 INFO - 'branch': 'Firefox', 18:57:09 INFO - 'buildbot_json_path': 'buildprops.json', 18:57:09 INFO - 'code_coverage': False, 18:57:09 INFO - 'config_files': ('talos/linux_config.py',), 18:57:09 INFO - 'default_actions': ('clobber', 18:57:09 INFO - 'read-buildbot-config', 18:57:09 INFO - 'download-and-extract', 18:57:09 INFO - 'populate-webroot', 18:57:09 INFO - 'create-virtualenv', 18:57:09 INFO - 'install', 18:57:09 INFO - 'setup-mitmproxy', 18:57:09 INFO - 'run-tests'), 18:57:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:57:09 INFO - 'disable_ccov_upload': False, 18:57:09 INFO - 'download_minidump_stackwalk': True, 18:57:09 INFO - 'download_symbols': 'ondemand', 18:57:09 INFO - 'enable_stylo': False, 18:57:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:57:09 INFO - 'tooltool.py': '/tools/tooltool.py', 18:57:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:57:09 INFO - '/tools/misc-python/virtualenv.py')}, 18:57:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:57:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:57:09 INFO - 'gecko_profile': False, 18:57:09 INFO - 'gecko_profile_interval': 0, 18:57:09 INFO - 'installer_path': 'installer.exe', 18:57:09 INFO - 'log_level': 'info', 18:57:09 INFO - 'log_name': 'talos', 18:57:09 INFO - 'log_to_console': True, 18:57:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:57:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:57:09 INFO - 'opt_config_files': (), 18:57:09 INFO - 'pip_index': False, 18:57:09 INFO - 'suite': 'dromaeojs-e10s', 18:57:09 INFO - 'system_bits': '32', 18:57:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:57:09 INFO - 'title': 'talos-linux64-ix-058', 18:57:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:57:09 INFO - 'use_talos_json': True, 18:57:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:57:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:57:09 INFO - 'work_dir': 'build'} 18:57:09 INFO - [mozharness: 2017-08-11 01:57:09.361902Z] Running clobber step. 18:57:09 INFO - Running pre-action listener: _resource_record_pre_action 18:57:09 INFO - Running main action method: clobber 18:57:09 INFO - rmtree: /builds/slave/test/build 18:57:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:57:10 INFO - Running post-action listener: _resource_record_post_action 18:57:10 INFO - [mozharness: 2017-08-11 01:57:10.397933Z] Finished clobber step (success) 18:57:10 INFO - [mozharness: 2017-08-11 01:57:10.398036Z] Running read-buildbot-config step. 18:57:10 INFO - Running pre-action listener: _resource_record_pre_action 18:57:10 INFO - Running main action method: read_buildbot_config 18:57:10 INFO - Using buildbot properties: 18:57:10 INFO - { 18:57:10 INFO - "project": "", 18:57:10 INFO - "product": "firefox", 18:57:10 INFO - "who": "kwierso@gmail.com", 18:57:10 INFO - "installer_path": "public/build/target.tar.bz2", 18:57:10 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 18:57:10 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:57:10 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 18:57:10 INFO - "stage_platform": "linux64-stylo", 18:57:10 INFO - "basedir": "/builds/slave/test", 18:57:10 INFO - "buildnumber": 76, 18:57:10 INFO - "platform": "ubuntu64_hw_stylo", 18:57:10 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:57:10 INFO - "slavebuilddir": "test", 18:57:10 INFO - "taskId": "JyxoUjp8QDWA9VNuyJfb-Q", 18:57:10 INFO - "branch": "mozilla-central", 18:57:10 INFO - "script_repo_revision": "production", 18:57:10 INFO - "revision": "253a8560dc34456d2e8a13065e4b3eb5ecf6704f", 18:57:10 INFO - "slavename": "talos-linux64-ix-058", 18:57:10 INFO - "repo_path": "mozilla-central" 18:57:10 INFO - } 18:57:10 INFO - Finding installer, test and symbols from parent task. 18:57:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JyxoUjp8QDWA9VNuyJfb-Q'}, attempt #1 18:57:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JyxoUjp8QDWA9VNuyJfb-Q'}, attempt #1 18:57:11 INFO - Task dependencies: cNUPDWBqR-u0RSojCR1X9w 18:57:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w'}, attempt #1 18:57:11 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.tar.bz2 18:57:11 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.test_packages.json 18:57:11 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.crashreporter-symbols.zip 18:57:11 INFO - Running post-action listener: _resource_record_post_action 18:57:11 INFO - [mozharness: 2017-08-11 01:57:11.778622Z] Finished read-buildbot-config step (success) 18:57:11 INFO - [mozharness: 2017-08-11 01:57:11.778843Z] Running download-and-extract step. 18:57:11 INFO - Running pre-action listener: _resource_record_pre_action 18:57:11 INFO - Running main action method: download_and_extract 18:57:11 INFO - mkdir: /builds/slave/test/build/tests 18:57:11 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')]} 18:57:11 INFO - trying https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.test_packages.json 18:57:11 INFO - Downloading https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:57:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:57:12 INFO - Downloaded 989 bytes. 18:57:12 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:57:12 INFO - Using the following test package requirements: 18:57:12 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:57:12 INFO - u'common': [u'target.common.tests.zip'], 18:57:12 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:57:12 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:57:12 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:57:12 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:57:12 INFO - u'mozbase': [u'target.common.tests.zip'], 18:57:12 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:57:12 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:57:12 INFO - u'web-platform': [u'target.common.tests.zip', 18:57:12 INFO - u'target.web-platform.tests.tar.gz'], 18:57:12 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:57:12 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:57:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.common.tests.zip 18:57:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:57:12 INFO - Fetch https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.common.tests.zip into memory 18:57:14 INFO - Content-Length response header: 54036061 18:57:14 INFO - Bytes received: 54036061 18:57:25 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:57:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.common.tests.zip 18:57:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:57:25 INFO - Fetch https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.common.tests.zip into memory 18:57:26 INFO - Content-Length response header: 54036061 18:57:26 INFO - Bytes received: 54036061 18:57:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.talos.tests.zip 18:57:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:57:39 INFO - Fetch https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.talos.tests.zip into memory 18:57:39 INFO - Content-Length response header: 14496050 18:57:39 INFO - Bytes received: 14496050 18:57:40 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')]} 18:57:40 INFO - trying https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.tar.bz2 18:57:40 INFO - Downloading https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:57:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:57:41 INFO - Downloaded 61837736 bytes. 18:57:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.tar.bz2 18:57:41 INFO - mkdir: /builds/slave/test/properties 18:57:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:57:41 INFO - Writing to file /builds/slave/test/properties/build_url 18:57:41 INFO - Contents: 18:57:41 INFO - build_url:https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.tar.bz2 18:57:41 INFO - Running post-action listener: _resource_record_post_action 18:57:41 INFO - Running post-action listener: set_extra_try_arguments 18:57:41 INFO - [mozharness: 2017-08-11 01:57:41.266841Z] Finished download-and-extract step (success) 18:57:41 INFO - [mozharness: 2017-08-11 01:57:41.266939Z] Running populate-webroot step. 18:57:41 INFO - Running pre-action listener: _resource_record_pre_action 18:57:41 INFO - Running main action method: populate_webroot 18:57:41 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:57:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:57:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:57:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:57:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:57:41 INFO - 'tests': ['damp', 'tps']}, 18:57:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:57:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:57:41 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:57:41 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:57:41 INFO - 'other-e10s': {'tests': ['a11yr', 18:57:41 INFO - 'ts_paint', 18:57:41 INFO - 'tpaint', 18:57:41 INFO - 'sessionrestore', 18:57:41 INFO - 'sessionrestore_many_windows', 18:57:41 INFO - 'sessionrestore_no_auto_restore', 18:57:41 INFO - 'tabpaint']}, 18:57:41 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:57:41 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:57:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:57:41 INFO - 'tsvgr_opacity', 18:57:41 INFO - 'tart', 18:57:41 INFO - 'tscrollx', 18:57:41 INFO - 'tsvg_static']}, 18:57:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:57:41 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:57:41 INFO - 'talos_options': ['--mitmproxy', 18:57:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:57:41 INFO - '--firstNonBlankPaint'], 18:57:41 INFO - 'tests': ['tp6_google', 18:57:41 INFO - 'tp6_youtube', 18:57:41 INFO - 'tp6_amazon', 18:57:41 INFO - 'tp6_facebook']}, 18:57:41 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:57:41 INFO - 'talos_options': ['--stylo', 18:57:41 INFO - '--mitmproxy', 18:57:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:57:41 INFO - '--firstNonBlankPaint'], 18:57:41 INFO - 'tests': ['tp6_google', 18:57:41 INFO - 'tp6_youtube', 18:57:41 INFO - 'tp6_amazon', 18:57:41 INFO - 'tp6_facebook']}, 18:57:41 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:57:41 INFO - 'talos_options': ['--stylo', 18:57:41 INFO - '--stylo-threads=1', 18:57:41 INFO - '--mitmproxy', 18:57:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:57:41 INFO - '--firstNonBlankPaint'], 18:57:41 INFO - 'tests': ['tp6_google', 18:57:41 INFO - 'tp6_youtube', 18:57:41 INFO - 'tp6_amazon', 18:57:41 INFO - 'tp6_facebook']}, 18:57:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:57:41 INFO - 'talos_options': ['--xperf_path', 18:57:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:57:41 INFO - 'tests': ['tp5n']}}} 18:57:41 INFO - Running post-action listener: _resource_record_post_action 18:57:41 INFO - [mozharness: 2017-08-11 01:57:41.275302Z] Finished populate-webroot step (success) 18:57:41 INFO - [mozharness: 2017-08-11 01:57:41.275393Z] Running create-virtualenv step. 18:57:41 INFO - Running pre-action listener: _resource_record_pre_action 18:57:41 INFO - Running main action method: create_virtualenv 18:57:41 INFO - Creating virtualenv /builds/slave/test/build/venv 18:57:41 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 18:57:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:57:41 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:57:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:57:41 INFO - Using real prefix '/usr' 18:57:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:57:41 INFO - Installing distribute.............................................................................................................................................................................................done. 18:57:43 INFO - Installing pip.................done. 18:57:43 INFO - Return code: 0 18:57:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:57:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:57:44 INFO - Reading from file tmpfile_stdout 18:57:44 INFO - Output received: 18:57:44 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) 18:57:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:57:44 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')]} 18:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:44 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')]} 18:57:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:44 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 0x211b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24878a0>, '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 0x24887a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2488c30>, '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': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', '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 18:57:44 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 18:57:44 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 18:57:44 INFO - Using env: {'DISPLAY': ':0', 18:57:44 INFO - 'HOME': '/home/cltbld', 18:57:44 INFO - 'LANG': 'en_US.UTF-8', 18:57:44 INFO - 'LANGUAGE': 'en_US:en', 18:57:44 INFO - 'LOGNAME': 'cltbld', 18:57:44 INFO - 'MAIL': '/var/mail/cltbld', 18:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:44 INFO - 'MOZ_NO_REMOTE': '1', 18:57:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:57:44 INFO - 'NO_EM_RESTART': '1', 18:57:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:57:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:44 INFO - 'PWD': '/builds/slave/test', 18:57:44 INFO - 'SHELL': '/bin/bash', 18:57:44 INFO - 'SHLVL': '1', 18:57:44 INFO - 'TERM': 'linux', 18:57:44 INFO - 'TMOUT': '86400', 18:57:44 INFO - 'USER': 'cltbld', 18:57:44 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', 18:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:44 INFO - '_': '/tools/buildbot/bin/python'} 18:57:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:44 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 18:57:44 INFO - Cleaning up... 18:57:44 INFO - Return code: 0 18:57:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:57:44 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')]} 18:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:44 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')]} 18:57:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:44 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 0x211b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24878a0>, '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 0x24887a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2488c30>, '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': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', '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 18:57:44 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 18:57:44 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 18:57:44 INFO - Using env: {'DISPLAY': ':0', 18:57:44 INFO - 'HOME': '/home/cltbld', 18:57:44 INFO - 'LANG': 'en_US.UTF-8', 18:57:44 INFO - 'LANGUAGE': 'en_US:en', 18:57:44 INFO - 'LOGNAME': 'cltbld', 18:57:44 INFO - 'MAIL': '/var/mail/cltbld', 18:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:44 INFO - 'MOZ_NO_REMOTE': '1', 18:57:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:57:44 INFO - 'NO_EM_RESTART': '1', 18:57:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:57:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:44 INFO - 'PWD': '/builds/slave/test', 18:57:44 INFO - 'SHELL': '/bin/bash', 18:57:44 INFO - 'SHLVL': '1', 18:57:44 INFO - 'TERM': 'linux', 18:57:44 INFO - 'TMOUT': '86400', 18:57:44 INFO - 'USER': 'cltbld', 18:57:44 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', 18:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:44 INFO - '_': '/tools/buildbot/bin/python'} 18:57:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:44 INFO - Downloading/unpacking psutil>=3.1.1 18:57:44 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 18:57:44 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 18:57:44 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 18:57:44 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 18:57:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:57:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:57:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:57:45 INFO - Installing collected packages: psutil 18:57:45 INFO - Running setup.py install for psutil 18:57:45 INFO - building 'psutil._psutil_linux' extension 18:57:45 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:57:45 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 18:57:45 INFO - building 'psutil._psutil_posix' extension 18:57:45 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 18:57:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:57:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:57:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:57:46 INFO - Successfully installed psutil 18:57:46 INFO - Cleaning up... 18:57:46 INFO - Return code: 0 18:57:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:57:46 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')]} 18:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:46 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')]} 18:57:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x211b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24878a0>, '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 0x24887a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2488c30>, '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': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', '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 18:57:46 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 18:57:46 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 18:57:46 INFO - Using env: {'DISPLAY': ':0', 18:57:46 INFO - 'HOME': '/home/cltbld', 18:57:46 INFO - 'LANG': 'en_US.UTF-8', 18:57:46 INFO - 'LANGUAGE': 'en_US:en', 18:57:46 INFO - 'LOGNAME': 'cltbld', 18:57:46 INFO - 'MAIL': '/var/mail/cltbld', 18:57:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:46 INFO - 'MOZ_NO_REMOTE': '1', 18:57:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:57:46 INFO - 'NO_EM_RESTART': '1', 18:57:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:57:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:46 INFO - 'PWD': '/builds/slave/test', 18:57:46 INFO - 'SHELL': '/bin/bash', 18:57:46 INFO - 'SHLVL': '1', 18:57:46 INFO - 'TERM': 'linux', 18:57:46 INFO - 'TMOUT': '86400', 18:57:46 INFO - 'USER': 'cltbld', 18:57:46 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', 18:57:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:46 INFO - '_': '/tools/buildbot/bin/python'} 18:57:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:57:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:57:47 INFO - Installing collected packages: mozsystemmonitor 18:57:47 INFO - Running setup.py install for mozsystemmonitor 18:57:47 INFO - Successfully installed mozsystemmonitor 18:57:47 INFO - Cleaning up... 18:57:47 INFO - Return code: 0 18:57:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:57:47 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')]} 18:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:47 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')]} 18:57:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:47 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 0x211b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24878a0>, '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 0x24887a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2488c30>, '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': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', '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 18:57:47 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 18:57:47 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 18:57:47 INFO - Using env: {'DISPLAY': ':0', 18:57:47 INFO - 'HOME': '/home/cltbld', 18:57:47 INFO - 'LANG': 'en_US.UTF-8', 18:57:47 INFO - 'LANGUAGE': 'en_US:en', 18:57:47 INFO - 'LOGNAME': 'cltbld', 18:57:47 INFO - 'MAIL': '/var/mail/cltbld', 18:57:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:47 INFO - 'MOZ_NO_REMOTE': '1', 18:57:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:57:47 INFO - 'NO_EM_RESTART': '1', 18:57:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:57:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:47 INFO - 'PWD': '/builds/slave/test', 18:57:47 INFO - 'SHELL': '/bin/bash', 18:57:47 INFO - 'SHLVL': '1', 18:57:47 INFO - 'TERM': 'linux', 18:57:47 INFO - 'TMOUT': '86400', 18:57:47 INFO - 'USER': 'cltbld', 18:57:47 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', 18:57:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:47 INFO - '_': '/tools/buildbot/bin/python'} 18:57:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:47 INFO - Downloading/unpacking jsonschema==2.5.1 18:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:57:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:49 INFO - Downloading functools32-3.2.3-2.tar.gz 18:57:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:57:49 INFO - warning: no files found matching '*.txt' 18:57:49 INFO - no previously-included directories found matching 'build' 18:57:49 INFO - no previously-included directories found matching 'dist' 18:57:49 INFO - no previously-included directories found matching '.git*' 18:57:49 INFO - Installing collected packages: jsonschema, functools32 18:57:49 INFO - Running setup.py install for functools32 18:57:49 INFO - warning: no files found matching '*.txt' 18:57:49 INFO - no previously-included directories found matching 'build' 18:57:49 INFO - no previously-included directories found matching 'dist' 18:57:49 INFO - no previously-included directories found matching '.git*' 18:57:49 INFO - Successfully installed jsonschema functools32 18:57:49 INFO - Cleaning up... 18:57:49 INFO - Return code: 0 18:57:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:57:49 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')]} 18:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:49 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')]} 18:57:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:49 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 0x211b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24878a0>, '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 0x24887a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2488c30>, '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': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', '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 18:57:49 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 18:57:49 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 18:57:49 INFO - Using env: {'DISPLAY': ':0', 18:57:49 INFO - 'HOME': '/home/cltbld', 18:57:49 INFO - 'LANG': 'en_US.UTF-8', 18:57:49 INFO - 'LANGUAGE': 'en_US:en', 18:57:49 INFO - 'LOGNAME': 'cltbld', 18:57:49 INFO - 'MAIL': '/var/mail/cltbld', 18:57:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:49 INFO - 'MOZ_NO_REMOTE': '1', 18:57:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:57:49 INFO - 'NO_EM_RESTART': '1', 18:57:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:57:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:49 INFO - 'PWD': '/builds/slave/test', 18:57:49 INFO - 'SHELL': '/bin/bash', 18:57:49 INFO - 'SHLVL': '1', 18:57:49 INFO - 'TERM': 'linux', 18:57:49 INFO - 'TMOUT': '86400', 18:57:49 INFO - 'USER': 'cltbld', 18:57:49 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', 18:57:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:49 INFO - '_': '/tools/buildbot/bin/python'} 18:57:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:57:49 INFO - Cleaning up... 18:57:49 INFO - Return code: 0 18:57:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:57:49 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')]} 18:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:49 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')]} 18:57:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:49 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 0x211b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24878a0>, '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 0x24887a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2488c30>, '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': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', '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 18:57:49 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 18:57:49 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 18:57:49 INFO - Using env: {'DISPLAY': ':0', 18:57:49 INFO - 'HOME': '/home/cltbld', 18:57:49 INFO - 'LANG': 'en_US.UTF-8', 18:57:49 INFO - 'LANGUAGE': 'en_US:en', 18:57:49 INFO - 'LOGNAME': 'cltbld', 18:57:49 INFO - 'MAIL': '/var/mail/cltbld', 18:57:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:49 INFO - 'MOZ_NO_REMOTE': '1', 18:57:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:57:49 INFO - 'NO_EM_RESTART': '1', 18:57:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:57:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:49 INFO - 'PWD': '/builds/slave/test', 18:57:49 INFO - 'SHELL': '/bin/bash', 18:57:49 INFO - 'SHLVL': '1', 18:57:49 INFO - 'TERM': 'linux', 18:57:49 INFO - 'TMOUT': '86400', 18:57:49 INFO - 'USER': 'cltbld', 18:57:49 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', 18:57:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:49 INFO - '_': '/tools/buildbot/bin/python'} 18:57:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:49 INFO - Downloading/unpacking blobuploader==1.2.4 18:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:50 INFO - Downloading blobuploader-1.2.4.tar.gz 18:57:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:57:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:57:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:51 INFO - Downloading docopt-0.6.1.tar.gz 18:57:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:57:51 INFO - Installing collected packages: blobuploader, requests, docopt 18:57:51 INFO - Running setup.py install for blobuploader 18:57:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:57:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:57:51 INFO - Running setup.py install for requests 18:57:52 INFO - Running setup.py install for docopt 18:57:52 INFO - Successfully installed blobuploader requests docopt 18:57:52 INFO - Cleaning up... 18:57:52 INFO - Return code: 0 18:57:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:57:52 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')]} 18:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:52 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')]} 18:57:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:52 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 0x211b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24878a0>, '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 0x24887a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2488c30>, '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': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', '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 18:57:52 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 18:57:52 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 18:57:52 INFO - Using env: {'DISPLAY': ':0', 18:57:52 INFO - 'HOME': '/home/cltbld', 18:57:52 INFO - 'LANG': 'en_US.UTF-8', 18:57:52 INFO - 'LANGUAGE': 'en_US:en', 18:57:52 INFO - 'LOGNAME': 'cltbld', 18:57:52 INFO - 'MAIL': '/var/mail/cltbld', 18:57:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:52 INFO - 'MOZ_NO_REMOTE': '1', 18:57:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:57:52 INFO - 'NO_EM_RESTART': '1', 18:57:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:57:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:52 INFO - 'PWD': '/builds/slave/test', 18:57:52 INFO - 'SHELL': '/bin/bash', 18:57:52 INFO - 'SHLVL': '1', 18:57:52 INFO - 'TERM': 'linux', 18:57:52 INFO - 'TMOUT': '86400', 18:57:52 INFO - 'USER': 'cltbld', 18:57:52 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', 18:57:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:52 INFO - '_': '/tools/buildbot/bin/python'} 18:57:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:57:52 INFO - Running setup.py (path:/tmp/pip-KgStNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:57:52 INFO - Running setup.py (path:/tmp/pip-9GoegB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:57:52 INFO - Running setup.py (path:/tmp/pip-xHMwfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:57:52 INFO - Running setup.py (path:/tmp/pip-evh8mw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:57:52 INFO - Running setup.py (path:/tmp/pip-2h60Li-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:57:52 INFO - Running setup.py (path:/tmp/pip-HcgSBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:57:52 INFO - Running setup.py (path:/tmp/pip-RloHdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:57:52 INFO - Running setup.py (path:/tmp/pip-5N2huY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:57:52 INFO - Running setup.py (path:/tmp/pip-4II1J4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:57:53 INFO - Running setup.py (path:/tmp/pip-D14KHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:57:53 INFO - Running setup.py (path:/tmp/pip-Aq6b4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:57:53 INFO - Running setup.py (path:/tmp/pip-y8CUWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:57:53 INFO - Running setup.py (path:/tmp/pip-q7i7ce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:57:53 INFO - Running setup.py (path:/tmp/pip-ipcxif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:57:53 INFO - Running setup.py (path:/tmp/pip-WL2_Is-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:57:53 INFO - Running setup.py (path:/tmp/pip-RX6ZCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:57:53 INFO - Running setup.py (path:/tmp/pip-FF8fb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:57:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:57:53 INFO - Running setup.py install for manifestparser 18:57:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:57:53 INFO - Running setup.py install for mozcrash 18:57:53 INFO - Running setup.py install for mozdebug 18:57:53 INFO - Running setup.py install for mozdevice 18:57:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:57:54 INFO - Running setup.py install for mozfile 18:57:54 INFO - Running setup.py install for mozhttpd 18:57:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:57:54 INFO - Running setup.py install for mozinfo 18:57:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:57:54 INFO - Running setup.py install for mozInstall 18:57:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:57:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:57:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:57:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:57:54 INFO - Running setup.py install for mozleak 18:57:54 INFO - Running setup.py install for mozlog 18:57:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:57:54 INFO - Running setup.py install for moznetwork 18:57:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:57:54 INFO - Running setup.py install for mozprocess 18:57:54 INFO - Running setup.py install for mozprofile 18:57:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:57:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:57:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:57:54 INFO - Running setup.py install for mozrunner 18:57:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:57:55 INFO - Running setup.py install for mozscreenshot 18:57:55 INFO - Running setup.py install for moztest 18:57:55 INFO - Running setup.py install for mozversion 18:57:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:57:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:57:55 INFO - Cleaning up... 18:57:55 INFO - Return code: 0 18:57:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:57:55 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')]} 18:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:55 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')]} 18:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:55 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 0x211b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24878a0>, '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 0x24887a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2488c30>, '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': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', '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 18:57:55 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 18:57:55 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 18:57:55 INFO - Using env: {'DISPLAY': ':0', 18:57:55 INFO - 'HOME': '/home/cltbld', 18:57:55 INFO - 'LANG': 'en_US.UTF-8', 18:57:55 INFO - 'LANGUAGE': 'en_US:en', 18:57:55 INFO - 'LOGNAME': 'cltbld', 18:57:55 INFO - 'MAIL': '/var/mail/cltbld', 18:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:55 INFO - 'MOZ_NO_REMOTE': '1', 18:57:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:57:55 INFO - 'NO_EM_RESTART': '1', 18:57:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:55 INFO - 'PWD': '/builds/slave/test', 18:57:55 INFO - 'SHELL': '/bin/bash', 18:57:55 INFO - 'SHLVL': '1', 18:57:55 INFO - 'TERM': 'linux', 18:57:55 INFO - 'TMOUT': '86400', 18:57:55 INFO - 'USER': 'cltbld', 18:57:55 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', 18:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:55 INFO - '_': '/tools/buildbot/bin/python'} 18:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:57:55 INFO - Running setup.py (path:/tmp/pip-l_2HTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:57:55 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)) 18:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:57:55 INFO - Running setup.py (path:/tmp/pip-zUICYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:57:55 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)) 18:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:57:55 INFO - Running setup.py (path:/tmp/pip-XyPWdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:57:55 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)) 18:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:57:55 INFO - Running setup.py (path:/tmp/pip-D8c7vr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:57:55 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)) 18:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:57:55 INFO - Running setup.py (path:/tmp/pip-A8qtHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:57:55 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)) 18:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:57:55 INFO - Running setup.py (path:/tmp/pip-H_34mL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:57:56 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)) 18:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:57:56 INFO - Running setup.py (path:/tmp/pip-3bB2gu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:57:56 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)) 18:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:57:56 INFO - Running setup.py (path:/tmp/pip-Eupm_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:57:56 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)) 18:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:57:56 INFO - Running setup.py (path:/tmp/pip-Qk2Vtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:57:56 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)) 18:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:57:56 INFO - Running setup.py (path:/tmp/pip-LZDx5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:57:56 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)) 18:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:57:56 INFO - Running setup.py (path:/tmp/pip-4S_tIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:57:56 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)) 18:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:57:56 INFO - Running setup.py (path:/tmp/pip-_OeUSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:57:56 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)) 18:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:57:56 INFO - Running setup.py (path:/tmp/pip-p9cDQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:57:56 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)) 18:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:57:56 INFO - Running setup.py (path:/tmp/pip-iBCR58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:57:56 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)) 18:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:57:56 INFO - Running setup.py (path:/tmp/pip-gCAgQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:57:56 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)) 18:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:57:56 INFO - Running setup.py (path:/tmp/pip-GrDw1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:57:56 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)) 18:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:57:56 INFO - Running setup.py (path:/tmp/pip-l2ulZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:57:56 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)) 18:57:56 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)) 18:57:56 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)) 18:57:56 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)) 18:57:56 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)) 18:57:56 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)) 18:57:56 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)) 18:57:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:57:56 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 18:57:56 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 18:57:56 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 18:57:56 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 18:57:57 INFO - Downloading blessings-1.6.tar.gz 18:57:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:57:58 INFO - Installing collected packages: blessings 18:57:58 INFO - Running setup.py install for blessings 18:57:58 INFO - Successfully installed blessings 18:57:58 INFO - Cleaning up... 18:57:58 INFO - Return code: 0 18:57:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:57:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:57:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:57:58 INFO - Reading from file tmpfile_stdout 18:57:58 INFO - Current package versions: 18:57:58 INFO - argparse == 1.2.1 18:57:58 INFO - blessings == 1.6 18:57:58 INFO - blobuploader == 1.2.4 18:57:58 INFO - docopt == 0.6.1 18:57:58 INFO - functools32 == 3.2.3-2 18:57:58 INFO - jsonschema == 2.5.1 18:57:58 INFO - manifestparser == 1.1 18:57:58 INFO - mozInstall == 1.13 18:57:58 INFO - mozcrash == 1.0 18:57:58 INFO - mozdebug == 0.1 18:57:58 INFO - mozdevice == 0.51 18:57:58 INFO - mozfile == 1.2 18:57:58 INFO - mozhttpd == 0.7 18:57:58 INFO - mozinfo == 0.10 18:57:58 INFO - mozleak == 0.1 18:57:58 INFO - mozlog == 3.5 18:57:58 INFO - moznetwork == 0.27 18:57:58 INFO - mozprocess == 0.25 18:57:58 INFO - mozprofile == 0.28 18:57:58 INFO - mozrunner == 6.13 18:57:58 INFO - mozscreenshot == 0.1 18:57:58 INFO - mozsystemmonitor == 0.3 18:57:58 INFO - moztest == 0.8 18:57:58 INFO - mozversion == 1.4 18:57:58 INFO - psutil == 3.1.1 18:57:58 INFO - requests == 1.2.3 18:57:58 INFO - wsgiref == 0.1.2 18:57:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:57:58 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')]} 18:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:58 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')]} 18:57:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:58 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 0x211b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24878a0>, '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 0x24887a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2488c30>, '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': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', '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 18:57:58 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 18:57:58 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 18:57:58 INFO - Using env: {'DISPLAY': ':0', 18:57:58 INFO - 'HOME': '/home/cltbld', 18:57:58 INFO - 'LANG': 'en_US.UTF-8', 18:57:58 INFO - 'LANGUAGE': 'en_US:en', 18:57:58 INFO - 'LOGNAME': 'cltbld', 18:57:58 INFO - 'MAIL': '/var/mail/cltbld', 18:57:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:58 INFO - 'MOZ_NO_REMOTE': '1', 18:57:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:57:58 INFO - 'NO_EM_RESTART': '1', 18:57:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:57:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:58 INFO - 'PWD': '/builds/slave/test', 18:57:58 INFO - 'SHELL': '/bin/bash', 18:57:58 INFO - 'SHLVL': '1', 18:57:58 INFO - 'TERM': 'linux', 18:57:58 INFO - 'TMOUT': '86400', 18:57:58 INFO - 'USER': 'cltbld', 18:57:58 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', 18:57:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:58 INFO - '_': '/tools/buildbot/bin/python'} 18:57:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:58 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)) 18:57:58 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)) 18:57:58 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)) 18:57:58 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)) 18:57:58 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)) 18:57:58 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)) 18:57:58 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)) 18:57:58 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)) 18:57:58 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)) 18:57:58 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:57:58 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 18:57:58 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 18:57:58 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 18:57:58 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 18:57:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:57:59 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)) 18:57:59 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)) 18:57:59 INFO - Installing collected packages: simplejson 18:57:59 INFO - Running setup.py install for simplejson 18:57:59 INFO - building 'simplejson._speedups' extension 18:57:59 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 18:58:00 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 18:58:00 INFO - Successfully installed simplejson 18:58:00 INFO - Cleaning up... 18:58:00 INFO - Return code: 0 18:58:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:58:00 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')]} 18:58:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:00 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')]} 18:58:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:00 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 0x211b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24878a0>, '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 0x24887a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2488c30>, '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': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', '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 18:58:00 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 18:58:00 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 18:58:00 INFO - Using env: {'DISPLAY': ':0', 18:58:00 INFO - 'HOME': '/home/cltbld', 18:58:00 INFO - 'LANG': 'en_US.UTF-8', 18:58:00 INFO - 'LANGUAGE': 'en_US:en', 18:58:00 INFO - 'LOGNAME': 'cltbld', 18:58:00 INFO - 'MAIL': '/var/mail/cltbld', 18:58:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:00 INFO - 'MOZ_NO_REMOTE': '1', 18:58:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:58:00 INFO - 'NO_EM_RESTART': '1', 18:58:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:58:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:58:00 INFO - 'PWD': '/builds/slave/test', 18:58:00 INFO - 'SHELL': '/bin/bash', 18:58:00 INFO - 'SHLVL': '1', 18:58:00 INFO - 'TERM': 'linux', 18:58:00 INFO - 'TMOUT': '86400', 18:58:00 INFO - 'USER': 'cltbld', 18:58:00 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', 18:58:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:00 INFO - '_': '/tools/buildbot/bin/python'} 18:58:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:58:01 INFO - Cleaning up... 18:58:01 INFO - Return code: 0 18:58:01 INFO - Running post-action listener: _resource_record_post_action 18:58:01 INFO - Running post-action listener: _start_resource_monitoring 18:58:01 INFO - Starting resource monitoring. 18:58:01 INFO - [mozharness: 2017-08-11 01:58:01.133523Z] Finished create-virtualenv step (success) 18:58:01 INFO - [mozharness: 2017-08-11 01:58:01.134115Z] Running install step. 18:58:01 INFO - Running pre-action listener: _resource_record_pre_action 18:58:01 INFO - Running main action method: install 18:58:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:58:01 INFO - Reading from file tmpfile_stdout 18:58:01 INFO - Detecting whether we're running mozinstall >=1.0... 18:58:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:58:01 INFO - Reading from file tmpfile_stdout 18:58:01 INFO - Output received: 18:58:01 INFO - Usage: mozinstall [options] installer 18:58:01 INFO - Options: 18:58:01 INFO - -h, --help show this help message and exit 18:58:01 INFO - -d DEST, --destination=DEST 18:58:01 INFO - Directory to install application into. [default: 18:58:01 INFO - "/builds/slave/test"] 18:58:01 INFO - --app=APP Application being installed. [default: firefox] 18:58:01 INFO - mkdir: /builds/slave/test/build/application 18:58:01 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'] 18:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:58:19 INFO - Reading from file tmpfile_stdout 18:58:19 INFO - Output received: 18:58:19 INFO - /builds/slave/test/build/application/firefox/firefox 18:58:19 INFO - Running post-action listener: _resource_record_post_action 18:58:19 INFO - [mozharness: 2017-08-11 01:58:19.423974Z] Finished install step (success) 18:58:19 INFO - [mozharness: 2017-08-11 01:58:19.424257Z] Running setup-mitmproxy step. 18:58:19 INFO - Running pre-action listener: _resource_record_pre_action 18:58:19 INFO - Running main action method: setup_mitmproxy 18:58:19 INFO - Skipping: mitmproxy is not required 18:58:19 INFO - Running post-action listener: _resource_record_post_action 18:58:19 INFO - [mozharness: 2017-08-11 01:58:19.425495Z] Finished setup-mitmproxy step (success) 18:58:19 INFO - [mozharness: 2017-08-11 01:58:19.425716Z] Running run-tests step. 18:58:19 INFO - Running pre-action listener: _resource_record_pre_action 18:58:19 INFO - Running pre-action listener: _set_gcov_prefix 18:58:19 INFO - Running main action method: run_tests 18:58:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:58:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:58:19 INFO - Python 2.7.3 18:58:19 INFO - Return code: 0 18:58:19 INFO - grabbing minidump binary from tooltool 18:58:19 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')]} 18:58:19 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 0x24878a0>, '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 0x24887a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2488c30>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:58:19 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 18:58:19 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 18:58:19 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 18:58:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:58:19 INFO - Return code: 0 18:58:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:58:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:58:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:58:19 INFO - ENV: RUST_BACKTRACE is now full 18:58:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:58:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:58:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 18:58:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-058 --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 18:58:19 INFO - Using env: {'DISPLAY': ':0', 18:58:19 INFO - 'HOME': '/home/cltbld', 18:58:19 INFO - 'LANG': 'en_US.UTF-8', 18:58:19 INFO - 'LANGUAGE': 'en_US:en', 18:58:19 INFO - 'LOGNAME': 'cltbld', 18:58:19 INFO - 'MAIL': '/var/mail/cltbld', 18:58:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:58:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:19 INFO - 'MOZ_NO_REMOTE': '1', 18:58:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:58:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:58:19 INFO - 'NO_EM_RESTART': '1', 18:58:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:58:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:58:19 INFO - 'PWD': '/builds/slave/test', 18:58:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:58:19 INFO - 'RUST_BACKTRACE': 'full', 18:58:19 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:58:19 INFO - 'SHELL': '/bin/bash', 18:58:19 INFO - 'SHLVL': '1', 18:58:19 INFO - 'STYLO_FORCE_ENABLED': '1', 18:58:19 INFO - 'TERM': 'linux', 18:58:19 INFO - 'TMOUT': '86400', 18:58:19 INFO - 'USER': 'cltbld', 18:58:19 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839', 18:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:19 INFO - '_': '/tools/buildbot/bin/python'} 18:58:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 18:58:19 INFO - mozversion application_buildid: 20170811011457 18:58:19 INFO - mozversion application_changeset: 253a8560dc34456d2e8a13065e4b3eb5ecf6704f 18:58:19 INFO - mozversion application_display_name: Nightly 18:58:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:58:19 INFO - mozversion application_name: Firefox 18:58:19 INFO - mozversion application_remotingname: firefox 18:58:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:58:19 INFO - mozversion application_vendor: Mozilla 18:58:19 INFO - mozversion application_version: 57.0a1 18:58:19 INFO - mozversion platform_buildid: 20170811011457 18:58:19 INFO - mozversion platform_changeset: 253a8560dc34456d2e8a13065e4b3eb5ecf6704f 18:58:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:58:19 INFO - mozversion platform_version: 57.0a1 18:58:19 INFO - using testdate: 1502416699 18:58:19 INFO - actual date: 1502416699 18:58:19 INFO - starting webserver on 'localhost:51982' 18:58:19 INFO - SUITE-START | Running 2 tests 18:58:19 INFO - TEST-START | dromaeo_css 18:58:19 INFO - Initialising browser for dromaeo_css test... 18:58:19 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51982/getInfo.html -profile /tmp/tmpdM9J4y/profile 18:58:19 INFO - TEST-INFO | started process 5713 (/builds/slave/test/build/application/firefox/firefox http://localhost:51982/getInfo.html) 18:58:25 INFO - TEST-INFO | 5713: exit 0 18:58:25 INFO - Browser initialized. 18:58:25 INFO - Running cycle 1/1 for dromaeo_css test... 18:58:25 INFO - TEST-INFO | started process 5951 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdM9J4y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:58:26 INFO - PID 5951 | 18:58:26 INFO - PID 5951 | (/builds/slave/test/build/application/firefox/firefox:6005): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:58:26 INFO - PID 5951 | 18:58:37 INFO - PID 5951 | RSS: Main: 168308736 18:58:37 INFO - PID 5951 | 19:01:35 INFO - PID 5951 | Cycle 1(1): loaded http://localhost:51982/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51982/tests/dromaeo/cssquery-ext.html) 19:01:37 INFO - PID 5951 | RSS: Main: 175800320 19:01:37 INFO - PID 5951 | 19:04:39 INFO - PID 5951 | Cycle 1(1): loaded http://localhost:51982/tests/dromaeo/cssquery-ext.html (next: http://localhost:51982/tests/dromaeo/cssquery-jquery.html) 19:04:39 INFO - PID 5951 | RSS: Main: 172826624 19:04:39 INFO - PID 5951 | 19:07:41 INFO - PID 5951 | Cycle 1(1): loaded http://localhost:51982/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51982/tests/dromaeo/cssquery-mootools.html) 19:07:42 INFO - PID 5951 | RSS: Main: 174714880 19:07:42 INFO - PID 5951 | 19:10:43 INFO - PID 5951 | Cycle 1(1): loaded http://localhost:51982/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51982/tests/dromaeo/cssquery-prototype.html) 19:10:44 INFO - PID 5951 | RSS: Main: 172990464 19:10:44 INFO - PID 5951 | 19:13:48 INFO - PID 5951 | Cycle 1(1): loaded http://localhost:51982/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51982/tests/dromaeo/cssquery-yui.html) 19:13:48 INFO - PID 5951 | RSS: Main: 177045504 19:13:48 INFO - PID 5951 | 19:16:50 INFO - PID 5951 | Cycle 1(1): loaded http://localhost:51982/tests/dromaeo/cssquery-yui.html (next: http://localhost:51982/tests/dromaeo/cssquery-dojo.html) 19:16:51 INFO - PID 5951 | RSS: Main: 177709056 19:16:51 INFO - PID 5951 | 19:16:51 INFO - PID 5951 | __start_tp_report 19:16:51 INFO - PID 5951 | _x_x_mozilla_page_load 19:16:51 INFO - PID 5951 | _x_x_mozilla_page_load_details 19:16:51 INFO - PID 5951 | |i|pagename|runs| 19:16:51 INFO - PID 5951 | |0;dojo.html;3128.5463607776887;3138.1213260287122;3172.349668317995;3191.888283910064;3201.3597280543895;6138.294096178941;6237.937620623792;6287.59130656507;6302.40127187917;6355.459785918202;6159.5380346474085;6212.5029997600195;6214.8135555933395;6225.035119556464;6300.527460440462;5693.288338957641;5702.116171993348;5713.114467257561;5714.857128571798;5753.597248192607;5758.798442054529;5784.363719990798;5789.942100578983;5800.9129863052085;5842.269716285465;5726.570507211955;5799.652020878761;5806.071028635419;5824.970875145618;5895.380984996578;5578.9163162552595;5733.856653583652;5738.684372359518;5827.912581311284;5847.064469684851;5675.091985282376;5731.283589551306;5738.167965645;5743;5773.422657734235;6044.607100538451;6163.815085547396;6243.843903902393;6280.560360774748;6292.87414251717;6620.569662971891;6635.270120286765;6658.3674550917585;6667.1332726745695;6683.3650803174105;6746.93253067468;6750.493712971498;6751.189857217117;6754.932450675479;6758.526903116784;6173.135760993465;6173.938260617426;6179.907301390483;6209.16176316196;6299.622022678654;6869.896951545631;6884.621345825927;6935.410490108397;6971.65141742921;7019.92980070203;6516.674166291667;6621.602703837785;6627.635480048643;6665.433438157714;6705.631190284484;6597.241317248482;6719.664016799141;6736.494762892803;6752.561083529555;6849.349311815369;6550.639714815731;6643.60138391698;6654.3345665434035;6661.100751398596;6675.899861502081;6558.049082882955;6604.702788374535;6683.933160668428;6698.095757072733;6719.899201511981;5275.340582427197;5288;5302.708351040653;5303.4696530346655;5306.973465132727;4450.109978004412;4550.408446901882;4556.2937744649635;4583.152116858392;4586.610138138229;5682.687452190169;5718.857028574319;5790.623609465456;5801.9129713054335;5821.388754180834;6763.7632682855865;6780.864382712318;6847.21257055445;6864.931350686429;6874.793756187322;5654.321481422256;5758.769649213985;5787.160861675034;5791.82624521265;5853.268341457318;5878.2064421302575;5912.379200184005;5941.465268125803;5966.880662386728;5970.820875373745;6558.606483610999;6566.146400967939;6597.241317248482;6601.174853143357;6650.900236496456;6720.596764194164;6780.627065511444;6787.185537735503;6879.690413931285;6886.552374095668;6537.0521274413995;6545.21457425093;6548.540054903918;6649.434798042221;6656.933430665825;6464.4828413725845;6555.410013098844;6558.704858281293;6564.113844630913;6569.441597464078;6641.435477984425;6712.362325579063;6725.697343619647;6727.78899798041;6732.562383445061;6415.230172379344;6450.967745161244;6485.897397442541;6486.4486518646445;6486.902696459462;6346.555741098264;6354.269259035085;6359.14151589548;6367.649779261999;6370.649614271074;6014.5901419836255;6288.245410550763;6290.654014029273;6333.240011198502;6333.303336632875;6371.553991220755;6384.233891932998;6420.293767685457;6456;6530.542861999615;6206.813795586139;6256.436920677161;6300.873982520232;6302.464290535172;6331.556791024584 19:16:51 INFO - PID 5951 | |1;ext.html;843.3421930893904;874.7463235661659;899.064972428674;899.5277479323354;1171.379169040409;30808.075757727274;32256.67743322569;42407.36388954161;43447.78276108615;43560.56439435605;42825.78587107068;43357.78321108398;43387.566124338744;43481.782591087;43516.78241608787;7761.844763104735;7888.211178882095;7895.368370530373;7909.090454597708;7916.366690664762;7770.689172433124;7803.258690424429;7909.485883417559;7914.722984695537;7916.366690664762;7901.170377110407;7901.723439679612;7954.681812727484;7965.800854978614;7970.920290797076;7894.407919406039;7899.8420031599335;7904.2886140247365;7916.168802275735;7919.128895821455;7697.923020769833;7740.303372696428;7741.7677469676;7749.535027898288;7751.069871615386;39838.60161398378;40115.197696046205;40197.59802401968;40217.79891100526;40247;3602.225521512887;3644.9976256529403;3645;3647.1246900743895;3649.124210189562;31719.84140079308;31823.204419889687;31832.840835795905;31888.20279493008;31889.681103189134;4900.803967841318;4905.607551395879;4913.213885778258;4926.359573255454;4932.80268789248;193012;193204;193283;193801;194215.99999999857;49261.261081083816;49480.25779613309;49518.50481495211;49561;49659.503404965486;51311.74344128255;51384.229236561485;51397;51480;51510.22734658983;16303.510894673178;16455;16474.341026359052;16474.917625411796;16480.0935948524;13408.932955335162;13512.662183445493;13588.93205533966;13626.795598066037;13642.795358069638;14022;14197;14204.289785510684;14222.715545689236;14378.424863005572;5271.65734227274;5303.632193557042;5370.436104209002;5372.731363431891;5426.158945363475;1014.5434554450379;1016.7254841192985;1023.6059117239917;1029.4132344563527;1037.227265687053;22705.772942270698;23614.527709445716;29738.256543586584;29994;30236.546451803242;1098.1544210957677;1104.939228342433;1123.2081382625229;1124.8256520239377;1125.178619607691;12760.6809829755;12781.424835882408;16308;16377;16774.49676509707;206334;210570;219454;220569;221533;58470;59514.10728839071;59577.70211148917;59579;59593;1503.8045054142892;1508;1509.1548732709718;1509.7433436315632;1525.488961198011;27950.30124246829;28039.43921121607;28369.716302836707;28470.43059138847;29199.85400073071;39347;39402.60597393989;39511.2097758049;39571;39585.2082958345;190971;191216;191332;191406;191485;35109;35237;35238.29523409569;35425;37522.624773751915;49144.50855491399;49443;49567.50432495629;49938.25092623541;50029.74985125051;36269.63730362768;36316.636833631324;36321.81839090788;36711.449328260635;36772.63227367692;33276.83361583177;33401.83299083489;34327.485087724206;34466;34537.48193777146;39941.80029099836;41294.1741165181;41509.58490415057;41656.58343416527;41995;42159.1568168641;42215.155696886504;42711;42725.35911961261;42963;24581.508369832147;26263.474730504902;26336.604950926136;26361.60457593176;26409.60385594256 19:16:51 INFO - PID 5951 | |2;jquery.html;573.2032474859943;575.3670961941867;578.5429510686558;582.2983305117333;584.9210356601859;22418.43953901153;22480.88759556202;23157;23228.18701345451;23370.415739606517;26454.074107406246;27005.729942700615;27008;27023.18930432085;27036.1889143326;6489.351064893508;6631.336866313378;6667.966660166692;6668.299828518008;6670.032845237438;5884.499817515516;6227.346128656493;6356.682165891687;6393.264774550917;6413.390727880844;6363.490920726331;6376.649284289362;6379.71291291893;6383.39357761012;6383.904241436382;6349.42855143037;6349.904751428732;6363.777267795628;6375.744970201186;6385.042243663441;5954.8809023819285;5961.658626808977;5965.105234214859;5970.402959704038;5976.432238937294;28451.715482845113;29134.708652913625;29674.258143546584;29924.401511969856;30170.84914575435;1907.160849226336;1949.3664559018375;1952.6485232658115;1953.990230048855;1956.4619729574338;19021.61956760857;19379.321723739744;19395.515112122168;20245.39263822073;20354.796452035585;2456.1894574790276;2466.5066986602574;2521.0924067335745;2525.7726804587674;2535.657686212356;143419;144668;145576;145641;145795;14489.927550362181;15015.549533513793;15043.924780376028;15138.470153544793;15253.771193432107;23560;23580.29259122229;23613;23615.29154125379;23615.29154125379;10359.94820025895;10623.20325975555;10730.517126729315;10766.192535559865;10775.892241077645;8091.474054186459;10001.34991225568;10028.946960569036;10070.69787906364;10120.69637910849;8230.135835737281;9367.250619950388;9509.144177024102;9516.762080947894;9616.182624476858;2824.2939265183704;2838.815476993989;2858.6712528059124;2863.656361236665;2945.528715405525;947.1381043250747;960.3280042592875;968.1528662420383;969.6121551379505;972.4991629310911;19396;19466.90266548658;19698;19844.60310793776;19910.800891991184;1029.248648486609;1032.9070383665505;1035.8653375061194;1044.2585764107416;1045.5556388534994;11024.559017639205;11060.723481913017;11060.834087488694;11123.221374503708;11164.720881977853;498105;501318;502320;504161;515802;116321.4183929075;121800;121944;127732.36133819458;130787;1270.764908491932;1277.067740549404;1325.4499382756242;1326.423005992377;1326.661701266171;20257.493562661053;20447.38657840267;20526.281580144623;20765.273215437388;21154.788452115896;59556.70221648864;60427.69786151041;60430;60758.39241607527;61821.38178618156;76593.23406765862;76956.23043769714;77269;77388.61305693435;77415;14486.85513144855;14630.634234144232;14694.191819450032;14776.113433194043;14806.111633302036;23009.424764381023;23180;23183.53632927366;23204.883975580015;23244.883775581013;17952.730709039115;18058.187381568125;18192.545186370447;18226.544336391697;18283.26866925308;14424.278786060864;14573.198474084027;15104.697906042038;16050.19749012568;16078.758818617496;19233.711494327876;19575;19598.804011960267;19669.508262292984;19727.60544789125;14410.135391876522;14645.48740794067;14655.193964332064;14686.706265874836;14686.926565367105;18719.90640046791;18957.336493222672;18982.335618253295;18985.715214272073;18998.335058272893 19:16:51 INFO - PID 5951 | |3;mootools.html;1348.9999999999998;1387.4172847404088;1402.389960367241;1402.866727660872;1416.4263473293317;14048.859511404895;14687.632809179762;14693.485727999521;14719.484818031371;14799.260036998148;14199.574012779607;14667.926660366684;15216.543503694907;15254.618634534143;15288.388464461435;2707.918762437125;2775.1674497650624;2776.6390369251967;2777.5833624956317;2785.6517935258094;2634.7365263473594;2679.4239238563705;2737.137801592502;2746.917592472229;2751.7110703376156;2697.608846717225;2702.202850159198;2726.3456770375165;2743.231895069384;2743.492453896026;2629.3295209721496;2637.9076732314375;2655.0574546036073;2655.1636234586235;2657.8139530232697;2584.4572639745675;2628.053900595803;2632.697239817426;2634.7892168626654;2636.1564299424;14218.573442796733;14239.501617443444;14395.35220915051;14444.855551444562;14452;7548.886766698504;7842;7842.529448233125;7861.135275119732;7862.567558784264;4983.202687569971;5121.820736274212;5157.277981082651;5246.81636142737;5256.106461901486;1909.608530251309;1912.0917564156914;1926.1332400419867;1928.5275107598588;1932.9710054349196;23835.523289534285;23876.40308992272;23876.64185037226;23950.16174433904;23988.64017039746;6637.435817955431;7112.6443677815905;7127.82180445493;7371.852562948711;7380.114386273682;16179.191040447931;16287.267072981744;16313.265903034391;16328.591785205228;16336.428225012305;7743.9612801936755;7861.449698521049;7866.09539902907;7876.173001834725;7916.960415197887;5840.123981402823;6013.639181649115;6223.751049957951;6223.782167624202;6323.494120470351;5243.580513558906;5359.464053594609;5386.865328366744;5538.972305138529;5554.333479982347;1532.7241096602718;1561.1023661394743;1567.6551158745058;1577.242923396776;1577.6055986003498;883.337496877342;908.2280061947292;926.5274709898;934.6354921580589;939.2392162348519;7692.269234422721;7704.961475192588;7720.575368354795;7783.649735761905;7802.492837965514;657.5627207906784;662.7415308029872;672.3142394757418;686.9587824730532;689.8309914070984;5194.22086687;5303.098473259555;5308.92036619451;5414.972925135349;5491.258680078218;19600.70598941017;19857.503562411057;20013.499662508555;20324.49188770263;20340.389788306373;19507.609847802963;19911.701324480146;19936.70094948577;20179.899100504404;20204.39386818398;10930.781384372269;11006.779864402828;11112.333260004263;11245.212835101463;11322.150838686966;8204.425690201628;8382;8385.790355240924;8398.41211115216;8422.95788521078;16845.41041063557;17176;17234.82765172332;17303.65392692114;17446.302147914448;17715.29138834432;17721.379751708628;17804.554886127433;18023;18589.628207436046;11478.139139564399;11626.593069242535;11695.298282103102;11728.94135529317;11729.706757330794;13314.866851331362;13384.665383365495;13540.052196346162;13695.109817861807;13696.52062177819;10960.725981850517;11076.889231107585;11114.277571957797;11190.94404527972;11248.156388271076;10454.63408780689;10713.910787396382;10745.462726863781;10932.890671093188;10959.616413425492;9308.348415610842;9586.137247647745;9839.065288797552;9972.900270997197;9974.950125249326;7357.3746231568775;7480.775576732707;7546.584937828472;7586.317231449196;7627.389808815171;7267;7296.306850849161;7340.853182936418;7399.075115610549;7407.777766667009 19:16:51 INFO - PID 5951 | |4;prototype.html;472.90778298231857;481.44393225824166;496.3770468062582;509.0658641393044;512.9512696293854;6188.226471691039;6286.8113956581265;6294.150289710888;6297.307296197415;6324.177856878605;5962.642241465515;6101.511879049676;6123.938760612393;6126.387361263872;6183.690815459231;4897.191963326047;4925.581325587321;4940.51784464661;4964.205727083668;4969.527894850002;4749.501302363236;4962.950370496285;4964;4969.583668654434;4971.850844474671;4878.756062196893;4895.853124406273;4915.591360233478;4924.48851085947;4948.752562371885;4876.171050921352;4923.630727695438;4977.452480227172;4984.327115839352;4991.376077990251;4389.2977123660385;4436.689431739779;4447.51077381491;4458.197524445599;4470.619997300233;5987.6108052976415;6015;6042.667653279068;6047.183630209909;6071;5665.745041473144;5673.865226954585;5692.601517893749;5720.742566584514;5723.255976723041;6288.182536270256;6360.586561873463;6423.7430502779835;6424.229092508882;6429.582077165016;4788.449328327252;4801.159797035505;4805;4818.68678535894;4859.757012149414;6804.965975170093;6820.624865632388;6900.585964842126;6907.16960005604;6961.408280296181;4914.631402644744;4930.33440485537;4940.6788558743565;4957.975210123926;4970.726610036482;5046.772895219724;5055;5057.671251368649;5059.747012649353;5088.160453525201;5609.354924183689;5637.492625663628;5706.144078388275;5721.828345149652;5738.512226460714;6515.283318834924;6553.984132459476;6561.311062338385;6596.571222870498;6621.370969757962;1637.1323198704706;1653.8180800112218;1707.6670049340507;1744.2151032035636;1745.2408202431982;1182.8462299900957;1199.5261871560858;1202.4108186988312;1206.2943178240653;1212.8665846756849;5562.610617256753;5624.521915637135;5632.85917852057;5637.701944362594;5705.172749951317;6478.4169424752;6590.901136482957;6653.401193892573;6707.66461676924;6746.83132921671;5743.770249190069;5771.192033115367;5781.306243250837;5785.71071446426;5786.508146807484;3533.434650455915;3534.893953181357;3546.929061418757;3571.8035508047305;3607.9639203607626;6527;6560.606363618198;6605.4055135037115;6606.084235571727;6653.733850646016;6890.793276201722;6938.826529336807;7005.404540614003;7012.719491220396;7021.508494405445;6513.902291465537;6533.18335208099;6538.869222615521;6540.672966351759;6567.31043240472;6684.16447944007;6712.966435167793;6756.662166891734;6782.966085169543;6784.6946887389195;6622.006698994996;6647.634380109141;6733.091032710521;6768.932310676831;6780.559263647847;6450.8064758057335;6477.631631551661;6511.283758786624;6524.641144737085;6546.672666366758;4895.33912921751;4933.9013219736125;4947.957006440941;4951.653384263067;4961.1318019345745;6673.165854268216;6707.8993815091835;6716.112071809169;6806.285340039224;6809.216940051958;6450.129462455835;6539.934600654123;6547.214334279726;6558;6565.179352580927;6174.722137503733;6438;6440.194975628046;6452.741890324334;6489.383508566679;6233.875322493615;6308.895943209828;6356.841078973062;6425.485961123192;6464.903026454513;6416.615003099829;6505.024246362894;6576.309487503743;6589.374009469093;6616.3789871481795;6310.842228944313;6354;6365.809025729235;6376.585521941059;6378.77674281398 19:16:51 INFO - PID 5951 | |5;yui.html;861.6984055580543;886.9113088691128;893.9942564614809;912.6623149434708;921.8571121476172;18233.726494102582;18859;18896.338628148016;18924.432267031993;21087;19845.007749612534;20394;20507.17971281147;20538.897305513452;20571.485712857186;1014.3457469931908;1054.620336678795;1056.550965839519;1058.227493929432;1058.30680904008;1025.9794804103913;1039.5633833789827;1046.9424181670006;1055.7993981143559;1057.3338796558196;1087.2063393722588;1087.9728006799814;1088.8638920134983;1092;1092.7705181911808;1077.2351630342466;1077.2459278505;1077.3751224289892;1077.8113830079744;1079.061216741436;776.8990031295896;777.0830420104274;777.9260970207821;782.22950393862;782.7377828427484;18365.265389384407;18419;18920.243190272373;18990.62018759631;19148.521286967796;20518;20631.174753009862;20911.68632470514;20976.5804683907;21024;15574.299156537983;16033.278502467418;16049.919750401174;16113.758293625606;16162.434314798926;3631.473436351714;3671.0271777978855;3680.411134218539;3702.963170312317;3704.8888533344043;120087;121368.39315803452;124023.3798831009;126229;128548;15506.379744810307;15901.204939752966;16652.916735416246;16820.579485512717;16834.24245908937;31119.377612448076;31385.37229255402;31808.363832723215;31811.68188318087;31830;8594.140585941355;8772.736817895473;8809.339299552432;8815;8843.69047083349;4142.420061191436;4217.135487225111;4228.725132866354;4230.048239146229;4232.936505952413;4924.507549245119;5105.285260063594;5120.436751957282;5133.589312854962;5146.485351464824;1006.6325791086347;1029.6499190275342;1037.9325343852777;1038.7974345002804;1038.8389799581078;661.6823924516259;676.2966514824626;678.9355011273921;685.0990946904805;692.8510370270416;11123.833142502706;11273.549058037586;11956.103292253116;12892.935535322264;13671.658208544866;704.084689903124;719.7732714195012;719.9460040496983;724.4530379563396;726.1939247435342;5287.814926477555;5453.509184173444;5627.43725627434;5828.854278643068;5834.154047663065;101455;105832;107088.4645576767;109771.09378512019;113534.43232783783;89097.55451222832;94344.52827735955;95428.52285738527;97107;99487.50256248673;84167.57916210502;85838.57080714681;87773;88773.55613221893;89965;12454.564090256797;13139.474421023053;13246.403911823798;13255.337233138112;13370.197788132744;38742.80628596839;38885.416718748675;39248.80375598218;39535.80232098821;40204.99487512836;63779.751935968095;66990;67706;69315.65342173257;70689.64655176691;17188.82811171872;17330.39343622967;17343.2195551198;17363.13184340803;17420.738688919424;19171.61656766829;20459.897700511996;20572.58854822925;21229.256976005767;21406.57186856223;18609.627807443503;18681.71977420367;18805.435836924913;19298.22807087757;19398.224071037;18903.621927561097;19085.236590536777;19161.233550657817;19317.710234346778;19467.90266048661;17887.105644717452;18297.908510457364;18788.718169227745;18832.435026949213;19013.42959711211;13706.65733356674;13873.236971966235;14095.718085638026;14102.647433814236;14302.642433938818;13633.795493067413;13757.587272381845;13924;14343.709066184094;14536.927315363355 19:16:51 INFO - PID 5951 | __end_tp_report 19:16:51 INFO - PID 5951 | __start_cc_report 19:16:51 INFO - PID 5951 | _x_x_mozilla_cycle_collect,379 19:16:51 INFO - PID 5951 | __end_cc_report 19:16:51 INFO - PID 5951 | __startTimestamp1502417811043__endTimestamp 19:16:51 INFO - PID 5951 | 19:16:51 INFO - PID 5951 | ------- Summary: start ------- 19:16:51 INFO - PID 5951 | Number of tests: 6 19:16:51 INFO - PID 5951 | 19:16:51 INFO - PID 5951 | [#0] dojo.html Cycles:165 Average:6152.12 Median:6333.24 stddev:735.17 (11.6%) stddev-sans-first:698.11 19:16:51 INFO - PID 5951 | Values: 3128.5 3138.1 3172.3 3191.9 3201.4 6138.3 6237.9 6287.6 6302.4 6355.5 6159.5 6212.5 6214.8 6225.0 6300.5 5693.3 5702.1 5713.1 5714.9 5753.6 5758.8 5784.4 5789.9 5800.9 5842.3 5726.6 5799.7 5806.1 5825.0 5895.4 5578.9 5733.9 5738.7 5827.9 5847.1 5675.1 5731.3 5738.2 5743.0 5773.4 6044.6 6163.8 6243.8 6280.6 6292.9 6620.6 6635.3 6658.4 6667.1 6683.4 6746.9 6750.5 6751.2 6754.9 6758.5 6173.1 6173.9 6179.9 6209.2 6299.6 6869.9 6884.6 6935.4 6971.7 7019.9 6516.7 6621.6 6627.6 6665.4 6705.6 6597.2 6719.7 6736.5 6752.6 6849.3 6550.6 6643.6 6654.3 6661.1 6675.9 6558.0 6604.7 6683.9 6698.1 6719.9 5275.3 5288.0 5302.7 5303.5 5307.0 4450.1 4550.4 4556.3 4583.2 4586.6 5682.7 5718.9 5790.6 5801.9 5821.4 6763.8 6780.9 6847.2 6864.9 6874.8 5654.3 5758.8 5787.2 5791.8 5853.3 5878.2 5912.4 5941.5 5966.9 5970.8 6558.6 6566.1 6597.2 6601.2 6650.9 6720.6 6780.6 6787.2 6879.7 6886.6 6537.1 6545.2 6548.5 6649.4 6656.9 6464.5 6555.4 6558.7 6564.1 6569.4 6641.4 6712.4 6725.7 6727.8 6732.6 6415.2 6451.0 6485.9 6486.4 6486.9 6346.6 6354.3 6359.1 6367.6 6370.6 6014.6 6288.2 6290.7 6333.2 6333.3 6371.6 6384.2 6420.3 6456.0 6530.5 6206.8 6256.4 6300.9 6302.5 6331.6 19:16:51 INFO - PID 5951 | 19:16:51 INFO - PID 5951 | [#1] ext.html Cycles:180 Average:38674.79 Median:36742.04 stddev:51831.84 (141.1%) stddev-sans-first:51899.39 19:16:51 INFO - PID 5951 | Values: 843.3 874.7 899.1 899.5 1171.4 30808.1 32256.7 42407.4 43447.8 43560.6 42825.8 43357.8 43387.6 43481.8 43516.8 7761.8 7888.2 7895.4 7909.1 7916.4 7770.7 7803.3 7909.5 7914.7 7916.4 7901.2 7901.7 7954.7 7965.8 7970.9 7894.4 7899.8 7904.3 7916.2 7919.1 7697.9 7740.3 7741.8 7749.5 7751.1 39838.6 40115.2 40197.6 40217.8 40247.0 3602.2 3645.0 3645.0 3647.1 3649.1 31719.8 31823.2 31832.8 31888.2 31889.7 4900.8 4905.6 4913.2 4926.4 4932.8 193012.0 193204.0 193283.0 193801.0 194216.0 49261.3 49480.3 49518.5 49561.0 49659.5 51311.7 51384.2 51397.0 51480.0 51510.2 16303.5 16455.0 16474.3 16474.9 16480.1 13408.9 13512.7 13588.9 13626.8 13642.8 14022.0 14197.0 14204.3 14222.7 14378.4 5271.7 5303.6 5370.4 5372.7 5426.2 1014.5 1016.7 1023.6 1029.4 1037.2 22705.8 23614.5 29738.3 29994.0 30236.5 1098.2 1104.9 1123.2 1124.8 1125.2 12760.7 12781.4 16308.0 16377.0 16774.5 206334.0 210570.0 219454.0 220569.0 221533.0 58470.0 59514.1 59577.7 59579.0 59593.0 1503.8 1508.0 1509.2 1509.7 1525.5 27950.3 28039.4 28369.7 28470.4 29199.9 39347.0 39402.6 39511.2 39571.0 39585.2 190971.0 191216.0 191332.0 191406.0 191485.0 35109.0 35237.0 35238.3 35425.0 37522.6 49144.5 49443.0 49567.5 49938.3 50029.7 36269.6 36316.6 36321.8 36711.4 36772.6 33276.8 33401.8 34327.5 34466.0 34537.5 39941.8 41294.2 41509.6 41656.6 41995.0 42159.2 42215.2 42711.0 42725.4 42963.0 24581.5 26263.5 26336.6 26361.6 26409.6 19:16:51 INFO - PID 5951 | 19:16:51 INFO - PID 5951 | [#2] jquery.html Cycles:180 Average:36012.19 Median:22745.16 stddev:85335.45 (375.2%) stddev-sans-first:85533.36 19:16:51 INFO - PID 5951 | Values: 573.2 575.4 578.5 582.3 584.9 22418.4 22480.9 23157.0 23228.2 23370.4 26454.1 27005.7 27008.0 27023.2 27036.2 6489.4 6631.3 6668.0 6668.3 6670.0 5884.5 6227.3 6356.7 6393.3 6413.4 6363.5 6376.6 6379.7 6383.4 6383.9 6349.4 6349.9 6363.8 6375.7 6385.0 5954.9 5961.7 5965.1 5970.4 5976.4 28451.7 29134.7 29674.3 29924.4 30170.8 1907.2 1949.4 1952.6 1954.0 1956.5 19021.6 19379.3 19395.5 20245.4 20354.8 2456.2 2466.5 2521.1 2525.8 2535.7 143419.0 144668.0 145576.0 145641.0 145795.0 14489.9 15015.5 15043.9 15138.5 15253.8 23560.0 23580.3 23613.0 23615.3 23615.3 10359.9 10623.2 10730.5 10766.2 10775.9 8091.5 10001.3 10028.9 10070.7 10120.7 8230.1 9367.3 9509.1 9516.8 9616.2 2824.3 2838.8 2858.7 2863.7 2945.5 947.1 960.3 968.2 969.6 972.5 19396.0 19466.9 19698.0 19844.6 19910.8 1029.2 1032.9 1035.9 1044.3 1045.6 11024.6 11060.7 11060.8 11123.2 11164.7 498105.0 501318.0 502320.0 504161.0 515802.0 116321.4 121800.0 121944.0 127732.4 130787.0 1270.8 1277.1 1325.4 1326.4 1326.7 20257.5 20447.4 20526.3 20765.3 21154.8 59556.7 60427.7 60430.0 60758.4 61821.4 76593.2 76956.2 77269.0 77388.6 77415.0 14486.9 14630.6 14694.2 14776.1 14806.1 23009.4 23180.0 23183.5 23204.9 23244.9 17952.7 18058.2 18192.5 18226.5 18283.3 14424.3 14573.2 15104.7 16050.2 16078.8 19233.7 19575.0 19598.8 19669.5 19727.6 14410.1 14645.5 14655.2 14686.7 14686.9 18719.9 18957.3 18982.3 18985.7 18998.3 19:16:51 INFO - PID 5951 | 19:16:51 INFO - PID 5951 | [#3] mootools.html Cycles:180 Average:8967.49 Median:2635.47 stddev:6163.09 (233.9%) stddev-sans-first:6153.80 19:16:51 INFO - PID 5951 | Values: 1349.0 1387.4 1402.4 1402.9 1416.4 14048.9 14687.6 14693.5 14719.5 14799.3 14199.6 14667.9 15216.5 15254.6 15288.4 2707.9 2775.2 2776.6 2777.6 2785.7 2634.7 2679.4 2737.1 2746.9 2751.7 2697.6 2702.2 2726.3 2743.2 2743.5 2629.3 2637.9 2655.1 2655.2 2657.8 2584.5 2628.1 2632.7 2634.8 2636.2 14218.6 14239.5 14395.4 14444.9 14452.0 7548.9 7842.0 7842.5 7861.1 7862.6 4983.2 5121.8 5157.3 5246.8 5256.1 1909.6 1912.1 1926.1 1928.5 1933.0 23835.5 23876.4 23876.6 23950.2 23988.6 6637.4 7112.6 7127.8 7371.9 7380.1 16179.2 16287.3 16313.3 16328.6 16336.4 7744.0 7861.4 7866.1 7876.2 7917.0 5840.1 6013.6 6223.8 6223.8 6323.5 5243.6 5359.5 5386.9 5539.0 5554.3 1532.7 1561.1 1567.7 1577.2 1577.6 883.3 908.2 926.5 934.6 939.2 7692.3 7705.0 7720.6 7783.6 7802.5 657.6 662.7 672.3 687.0 689.8 5194.2 5303.1 5308.9 5415.0 5491.3 19600.7 19857.5 20013.5 20324.5 20340.4 19507.6 19911.7 19936.7 20179.9 20204.4 10930.8 11006.8 11112.3 11245.2 11322.2 8204.4 8382.0 8385.8 8398.4 8423.0 16845.4 17176.0 17234.8 17303.7 17446.3 17715.3 17721.4 17804.6 18023.0 18589.6 11478.1 11626.6 11695.3 11728.9 11729.7 13314.9 13384.7 13540.1 13695.1 13696.5 10960.7 11076.9 11114.3 11190.9 11248.2 10454.6 10713.9 10745.5 10932.9 10959.6 9308.3 9586.1 9839.1 9972.9 9975.0 7357.4 7480.8 7546.6 7586.3 7627.4 7267.0 7296.3 7340.9 7399.1 7407.8 19:16:51 INFO - PID 5951 | 19:16:51 INFO - PID 5951 | [#4] prototype.html Cycles:180 Average:5486.93 Median:6086.26 stddev:1554.52 (25.5%) stddev-sans-first:1512.64 19:16:51 INFO - PID 5951 | Values: 472.9 481.4 496.4 509.1 513.0 6188.2 6286.8 6294.2 6297.3 6324.2 5962.6 6101.5 6123.9 6126.4 6183.7 4897.2 4925.6 4940.5 4964.2 4969.5 4749.5 4963.0 4964.0 4969.6 4971.9 4878.8 4895.9 4915.6 4924.5 4948.8 4876.2 4923.6 4977.5 4984.3 4991.4 4389.3 4436.7 4447.5 4458.2 4470.6 5987.6 6015.0 6042.7 6047.2 6071.0 5665.7 5673.9 5692.6 5720.7 5723.3 6288.2 6360.6 6423.7 6424.2 6429.6 4788.4 4801.2 4805.0 4818.7 4859.8 6805.0 6820.6 6900.6 6907.2 6961.4 4914.6 4930.3 4940.7 4958.0 4970.7 5046.8 5055.0 5057.7 5059.7 5088.2 5609.4 5637.5 5706.1 5721.8 5738.5 6515.3 6554.0 6561.3 6596.6 6621.4 1637.1 1653.8 1707.7 1744.2 1745.2 1182.8 1199.5 1202.4 1206.3 1212.9 5562.6 5624.5 5632.9 5637.7 5705.2 6478.4 6590.9 6653.4 6707.7 6746.8 5743.8 5771.2 5781.3 5785.7 5786.5 3533.4 3534.9 3546.9 3571.8 3608.0 6527.0 6560.6 6605.4 6606.1 6653.7 6890.8 6938.8 7005.4 7012.7 7021.5 6513.9 6533.2 6538.9 6540.7 6567.3 6684.2 6713.0 6756.7 6783.0 6784.7 6622.0 6647.6 6733.1 6768.9 6780.6 6450.8 6477.6 6511.3 6524.6 6546.7 4895.3 4933.9 4948.0 4951.7 4961.1 6673.2 6707.9 6716.1 6806.3 6809.2 6450.1 6539.9 6547.2 6558.0 6565.2 6174.7 6438.0 6440.2 6452.7 6489.4 6233.9 6308.9 6356.8 6425.5 6464.9 6416.6 6505.0 6576.3 6589.4 6616.4 6310.8 6354.0 6365.8 6376.6 6378.8 19:16:51 INFO - PID 5951 | 19:16:51 INFO - PID 5951 | [#5] yui.html Cycles:180 Average:23548.00 Median:19234.92 stddev:31537.80 (164.0%) stddev-sans-first:31580.26 19:16:51 INFO - PID 5951 | Values: 861.7 886.9 894.0 912.7 921.9 18233.7 18859.0 18896.3 18924.4 21087.0 19845.0 20394.0 20507.2 20538.9 20571.5 1014.3 1054.6 1056.6 1058.2 1058.3 1026.0 1039.6 1046.9 1055.8 1057.3 1087.2 1088.0 1088.9 1092.0 1092.8 1077.2 1077.2 1077.4 1077.8 1079.1 776.9 777.1 777.9 782.2 782.7 18365.3 18419.0 18920.2 18990.6 19148.5 20518.0 20631.2 20911.7 20976.6 21024.0 15574.3 16033.3 16049.9 16113.8 16162.4 3631.5 3671.0 3680.4 3703.0 3704.9 120087.0 121368.4 124023.4 126229.0 128548.0 15506.4 15901.2 16652.9 16820.6 16834.2 31119.4 31385.4 31808.4 31811.7 31830.0 8594.1 8772.7 8809.3 8815.0 8843.7 4142.4 4217.1 4228.7 4230.0 4232.9 4924.5 5105.3 5120.4 5133.6 5146.5 1006.6 1029.6 1037.9 1038.8 1038.8 661.7 676.3 678.9 685.1 692.9 11123.8 11273.5 11956.1 12892.9 13671.7 704.1 719.8 719.9 724.5 726.2 5287.8 5453.5 5627.4 5828.9 5834.2 101455.0 105832.0 107088.5 109771.1 113534.4 89097.6 94344.5 95428.5 97107.0 99487.5 84167.6 85838.6 87773.0 88773.6 89965.0 12454.6 13139.5 13246.4 13255.3 13370.2 38742.8 38885.4 39248.8 39535.8 40205.0 63779.8 66990.0 67706.0 69315.7 70689.6 17188.8 17330.4 17343.2 17363.1 17420.7 19171.6 20459.9 20572.6 21229.3 21406.6 18609.6 18681.7 18805.4 19298.2 19398.2 18903.6 19085.2 19161.2 19317.7 19467.9 17887.1 18297.9 18788.7 18832.4 19013.4 13706.7 13873.2 14095.7 14102.6 14302.6 13633.8 13757.6 13924.0 14343.7 14536.9 19:16:51 INFO - PID 5951 | -------- Summary: end -------- 19:16:51 INFO - PID 5951 | 19:16:51 INFO - TEST-INFO | 5951: exit 0 19:16:51 INFO - TEST-OK | dromaeo_css | took 1111995ms 19:16:51 INFO - TEST-START | kraken 19:16:51 INFO - Initialising browser for kraken test... 19:16:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51982/getInfo.html -profile /tmp/tmpz_Xk_e/profile 19:16:51 INFO - TEST-INFO | started process 6331 (/builds/slave/test/build/application/firefox/firefox http://localhost:51982/getInfo.html) 19:16:57 INFO - TEST-INFO | 6331: exit 0 19:16:57 INFO - Browser initialized. 19:16:57 INFO - Running cycle 1/1 for kraken test... 19:16:57 INFO - TEST-INFO | started process 6568 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpz_Xk_e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:16:59 INFO - PID 6568 | 19:16:59 INFO - PID 6568 | (/builds/slave/test/build/application/firefox/firefox:6622): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:16:59 INFO - PID 6568 | 19:17:09 INFO - PID 6568 | RSS: Main: 168284160 19:17:09 INFO - PID 6568 | 19:17:12 INFO - PID 6568 | Cycle 1(1): loaded http://localhost:51982/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51982/tests/kraken/driver.html?testName=audio-beat-detection) 19:17:12 INFO - PID 6568 | RSS: Main: 174825472 19:17:12 INFO - PID 6568 | 19:17:15 INFO - PID 6568 | Cycle 1(1): loaded http://localhost:51982/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51982/tests/kraken/driver.html?testName=audio-dft) 19:17:16 INFO - PID 6568 | RSS: Main: 178245632 19:17:16 INFO - PID 6568 | 19:17:19 INFO - PID 6568 | Cycle 1(1): loaded http://localhost:51982/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51982/tests/kraken/driver.html?testName=audio-fft) 19:17:20 INFO - PID 6568 | RSS: Main: 171786240 19:17:20 INFO - PID 6568 | 19:17:22 INFO - PID 6568 | Cycle 1(1): loaded http://localhost:51982/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51982/tests/kraken/driver.html?testName=audio-oscillator) 19:17:22 INFO - PID 6568 | RSS: Main: 172539904 19:17:22 INFO - PID 6568 | 19:17:25 INFO - PID 6568 | Cycle 1(1): loaded http://localhost:51982/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51982/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:17:25 INFO - PID 6568 | RSS: Main: 172855296 19:17:25 INFO - PID 6568 | 19:17:28 INFO - PID 6568 | Cycle 1(1): loaded http://localhost:51982/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51982/tests/kraken/driver.html?testName=imaging-darkroom) 19:17:29 INFO - PID 6568 | RSS: Main: 174477312 19:17:29 INFO - PID 6568 | 19:17:33 INFO - PID 6568 | Cycle 1(1): loaded http://localhost:51982/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51982/tests/kraken/driver.html?testName=imaging-desaturate) 19:17:33 INFO - PID 6568 | RSS: Main: 173801472 19:17:33 INFO - PID 6568 | 19:17:36 INFO - PID 6568 | Cycle 1(1): loaded http://localhost:51982/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51982/tests/kraken/driver.html?testName=json-parse-financial) 19:17:37 INFO - PID 6568 | RSS: Main: 174579712 19:17:37 INFO - PID 6568 | 19:17:38 INFO - PID 6568 | Cycle 1(1): loaded http://localhost:51982/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51982/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:17:39 INFO - PID 6568 | RSS: Main: 174981120 19:17:39 INFO - PID 6568 | 19:17:41 INFO - PID 6568 | Cycle 1(1): loaded http://localhost:51982/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51982/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:17:42 INFO - PID 6568 | RSS: Main: 175009792 19:17:42 INFO - PID 6568 | 19:17:44 INFO - PID 6568 | Cycle 1(1): loaded http://localhost:51982/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51982/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:17:44 INFO - PID 6568 | RSS: Main: 174854144 19:17:44 INFO - PID 6568 | 19:17:47 INFO - PID 6568 | Cycle 1(1): loaded http://localhost:51982/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51982/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:17:48 INFO - PID 6568 | RSS: Main: 175157248 19:17:48 INFO - PID 6568 | 19:17:51 INFO - PID 6568 | Cycle 1(1): loaded http://localhost:51982/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51982/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:17:52 INFO - PID 6568 | RSS: Main: 175030272 19:17:52 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | Cycle 1(1): loaded http://localhost:51982/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51982/tests/kraken/driver.html?testName=ai-astar) 19:17:54 INFO - PID 6568 | RSS: Main: 176115712 19:17:54 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | __start_tp_report 19:17:54 INFO - PID 6568 | _x_x_mozilla_page_load 19:17:54 INFO - PID 6568 | _x_x_mozilla_page_load_details 19:17:54 INFO - PID 6568 | |i|pagename|runs| 19:17:54 INFO - PID 6568 | |0;ai-astar;107;107;101;109;115;110;109;100;109;108 19:17:54 INFO - PID 6568 | |1;audio-beat-detection;148;123;150;182;119;157;157;120;179;120 19:17:54 INFO - PID 6568 | |2;audio-dft;166;163;164;165;164;168;165;171;162;163 19:17:54 INFO - PID 6568 | |3;audio-fft;75;76;74;172;76;76;76;90;101;75 19:17:54 INFO - PID 6568 | |4;audio-oscillator;84;79;133;78;78;80;130;84;79;133 19:17:54 INFO - PID 6568 | |5;imaging-gaussian-blur;118;114;121;115;114;115;115;114;115;121 19:17:54 INFO - PID 6568 | |6;imaging-darkroom;152;153;153;150;152;152;153;153;152;151 19:17:54 INFO - PID 6568 | |7;imaging-desaturate;95;94;94;94;94;94;100;94;94;94 19:17:54 INFO - PID 6568 | |8;json-parse-financial;55;54;54;56;57;59;59;54;55;57 19:17:54 INFO - PID 6568 | |9;json-stringify-tinderbox;55;64;59;54;54;55;59;54;54;54 19:17:54 INFO - PID 6568 | |10;stanford-crypto-aes;91;78;79;79;79;92;78;89;79;86 19:17:54 INFO - PID 6568 | |11;stanford-crypto-ccm;127;131;130;122;149;132;128;127;122;172 19:17:54 INFO - PID 6568 | |12;stanford-crypto-pbkdf2;194;181;198;185;177;181;184;185;176;176 19:17:54 INFO - PID 6568 | |13;stanford-crypto-sha256-iterative;74;76;70;74;73;70;70;75;70;78 19:17:54 INFO - PID 6568 | __end_tp_report 19:17:54 INFO - PID 6568 | __start_cc_report 19:17:54 INFO - PID 6568 | _x_x_mozilla_cycle_collect,972 19:17:54 INFO - PID 6568 | __end_cc_report 19:17:54 INFO - PID 6568 | __startTimestamp1502417874255__endTimestamp 19:17:54 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | ------- Summary: start ------- 19:17:54 INFO - PID 6568 | Number of tests: 14 19:17:54 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | [#0] ai-astar Cycles:10 Average:107.50 Median:109.00 stddev:4.33 (4.0%) stddev-sans-first:4.59 19:17:54 INFO - PID 6568 | Values: 107.0 107.0 101.0 109.0 115.0 110.0 109.0 100.0 109.0 108.0 19:17:54 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | [#1] audio-beat-detection Cycles:10 Average:145.50 Median:153.50 stddev:24.16 (15.7%) stddev-sans-first:25.61 19:17:54 INFO - PID 6568 | Values: 148.0 123.0 150.0 182.0 119.0 157.0 157.0 120.0 179.0 120.0 19:17:54 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | [#2] audio-dft Cycles:10 Average:165.10 Median:165.00 stddev:2.69 (1.6%) stddev-sans-first:2.83 19:17:54 INFO - PID 6568 | Values: 166.0 163.0 164.0 165.0 164.0 168.0 165.0 171.0 162.0 163.0 19:17:54 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | [#3] audio-fft Cycles:10 Average:89.10 Median:76.00 stddev:30.42 (40.0%) stddev-sans-first:31.83 19:17:54 INFO - PID 6568 | Values: 75.0 76.0 74.0 172.0 76.0 76.0 76.0 90.0 101.0 75.0 19:17:54 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:79.00 stddev:25.09 (31.8%) stddev-sans-first:26.24 19:17:54 INFO - PID 6568 | Values: 84.0 79.0 133.0 78.0 78.0 80.0 130.0 84.0 79.0 133.0 19:17:54 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | [#5] imaging-gaussian-blur Cycles:10 Average:116.20 Median:115.00 stddev:2.78 (2.4%) stddev-sans-first:2.87 19:17:54 INFO - PID 6568 | Values: 118.0 114.0 121.0 115.0 114.0 115.0 115.0 114.0 115.0 121.0 19:17:54 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | [#6] imaging-darkroom Cycles:10 Average:152.10 Median:152.50 stddev:0.99 (0.7%) stddev-sans-first:1.05 19:17:54 INFO - PID 6568 | Values: 152.0 153.0 153.0 150.0 152.0 152.0 153.0 153.0 152.0 151.0 19:17:54 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.00 stddev:1.89 (2.0%) stddev-sans-first:2.00 19:17:54 INFO - PID 6568 | Values: 95.0 94.0 94.0 94.0 94.0 94.0 100.0 94.0 94.0 94.0 19:17:54 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:56.50 stddev:1.94 (3.4%) stddev-sans-first:2.03 19:17:54 INFO - PID 6568 | Values: 55.0 54.0 54.0 56.0 57.0 59.0 59.0 54.0 55.0 57.0 19:17:54 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | [#9] json-stringify-tinderbox Cycles:10 Average:56.20 Median:55.00 stddev:3.39 (6.2%) stddev-sans-first:3.57 19:17:54 INFO - PID 6568 | Values: 55.0 64.0 59.0 54.0 54.0 55.0 59.0 54.0 54.0 54.0 19:17:54 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:82.50 stddev:5.81 (7.0%) stddev-sans-first:5.40 19:17:54 INFO - PID 6568 | Values: 91.0 78.0 79.0 79.0 79.0 92.0 78.0 89.0 79.0 86.0 19:17:54 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | [#11] stanford-crypto-ccm Cycles:10 Average:134.00 Median:130.50 stddev:15.35 (11.8%) stddev-sans-first:16.07 19:17:54 INFO - PID 6568 | Values: 127.0 131.0 130.0 122.0 149.0 132.0 128.0 127.0 122.0 172.0 19:17:54 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.70 Median:184.50 stddev:7.39 (4.0%) stddev-sans-first:6.84 19:17:54 INFO - PID 6568 | Values: 194.0 181.0 198.0 185.0 177.0 181.0 184.0 185.0 176.0 176.0 19:17:54 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.00 Median:74.00 stddev:2.91 (3.9%) stddev-sans-first:3.06 19:17:54 INFO - PID 6568 | Values: 74.0 76.0 70.0 74.0 73.0 70.0 70.0 75.0 70.0 78.0 19:17:54 INFO - PID 6568 | -------- Summary: end -------- 19:17:54 INFO - PID 6568 | 19:17:54 INFO - PID 6568 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 19:17:54 INFO - TEST-INFO | 6568: exit 0 19:17:55 INFO - TEST-OK | kraken | took 63225ms 19:17:55 INFO - SUITE-END | took 1175s 19:17:55 INFO - Completed test suite (00:19:35) 19:17:55 INFO - javascript benchmark 19:17:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8589.357407212758, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3128.5463607776887, 3138.1213260287122, 3172.349668317995, 3191.888283910064, 3201.3597280543895, 6138.294096178941, 6237.937620623792, 6287.59130656507, 6302.40127187917, 6355.459785918202, 6159.5380346474085, 6212.5029997600195, 6214.8135555933395, 6225.035119556464, 6300.527460440462, 5693.288338957641, 5702.116171993348, 5713.114467257561, 5714.857128571798, 5753.597248192607, 5758.798442054529, 5784.363719990798, 5789.942100578983, 5800.9129863052085, 5842.269716285465, 5726.570507211955, 5799.652020878761, 5806.071028635419, 5824.970875145618, 5895.380984996578, 5578.9163162552595, 5733.856653583652, 5738.684372359518, 5827.912581311284, 5847.064469684851, 5675.091985282376, 5731.283589551306, 5738.167965645, 5743.0, 5773.422657734235, 6044.607100538451, 6163.815085547396, 6243.843903902393, 6280.560360774748, 6292.87414251717, 6620.569662971891, 6635.270120286765, 6658.3674550917585, 6667.1332726745695, 6683.3650803174105, 6746.93253067468, 6750.493712971498, 6751.189857217117, 6754.932450675479, 6758.526903116784, 6173.135760993465, 6173.938260617426, 6179.907301390483, 6209.16176316196, 6299.622022678654, 6869.896951545631, 6884.621345825927, 6935.410490108397, 6971.65141742921, 7019.92980070203, 6516.674166291667, 6621.602703837785, 6627.635480048643, 6665.433438157714, 6705.631190284484, 6597.241317248482, 6719.664016799141, 6736.494762892803, 6752.561083529555, 6849.349311815369, 6550.639714815731, 6643.60138391698, 6654.3345665434035, 6661.100751398596, 6675.899861502081, 6558.049082882955, 6604.702788374535, 6683.933160668428, 6698.095757072733, 6719.899201511981, 5275.340582427197, 5288.0, 5302.708351040653, 5303.4696530346655, 5306.973465132727, 4450.109978004412, 4550.408446901882, 4556.2937744649635, 4583.152116858392, 4586.610138138229, 5682.687452190169, 5718.857028574319, 5790.623609465456, 5801.9129713054335, 5821.388754180834, 6763.7632682855865, 6780.864382712318, 6847.21257055445, 6864.931350686429, 6874.793756187322, 5654.321481422256, 5758.769649213985, 5787.160861675034, 5791.82624521265, 5853.268341457318, 5878.2064421302575, 5912.379200184005, 5941.465268125803, 5966.880662386728, 5970.820875373745, 6558.606483610999, 6566.146400967939, 6597.241317248482, 6601.174853143357, 6650.900236496456, 6720.596764194164, 6780.627065511444, 6787.185537735503, 6879.690413931285, 6886.552374095668, 6537.0521274413995, 6545.21457425093, 6548.540054903918, 6649.434798042221, 6656.933430665825, 6464.4828413725845, 6555.410013098844, 6558.704858281293, 6564.113844630913, 6569.441597464078, 6641.435477984425, 6712.362325579063, 6725.697343619647, 6727.78899798041, 6732.562383445061, 6415.230172379344, 6450.967745161244, 6485.897397442541, 6486.4486518646445, 6486.902696459462, 6346.555741098264, 6354.269259035085, 6359.14151589548, 6367.649779261999, 6370.649614271074, 6014.5901419836255, 6288.245410550763, 6290.654014029273, 6333.240011198502, 6333.303336632875, 6371.553991220755, 6384.233891932998, 6420.293767685457, 6456.0, 6530.542861999615, 6206.813795586139, 6256.436920677161, 6300.873982520232, 6302.464290535172, 6331.556791024584], "value": 6096.481750437534, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [843.3421930893904, 874.7463235661659, 899.064972428674, 899.5277479323354, 1171.379169040409, 30808.075757727274, 32256.67743322569, 42407.36388954161, 43447.78276108615, 43560.56439435605, 42825.78587107068, 43357.78321108398, 43387.566124338744, 43481.782591087, 43516.78241608787, 7761.844763104735, 7888.211178882095, 7895.368370530373, 7909.090454597708, 7916.366690664762, 7770.689172433124, 7803.258690424429, 7909.485883417559, 7914.722984695537, 7916.366690664762, 7901.170377110407, 7901.723439679612, 7954.681812727484, 7965.800854978614, 7970.920290797076, 7894.407919406039, 7899.8420031599335, 7904.2886140247365, 7916.168802275735, 7919.128895821455, 7697.923020769833, 7740.303372696428, 7741.7677469676, 7749.535027898288, 7751.069871615386, 39838.60161398378, 40115.197696046205, 40197.59802401968, 40217.79891100526, 40247.0, 3602.225521512887, 3644.9976256529403, 3645.0, 3647.1246900743895, 3649.124210189562, 31719.84140079308, 31823.204419889687, 31832.840835795905, 31888.20279493008, 31889.681103189134, 4900.803967841318, 4905.607551395879, 4913.213885778258, 4926.359573255454, 4932.80268789248, 193012.0, 193204.0, 193283.0, 193801.0, 194215.99999999857, 49261.261081083816, 49480.25779613309, 49518.50481495211, 49561.0, 49659.503404965486, 51311.74344128255, 51384.229236561485, 51397.0, 51480.0, 51510.22734658983, 16303.510894673178, 16455.0, 16474.341026359052, 16474.917625411796, 16480.0935948524, 13408.932955335162, 13512.662183445493, 13588.93205533966, 13626.795598066037, 13642.795358069638, 14022.0, 14197.0, 14204.289785510684, 14222.715545689236, 14378.424863005572, 5271.65734227274, 5303.632193557042, 5370.436104209002, 5372.731363431891, 5426.158945363475, 1014.5434554450379, 1016.7254841192985, 1023.6059117239917, 1029.4132344563527, 1037.227265687053, 22705.772942270698, 23614.527709445716, 29738.256543586584, 29994.0, 30236.546451803242, 1098.1544210957677, 1104.939228342433, 1123.2081382625229, 1124.8256520239377, 1125.178619607691, 12760.6809829755, 12781.424835882408, 16308.0, 16377.0, 16774.49676509707, 206334.0, 210570.0, 219454.0, 220569.0, 221533.0, 58470.0, 59514.10728839071, 59577.70211148917, 59579.0, 59593.0, 1503.8045054142892, 1508.0, 1509.1548732709718, 1509.7433436315632, 1525.488961198011, 27950.30124246829, 28039.43921121607, 28369.716302836707, 28470.43059138847, 29199.85400073071, 39347.0, 39402.60597393989, 39511.2097758049, 39571.0, 39585.2082958345, 190971.0, 191216.0, 191332.0, 191406.0, 191485.0, 35109.0, 35237.0, 35238.29523409569, 35425.0, 37522.624773751915, 49144.50855491399, 49443.0, 49567.50432495629, 49938.25092623541, 50029.74985125051, 36269.63730362768, 36316.636833631324, 36321.81839090788, 36711.449328260635, 36772.63227367692, 33276.83361583177, 33401.83299083489, 34327.485087724206, 34466.0, 34537.48193777146, 39941.80029099836, 41294.1741165181, 41509.58490415057, 41656.58343416527, 41995.0, 42159.1568168641, 42215.155696886504, 42711.0, 42725.35911961261, 42963.0, 24581.508369832147, 26263.474730504902, 26336.604950926136, 26361.60457593176, 26409.60385594256], "value": 18060.594825393506, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.2032474859943, 575.3670961941867, 578.5429510686558, 582.2983305117333, 584.9210356601859, 22418.43953901153, 22480.88759556202, 23157.0, 23228.18701345451, 23370.415739606517, 26454.074107406246, 27005.729942700615, 27008.0, 27023.18930432085, 27036.1889143326, 6489.351064893508, 6631.336866313378, 6667.966660166692, 6668.299828518008, 6670.032845237438, 5884.499817515516, 6227.346128656493, 6356.682165891687, 6393.264774550917, 6413.390727880844, 6363.490920726331, 6376.649284289362, 6379.71291291893, 6383.39357761012, 6383.904241436382, 6349.42855143037, 6349.904751428732, 6363.777267795628, 6375.744970201186, 6385.042243663441, 5954.8809023819285, 5961.658626808977, 5965.105234214859, 5970.402959704038, 5976.432238937294, 28451.715482845113, 29134.708652913625, 29674.258143546584, 29924.401511969856, 30170.84914575435, 1907.160849226336, 1949.3664559018375, 1952.6485232658115, 1953.990230048855, 1956.4619729574338, 19021.61956760857, 19379.321723739744, 19395.515112122168, 20245.39263822073, 20354.796452035585, 2456.1894574790276, 2466.5066986602574, 2521.0924067335745, 2525.7726804587674, 2535.657686212356, 143419.0, 144668.0, 145576.0, 145641.0, 145795.0, 14489.927550362181, 15015.549533513793, 15043.924780376028, 15138.470153544793, 15253.771193432107, 23560.0, 23580.29259122229, 23613.0, 23615.29154125379, 23615.29154125379, 10359.94820025895, 10623.20325975555, 10730.517126729315, 10766.192535559865, 10775.892241077645, 8091.474054186459, 10001.34991225568, 10028.946960569036, 10070.69787906364, 10120.69637910849, 8230.135835737281, 9367.250619950388, 9509.144177024102, 9516.762080947894, 9616.182624476858, 2824.2939265183704, 2838.815476993989, 2858.6712528059124, 2863.656361236665, 2945.528715405525, 947.1381043250747, 960.3280042592875, 968.1528662420383, 969.6121551379505, 972.4991629310911, 19396.0, 19466.90266548658, 19698.0, 19844.60310793776, 19910.800891991184, 1029.248648486609, 1032.9070383665505, 1035.8653375061194, 1044.2585764107416, 1045.5556388534994, 11024.559017639205, 11060.723481913017, 11060.834087488694, 11123.221374503708, 11164.720881977853, 498105.0, 501318.0, 502320.0, 504161.0, 515802.0, 116321.4183929075, 121800.0, 121944.0, 127732.36133819458, 130787.0, 1270.764908491932, 1277.067740549404, 1325.4499382756242, 1326.423005992377, 1326.661701266171, 20257.493562661053, 20447.38657840267, 20526.281580144623, 20765.273215437388, 21154.788452115896, 59556.70221648864, 60427.69786151041, 60430.0, 60758.39241607527, 61821.38178618156, 76593.23406765862, 76956.23043769714, 77269.0, 77388.61305693435, 77415.0, 14486.85513144855, 14630.634234144232, 14694.191819450032, 14776.113433194043, 14806.111633302036, 23009.424764381023, 23180.0, 23183.53632927366, 23204.883975580015, 23244.883775581013, 17952.730709039115, 18058.187381568125, 18192.545186370447, 18226.544336391697, 18283.26866925308, 14424.278786060864, 14573.198474084027, 15104.697906042038, 16050.19749012568, 16078.758818617496, 19233.711494327876, 19575.0, 19598.804011960267, 19669.508262292984, 19727.60544789125, 14410.135391876522, 14645.48740794067, 14655.193964332064, 14686.706265874836, 14686.926565367105, 18719.90640046791, 18957.336493222672, 18982.335618253295, 18985.715214272073, 18998.335058272893], "value": 12187.288840508361, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1348.9999999999998, 1387.4172847404088, 1402.389960367241, 1402.866727660872, 1416.4263473293317, 14048.859511404895, 14687.632809179762, 14693.485727999521, 14719.484818031371, 14799.260036998148, 14199.574012779607, 14667.926660366684, 15216.543503694907, 15254.618634534143, 15288.388464461435, 2707.918762437125, 2775.1674497650624, 2776.6390369251967, 2777.5833624956317, 2785.6517935258094, 2634.7365263473594, 2679.4239238563705, 2737.137801592502, 2746.917592472229, 2751.7110703376156, 2697.608846717225, 2702.202850159198, 2726.3456770375165, 2743.231895069384, 2743.492453896026, 2629.3295209721496, 2637.9076732314375, 2655.0574546036073, 2655.1636234586235, 2657.8139530232697, 2584.4572639745675, 2628.053900595803, 2632.697239817426, 2634.7892168626654, 2636.1564299424, 14218.573442796733, 14239.501617443444, 14395.35220915051, 14444.855551444562, 14452.0, 7548.886766698504, 7842.0, 7842.529448233125, 7861.135275119732, 7862.567558784264, 4983.202687569971, 5121.820736274212, 5157.277981082651, 5246.81636142737, 5256.106461901486, 1909.608530251309, 1912.0917564156914, 1926.1332400419867, 1928.5275107598588, 1932.9710054349196, 23835.523289534285, 23876.40308992272, 23876.64185037226, 23950.16174433904, 23988.64017039746, 6637.435817955431, 7112.6443677815905, 7127.82180445493, 7371.852562948711, 7380.114386273682, 16179.191040447931, 16287.267072981744, 16313.265903034391, 16328.591785205228, 16336.428225012305, 7743.9612801936755, 7861.449698521049, 7866.09539902907, 7876.173001834725, 7916.960415197887, 5840.123981402823, 6013.639181649115, 6223.751049957951, 6223.782167624202, 6323.494120470351, 5243.580513558906, 5359.464053594609, 5386.865328366744, 5538.972305138529, 5554.333479982347, 1532.7241096602718, 1561.1023661394743, 1567.6551158745058, 1577.242923396776, 1577.6055986003498, 883.337496877342, 908.2280061947292, 926.5274709898, 934.6354921580589, 939.2392162348519, 7692.269234422721, 7704.961475192588, 7720.575368354795, 7783.649735761905, 7802.492837965514, 657.5627207906784, 662.7415308029872, 672.3142394757418, 686.9587824730532, 689.8309914070984, 5194.22086687, 5303.098473259555, 5308.92036619451, 5414.972925135349, 5491.258680078218, 19600.70598941017, 19857.503562411057, 20013.499662508555, 20324.49188770263, 20340.389788306373, 19507.609847802963, 19911.701324480146, 19936.70094948577, 20179.899100504404, 20204.39386818398, 10930.781384372269, 11006.779864402828, 11112.333260004263, 11245.212835101463, 11322.150838686966, 8204.425690201628, 8382.0, 8385.790355240924, 8398.41211115216, 8422.95788521078, 16845.41041063557, 17176.0, 17234.82765172332, 17303.65392692114, 17446.302147914448, 17715.29138834432, 17721.379751708628, 17804.554886127433, 18023.0, 18589.628207436046, 11478.139139564399, 11626.593069242535, 11695.298282103102, 11728.94135529317, 11729.706757330794, 13314.866851331362, 13384.665383365495, 13540.052196346162, 13695.109817861807, 13696.52062177819, 10960.725981850517, 11076.889231107585, 11114.277571957797, 11190.94404527972, 11248.156388271076, 10454.63408780689, 10713.910787396382, 10745.462726863781, 10932.890671093188, 10959.616413425492, 9308.348415610842, 9586.137247647745, 9839.065288797552, 9972.900270997197, 9974.950125249326, 7357.3746231568775, 7480.775576732707, 7546.584937828472, 7586.317231449196, 7627.389808815171, 7267.0, 7296.306850849161, 7340.853182936418, 7399.075115610549, 7407.777766667009], "value": 6475.407827385312, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.90778298231857, 481.44393225824166, 496.3770468062582, 509.0658641393044, 512.9512696293854, 6188.226471691039, 6286.8113956581265, 6294.150289710888, 6297.307296197415, 6324.177856878605, 5962.642241465515, 6101.511879049676, 6123.938760612393, 6126.387361263872, 6183.690815459231, 4897.191963326047, 4925.581325587321, 4940.51784464661, 4964.205727083668, 4969.527894850002, 4749.501302363236, 4962.950370496285, 4964.0, 4969.583668654434, 4971.850844474671, 4878.756062196893, 4895.853124406273, 4915.591360233478, 4924.48851085947, 4948.752562371885, 4876.171050921352, 4923.630727695438, 4977.452480227172, 4984.327115839352, 4991.376077990251, 4389.2977123660385, 4436.689431739779, 4447.51077381491, 4458.197524445599, 4470.619997300233, 5987.6108052976415, 6015.0, 6042.667653279068, 6047.183630209909, 6071.0, 5665.745041473144, 5673.865226954585, 5692.601517893749, 5720.742566584514, 5723.255976723041, 6288.182536270256, 6360.586561873463, 6423.7430502779835, 6424.229092508882, 6429.582077165016, 4788.449328327252, 4801.159797035505, 4805.0, 4818.68678535894, 4859.757012149414, 6804.965975170093, 6820.624865632388, 6900.585964842126, 6907.16960005604, 6961.408280296181, 4914.631402644744, 4930.33440485537, 4940.6788558743565, 4957.975210123926, 4970.726610036482, 5046.772895219724, 5055.0, 5057.671251368649, 5059.747012649353, 5088.160453525201, 5609.354924183689, 5637.492625663628, 5706.144078388275, 5721.828345149652, 5738.512226460714, 6515.283318834924, 6553.984132459476, 6561.311062338385, 6596.571222870498, 6621.370969757962, 1637.1323198704706, 1653.8180800112218, 1707.6670049340507, 1744.2151032035636, 1745.2408202431982, 1182.8462299900957, 1199.5261871560858, 1202.4108186988312, 1206.2943178240653, 1212.8665846756849, 5562.610617256753, 5624.521915637135, 5632.85917852057, 5637.701944362594, 5705.172749951317, 6478.4169424752, 6590.901136482957, 6653.401193892573, 6707.66461676924, 6746.83132921671, 5743.770249190069, 5771.192033115367, 5781.306243250837, 5785.71071446426, 5786.508146807484, 3533.434650455915, 3534.893953181357, 3546.929061418757, 3571.8035508047305, 3607.9639203607626, 6527.0, 6560.606363618198, 6605.4055135037115, 6606.084235571727, 6653.733850646016, 6890.793276201722, 6938.826529336807, 7005.404540614003, 7012.719491220396, 7021.508494405445, 6513.902291465537, 6533.18335208099, 6538.869222615521, 6540.672966351759, 6567.31043240472, 6684.16447944007, 6712.966435167793, 6756.662166891734, 6782.966085169543, 6784.6946887389195, 6622.006698994996, 6647.634380109141, 6733.091032710521, 6768.932310676831, 6780.559263647847, 6450.8064758057335, 6477.631631551661, 6511.283758786624, 6524.641144737085, 6546.672666366758, 4895.33912921751, 4933.9013219736125, 4947.957006440941, 4951.653384263067, 4961.1318019345745, 6673.165854268216, 6707.8993815091835, 6716.112071809169, 6806.285340039224, 6809.216940051958, 6450.129462455835, 6539.934600654123, 6547.214334279726, 6558.0, 6565.179352580927, 6174.722137503733, 6438.0, 6440.194975628046, 6452.741890324334, 6489.383508566679, 6233.875322493615, 6308.895943209828, 6356.841078973062, 6425.485961123192, 6464.903026454513, 6416.615003099829, 6505.024246362894, 6576.309487503743, 6589.374009469093, 6616.3789871481795, 6310.842228944313, 6354.0, 6365.809025729235, 6376.585521941059, 6378.77674281398], "value": 5023.516042814212, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [861.6984055580543, 886.9113088691128, 893.9942564614809, 912.6623149434708, 921.8571121476172, 18233.726494102582, 18859.0, 18896.338628148016, 18924.432267031993, 21087.0, 19845.007749612534, 20394.0, 20507.17971281147, 20538.897305513452, 20571.485712857186, 1014.3457469931908, 1054.620336678795, 1056.550965839519, 1058.227493929432, 1058.30680904008, 1025.9794804103913, 1039.5633833789827, 1046.9424181670006, 1055.7993981143559, 1057.3338796558196, 1087.2063393722588, 1087.9728006799814, 1088.8638920134983, 1092.0, 1092.7705181911808, 1077.2351630342466, 1077.2459278505, 1077.3751224289892, 1077.8113830079744, 1079.061216741436, 776.8990031295896, 777.0830420104274, 777.9260970207821, 782.22950393862, 782.7377828427484, 18365.265389384407, 18419.0, 18920.243190272373, 18990.62018759631, 19148.521286967796, 20518.0, 20631.174753009862, 20911.68632470514, 20976.5804683907, 21024.0, 15574.299156537983, 16033.278502467418, 16049.919750401174, 16113.758293625606, 16162.434314798926, 3631.473436351714, 3671.0271777978855, 3680.411134218539, 3702.963170312317, 3704.8888533344043, 120087.0, 121368.39315803452, 124023.3798831009, 126229.0, 128548.0, 15506.379744810307, 15901.204939752966, 16652.916735416246, 16820.579485512717, 16834.24245908937, 31119.377612448076, 31385.37229255402, 31808.363832723215, 31811.68188318087, 31830.0, 8594.140585941355, 8772.736817895473, 8809.339299552432, 8815.0, 8843.69047083349, 4142.420061191436, 4217.135487225111, 4228.725132866354, 4230.048239146229, 4232.936505952413, 4924.507549245119, 5105.285260063594, 5120.436751957282, 5133.589312854962, 5146.485351464824, 1006.6325791086347, 1029.6499190275342, 1037.9325343852777, 1038.7974345002804, 1038.8389799581078, 661.6823924516259, 676.2966514824626, 678.9355011273921, 685.0990946904805, 692.8510370270416, 11123.833142502706, 11273.549058037586, 11956.103292253116, 12892.935535322264, 13671.658208544866, 704.084689903124, 719.7732714195012, 719.9460040496983, 724.4530379563396, 726.1939247435342, 5287.814926477555, 5453.509184173444, 5627.43725627434, 5828.854278643068, 5834.154047663065, 101455.0, 105832.0, 107088.4645576767, 109771.09378512019, 113534.43232783783, 89097.55451222832, 94344.52827735955, 95428.52285738527, 97107.0, 99487.50256248673, 84167.57916210502, 85838.57080714681, 87773.0, 88773.55613221893, 89965.0, 12454.564090256797, 13139.474421023053, 13246.403911823798, 13255.337233138112, 13370.197788132744, 38742.80628596839, 38885.416718748675, 39248.80375598218, 39535.80232098821, 40204.99487512836, 63779.751935968095, 66990.0, 67706.0, 69315.65342173257, 70689.64655176691, 17188.82811171872, 17330.39343622967, 17343.2195551198, 17363.13184340803, 17420.738688919424, 19171.61656766829, 20459.897700511996, 20572.58854822925, 21229.256976005767, 21406.57186856223, 18609.627807443503, 18681.71977420367, 18805.435836924913, 19298.22807087757, 19398.224071037, 18903.621927561097, 19085.236590536777, 19161.233550657817, 19317.710234346778, 19467.90266048661, 17887.105644717452, 18297.908510457364, 18788.718169227745, 18832.435026949213, 19013.42959711211, 13706.65733356674, 13873.236971966235, 14095.718085638026, 14102.647433814236, 14302.642433938818, 13633.795493067413, 13757.587272381845, 13924.0, 14343.709066184094, 14536.927315363355], "value": 9199.038212628431, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1551.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 107.0, 101.0, 109.0, 115.0, 110.0, 109.0, 100.0, 109.0, 108.0], "value": 107.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 123.0, 150.0, 182.0, 119.0, 157.0, 157.0, 120.0, 179.0, 120.0], "value": 145.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 164.0, 165.0, 164.0, 168.0, 165.0, 171.0, 162.0, 163.0], "value": 165.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 172.0, 76.0, 76.0, 76.0, 90.0, 101.0, 75.0], "value": 89.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 79.0, 133.0, 78.0, 78.0, 80.0, 130.0, 84.0, 79.0, 133.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 114.0, 121.0, 115.0, 114.0, 115.0, 115.0, 114.0, 115.0, 121.0], "value": 116.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 153.0, 153.0, 150.0, 152.0, 152.0, 153.0, 153.0, 152.0, 151.0], "value": 152.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 100.0, 94.0, 94.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 56.0, 57.0, 59.0, 59.0, 54.0, 55.0, 57.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 64.0, 59.0, 54.0, 54.0, 55.0, 59.0, 54.0, 54.0, 54.0], "value": 56.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 78.0, 79.0, 79.0, 79.0, 92.0, 78.0, 89.0, 79.0, 86.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 131.0, 130.0, 122.0, 149.0, 132.0, 128.0, 127.0, 122.0, 172.0], "value": 134.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 181.0, 198.0, 185.0, 177.0, 181.0, 184.0, 185.0, 176.0, 176.0], "value": 183.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 70.0, 74.0, 73.0, 70.0, 70.0, 75.0, 70.0, 78.0], "value": 73.0, "unit": "score"}]}]} 19:17:55 INFO - Return code: 0 19:17:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:17:55 INFO - # TBPL SUCCESS # 19:17:55 INFO - Running post-action listener: _package_coverage_data 19:17:55 INFO - Running post-action listener: _resource_record_post_action 19:17:55 INFO - [mozharness: 2017-08-11 02:17:55.517072Z] Finished run-tests step (success) 19:17:55 INFO - Running post-run listener: _resource_record_post_run 19:17:55 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 20480; Write bytes: 440987648; Read time: 240; Write time: 304412 19:17:55 INFO - TinderboxPrint: CPU usage
13.2% 19:17:55 INFO - TinderboxPrint: I/O read bytes / time
20,480 / 240 19:17:55 INFO - TinderboxPrint: I/O write bytes / time
440,987,648 / 304,412 19:17:55 INFO - TinderboxPrint: CPU idle
8,265.0 (86.8%) 19:17:55 INFO - TinderboxPrint: CPU user
1,203.5 (12.6%) 19:17:55 INFO - TinderboxPrint: Swap in / out
0 / 0 19:17:55 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 118165504; Read time: 0; Write time: 91568 19:17:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:17:55 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 16384; Write bytes: 322822144; Read time: 228; Write time: 212844 19:17:56 INFO - Running post-run listener: _upload_blobber_files 19:17:56 INFO - Blob upload gear active. 19:17:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:17:56 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 19:17:56 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:17:56 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:17:56 INFO - (blobuploader) - INFO - Open directory for files ... 19:17:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:17:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:17:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:57 INFO - (blobuploader) - INFO - Done attempting. 19:17:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:17:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:18:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:18:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:18:05 INFO - (blobuploader) - INFO - Done attempting. 19:18:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:18:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:18:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:18:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:18:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:18:06 INFO - (blobuploader) - INFO - Done attempting. 19:18:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:18:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:18:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:18:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:18:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:18:07 INFO - (blobuploader) - INFO - Done attempting. 19:18:07 INFO - (blobuploader) - INFO - Iteration through files over. 19:18:07 INFO - Return code: 0 19:18:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:18:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:18:07 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a16b0971350d60f295bed0fc1a8606751a70622cf49101b5ff4845d458197439f032768b46d9891c24c3e74eb3a639b5263514eb47dad111530811e36103ab3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3069070e88d6cf981d05612428fed14e265817749927fc1bd68fda64b705a7d4782eeeebdfd92bcf04cfb0c3402c0c25675828ba2754fa8105ae5e5f9cc604c5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d41af5b089cdccaab28db41a61598f579d343e61820556e25bb82ce80a387182014183761ab1f876f380e11edc2a977b21922e32b9117c3e4acc931de47c948", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/734ad3c804f8ab60c69d33c9b6fffc86d3dcf6276e96ae1b2c4365074a32bab52a0729d40844c2338a2ee81e5a4dc5f6d29c14ccd6146b33494f621fb7ef90eb"} 19:18:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:18:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:18:07 INFO - Contents: 19:18:07 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a16b0971350d60f295bed0fc1a8606751a70622cf49101b5ff4845d458197439f032768b46d9891c24c3e74eb3a639b5263514eb47dad111530811e36103ab3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3069070e88d6cf981d05612428fed14e265817749927fc1bd68fda64b705a7d4782eeeebdfd92bcf04cfb0c3402c0c25675828ba2754fa8105ae5e5f9cc604c5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d41af5b089cdccaab28db41a61598f579d343e61820556e25bb82ce80a387182014183761ab1f876f380e11edc2a977b21922e32b9117c3e4acc931de47c948", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/734ad3c804f8ab60c69d33c9b6fffc86d3dcf6276e96ae1b2c4365074a32bab52a0729d40844c2338a2ee81e5a4dc5f6d29c14ccd6146b33494f621fb7ef90eb"} 19:18:07 INFO - Running post-run listener: copy_logs_to_upload_dir 19:18:07 INFO - Copying logs to upload dir... 19:18:07 INFO - mkdir: /builds/slave/test/build/upload/logs 19:18:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.894511 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-08-10 19:18:07.083485) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-10 19:18:07.091401) ========= 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=0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839 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/sha512/a16b0971350d60f295bed0fc1a8606751a70622cf49101b5ff4845d458197439f032768b46d9891c24c3e74eb3a639b5263514eb47dad111530811e36103ab3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3069070e88d6cf981d05612428fed14e265817749927fc1bd68fda64b705a7d4782eeeebdfd92bcf04cfb0c3402c0c25675828ba2754fa8105ae5e5f9cc604c5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d41af5b089cdccaab28db41a61598f579d343e61820556e25bb82ce80a387182014183761ab1f876f380e11edc2a977b21922e32b9117c3e4acc931de47c948", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/734ad3c804f8ab60c69d33c9b6fffc86d3dcf6276e96ae1b2c4365074a32bab52a0729d40844c2338a2ee81e5a4dc5f6d29c14ccd6146b33494f621fb7ef90eb"} build_url:https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011286 build_url: 'https://queue.taskcluster.net/v1/task/cNUPDWBqR-u0RSojCR1X9w/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a16b0971350d60f295bed0fc1a8606751a70622cf49101b5ff4845d458197439f032768b46d9891c24c3e74eb3a639b5263514eb47dad111530811e36103ab3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3069070e88d6cf981d05612428fed14e265817749927fc1bd68fda64b705a7d4782eeeebdfd92bcf04cfb0c3402c0c25675828ba2754fa8105ae5e5f9cc604c5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d41af5b089cdccaab28db41a61598f579d343e61820556e25bb82ce80a387182014183761ab1f876f380e11edc2a977b21922e32b9117c3e4acc931de47c948", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/734ad3c804f8ab60c69d33c9b6fffc86d3dcf6276e96ae1b2c4365074a32bab52a0729d40844c2338a2ee81e5a4dc5f6d29c14ccd6146b33494f621fb7ef90eb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-10 19:18:07.119062) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 19:18:07.119404) ========= 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=0e2a256762fed810e2f0f810000001b3-1502416283.759157-2057263839 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-10 19:18:07.199929) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-10 19:18:07.202282) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-10 19:18:07.202578) ========= ========= Total master_lag: 0.15 =========